{"level":"warn","ts":"2026-09-07T10:47:11.405713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-07T10:47:11.405924Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.118:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.118:2380","--initial-cluster=instance=https://162.253.55.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.118: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-09-07T10:47:11.406084Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-07T10:47:11.406121Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.118:2380"]}
{"level":"info","ts":"2026-09-07T10:47:11.406176Z","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-09-07T10:47:11.407791Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"]}
{"level":"info","ts":"2026-09-07T10:47:11.407988Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.118: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-09-07T10:47:11.414343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.795954ms"}
{"level":"info","ts":"2026-09-07T10:47:11.426915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39c975536cfc1086","cluster-id":"ac0a95643fc422f0"}
{"level":"info","ts":"2026-09-07T10:47:11.427117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=()"}
{"level":"info","ts":"2026-09-07T10:47:11.427173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 0"}
{"level":"info","ts":"2026-09-07T10:47:11.427212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39c975536cfc1086 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-07T10:47:11.427246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 1"}
{"level":"info","ts":"2026-09-07T10:47:11.427344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"}
{"level":"warn","ts":"2026-09-07T10:47:11.436341Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-07T10:47:11.438634Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-07T10:47:11.440429Z","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-09-07T10:47:11.443654Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39c975536cfc1086","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-07T10:47:11.443938Z","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-09-07T10:47:11.443957Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39c975536cfc1086","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-07T10:47:11.444091Z","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-09-07T10:47:11.444144Z","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-09-07T10:47:11.446216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"}
{"level":"info","ts":"2026-09-07T10:47:11.447137Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","added-peer-id":"39c975536cfc1086","added-peer-peer-urls":["https://162.253.55.118:2380"]}
{"level":"info","ts":"2026-09-07T10:47:11.449191Z","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-09-07T10:47:11.449297Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.118:2380"}
{"level":"info","ts":"2026-09-07T10:47:11.449365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.118:2380"}
{"level":"info","ts":"2026-09-07T10:47:11.449756Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39c975536cfc1086","initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-07T10:47:11.449828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-07T10:47:11.928322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 is starting a new election at term 1"}
{"level":"info","ts":"2026-09-07T10:47:11.928416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-07T10:47:11.928482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgPreVoteResp from 39c975536cfc1086 at term 1"}
{"level":"info","ts":"2026-09-07T10:47:11.928623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became candidate at term 2"}
{"level":"info","ts":"2026-09-07T10:47:11.928675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgVoteResp from 39c975536cfc1086 at term 2"}
{"level":"info","ts":"2026-09-07T10:47:11.928724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became leader at term 2"}
{"level":"info","ts":"2026-09-07T10:47:11.928748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39c975536cfc1086 elected leader 39c975536cfc1086 at term 2"}
{"level":"info","ts":"2026-09-07T10:47:11.934191Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39c975536cfc1086","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.118:2379]}","request-path":"/0/members/39c975536cfc1086/attributes","cluster-id":"ac0a95643fc422f0","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-07T10:47:11.934477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-07T10:47:11.935135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-07T10:47:11.935549Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-07T10:47:11.93558Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-07T10:47:11.933953Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T10:47:11.93757Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T10:47:11.93777Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T10:47:11.937826Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T10:47:11.938253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-07T10:47:11.94034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.118:2379"}
{"level":"info","ts":"2026-09-07T10:47:30.639425Z","caller":"traceutil/trace.go:171","msg":"trace[320436891] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"146.435691ms","start":"2026-09-07T10:47:30.492949Z","end":"2026-09-07T10:47:30.639385Z","steps":["trace[320436891] 'read index received'  (duration: 52.580801ms)","trace[320436891] 'applied index is now lower than readState.Index'  (duration: 93.85326ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:47:30.639628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.654467ms","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-09-07T10:47:30.640493Z","caller":"traceutil/trace.go:171","msg":"trace[125519809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"147.58304ms","start":"2026-09-07T10:47:30.492871Z","end":"2026-09-07T10:47:30.640454Z","steps":["trace[125519809] 'agreement among raft nodes before linearized reading'  (duration: 146.640516ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:47:36.096216Z","caller":"traceutil/trace.go:171","msg":"trace[118492725] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"107.244912ms","start":"2026-09-07T10:47:35.988924Z","end":"2026-09-07T10:47:36.096169Z","steps":["trace[118492725] 'process raft request'  (duration: 107.029537ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:47:36.237042Z","caller":"traceutil/trace.go:171","msg":"trace[1960365619] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"135.353888ms","start":"2026-09-07T10:47:36.101641Z","end":"2026-09-07T10:47:36.236994Z","steps":["trace[1960365619] 'process raft request'  (duration: 66.180558ms)","trace[1960365619] 'compare'  (duration: 69.025027ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:47:56.331168Z","caller":"traceutil/trace.go:171","msg":"trace[154204149] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"157.955238ms","start":"2026-09-07T10:47:56.173176Z","end":"2026-09-07T10:47:56.331131Z","steps":["trace[154204149] 'process raft request'  (duration: 153.203526ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:47:56.331394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.515828ms","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-09-07T10:47:56.331247Z","caller":"traceutil/trace.go:171","msg":"trace[643363528] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"123.259462ms","start":"2026-09-07T10:47:56.20785Z","end":"2026-09-07T10:47:56.331109Z","steps":["trace[643363528] 'read index received'  (duration: 117.794072ms)","trace[643363528] 'applied index is now lower than readState.Index'  (duration: 5.46226ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:47:56.331544Z","caller":"traceutil/trace.go:171","msg":"trace[1990482982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"123.704612ms","start":"2026-09-07T10:47:56.207815Z","end":"2026-09-07T10:47:56.331519Z","steps":["trace[1990482982] 'agreement among raft nodes before linearized reading'  (duration: 123.370155ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:47:56.563259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.396377ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685410945 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:614 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:47:56.5634Z","caller":"traceutil/trace.go:171","msg":"trace[914231825] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"227.719727ms","start":"2026-09-07T10:47:56.335644Z","end":"2026-09-07T10:47:56.563364Z","steps":["trace[914231825] 'process raft request'  (duration: 26.630824ms)","trace[914231825] 'compare'  (duration: 200.242793ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:47:56.563459Z","caller":"traceutil/trace.go:171","msg":"trace[985242118] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"160.57426ms","start":"2026-09-07T10:47:56.402866Z","end":"2026-09-07T10:47:56.56344Z","steps":["trace[985242118] 'process raft request'  (duration: 160.506958ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:47:56.563494Z","caller":"traceutil/trace.go:171","msg":"trace[898542442] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"163.829047ms","start":"2026-09-07T10:47:56.399608Z","end":"2026-09-07T10:47:56.563437Z","steps":["trace[898542442] 'read index received'  (duration: 47.321µs)","trace[898542442] 'applied index is now lower than readState.Index'  (duration: 163.778016ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:47:56.563718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.095614ms","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-09-07T10:47:56.563756Z","caller":"traceutil/trace.go:171","msg":"trace[456763799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"164.180186ms","start":"2026-09-07T10:47:56.399564Z","end":"2026-09-07T10:47:56.563745Z","steps":["trace[456763799] 'agreement among raft nodes before linearized reading'  (duration: 164.001982ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:47:57.029634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.657023ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685410951 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/cilium-ws2fz\" mod_revision:520 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-ws2fz\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-ws2fz\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:47:57.029787Z","caller":"traceutil/trace.go:171","msg":"trace[844604652] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"201.665437ms","start":"2026-09-07T10:47:56.828101Z","end":"2026-09-07T10:47:57.029767Z","steps":["trace[844604652] 'read index received'  (duration: 42.951µs)","trace[844604652] 'applied index is now lower than readState.Index'  (duration: 201.620776ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:47:57.029875Z","caller":"traceutil/trace.go:171","msg":"trace[867314415] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"408.929237ms","start":"2026-09-07T10:47:56.62093Z","end":"2026-09-07T10:47:57.029859Z","steps":["trace[867314415] 'process raft request'  (duration: 75.938376ms)","trace[867314415] 'compare'  (duration: 332.490019ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:47:57.029983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:47:56.620911Z","time spent":"409.02512ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-ws2fz\" mod_revision:520 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-ws2fz\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-ws2fz\" > >"}
{"level":"warn","ts":"2026-09-07T10:47:57.030061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.927753ms","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-09-07T10:47:57.030128Z","caller":"traceutil/trace.go:171","msg":"trace[2014364900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"202.064836ms","start":"2026-09-07T10:47:56.828048Z","end":"2026-09-07T10:47:57.030113Z","steps":["trace[2014364900] 'agreement among raft nodes before linearized reading'  (duration: 201.873322ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:47:57.239611Z","caller":"traceutil/trace.go:171","msg":"trace[1593283998] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"209.773571ms","start":"2026-09-07T10:47:57.02982Z","end":"2026-09-07T10:47:57.239594Z","steps":["trace[1593283998] 'read index received'  (duration: 209.34431ms)","trace[1593283998] 'applied index is now lower than readState.Index'  (duration: 428.571µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:47:57.239656Z","caller":"traceutil/trace.go:171","msg":"trace[452834864] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"226.434816ms","start":"2026-09-07T10:47:57.013202Z","end":"2026-09-07T10:47:57.239636Z","steps":["trace[452834864] 'process raft request'  (duration: 226.039166ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:47:57.239783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.974465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-07T10:47:57.23982Z","caller":"traceutil/trace.go:171","msg":"trace[1736843466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"411.045558ms","start":"2026-09-07T10:47:56.828767Z","end":"2026-09-07T10:47:57.239813Z","steps":["trace[1736843466] 'agreement among raft nodes before linearized reading'  (duration: 410.902204ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:47:57.239838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:47:56.828758Z","time spent":"411.076028ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-09-07T10:47:57.247429Z","caller":"traceutil/trace.go:171","msg":"trace[9467902] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"213.98918ms","start":"2026-09-07T10:47:57.033425Z","end":"2026-09-07T10:47:57.247414Z","steps":["trace[9467902] 'process raft request'  (duration: 213.911748ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:49:31.871705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.949018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:49:31.871858Z","caller":"traceutil/trace.go:171","msg":"trace[68922385] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1880; }","duration":"173.200155ms","start":"2026-09-07T10:49:31.698624Z","end":"2026-09-07T10:49:31.871824Z","steps":["trace[68922385] 'count revisions from in-memory index tree'  (duration: 172.828065ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:49:32.364257Z","caller":"traceutil/trace.go:171","msg":"trace[1254524643] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"197.668437ms","start":"2026-09-07T10:49:32.166544Z","end":"2026-09-07T10:49:32.364212Z","steps":["trace[1254524643] 'process raft request'  (duration: 197.3843ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:49:47.098427Z","caller":"traceutil/trace.go:171","msg":"trace[2074891722] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2085; }","duration":"220.792686ms","start":"2026-09-07T10:49:46.87758Z","end":"2026-09-07T10:49:47.098373Z","steps":["trace[2074891722] 'read index received'  (duration: 220.344545ms)","trace[2074891722] 'applied index is now lower than readState.Index'  (duration: 445.551µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:49:47.098468Z","caller":"traceutil/trace.go:171","msg":"trace[1877323858] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"331.619755ms","start":"2026-09-07T10:49:46.766804Z","end":"2026-09-07T10:49:47.098424Z","steps":["trace[1877323858] 'process raft request'  (duration: 331.289087ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:49:47.09873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:46.766779Z","time spent":"331.82126ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:1987 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-07T10:49:47.098796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.170645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-07T10:49:47.098862Z","caller":"traceutil/trace.go:171","msg":"trace[567902073] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2011; }","duration":"221.358701ms","start":"2026-09-07T10:49:46.877476Z","end":"2026-09-07T10:49:47.098834Z","steps":["trace[567902073] 'agreement among raft nodes before linearized reading'  (duration: 221.091463ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:50:17.375578Z","caller":"traceutil/trace.go:171","msg":"trace[1859085643] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"195.610651ms","start":"2026-09-07T10:50:17.179937Z","end":"2026-09-07T10:50:17.375548Z","steps":["trace[1859085643] 'process raft request'  (duration: 195.401646ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:50:17.383925Z","caller":"traceutil/trace.go:171","msg":"trace[1340026621] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"150.152776ms","start":"2026-09-07T10:50:17.233754Z","end":"2026-09-07T10:50:17.383907Z","steps":["trace[1340026621] 'process raft request'  (duration: 149.92914ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:50:17.712895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.265326ms","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-09-07T10:50:17.713279Z","caller":"traceutil/trace.go:171","msg":"trace[663943110] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"138.694067ms","start":"2026-09-07T10:50:17.574487Z","end":"2026-09-07T10:50:17.713181Z","steps":["trace[663943110] 'range keys from in-memory index tree'  (duration: 137.897837ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:50:23.36199Z","caller":"traceutil/trace.go:171","msg":"trace[1526310710] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"167.877265ms","start":"2026-09-07T10:50:23.194084Z","end":"2026-09-07T10:50:23.361962Z","steps":["trace[1526310710] 'process raft request'  (duration: 167.728342ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:50:23.604751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.916736ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685414180 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2206 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:50:23.604865Z","caller":"traceutil/trace.go:171","msg":"trace[282950955] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"199.124598ms","start":"2026-09-07T10:50:23.405717Z","end":"2026-09-07T10:50:23.604842Z","steps":["trace[282950955] 'process raft request'  (duration: 52.026457ms)","trace[282950955] 'compare'  (duration: 146.510446ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:50:23.610696Z","caller":"traceutil/trace.go:171","msg":"trace[2013364846] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"121.448366ms","start":"2026-09-07T10:50:23.489229Z","end":"2026-09-07T10:50:23.610678Z","steps":["trace[2013364846] 'read index received'  (duration: 121.213751ms)","trace[2013364846] 'applied index is now lower than readState.Index'  (duration: 232.835µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:50:23.610831Z","caller":"traceutil/trace.go:171","msg":"trace[1050961712] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"203.247298ms","start":"2026-09-07T10:50:23.40757Z","end":"2026-09-07T10:50:23.610818Z","steps":["trace[1050961712] 'process raft request'  (duration: 202.876769ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:50:23.611034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.809254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-07T10:50:23.611073Z","caller":"traceutil/trace.go:171","msg":"trace[258332486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2216; }","duration":"121.878226ms","start":"2026-09-07T10:50:23.489179Z","end":"2026-09-07T10:50:23.611057Z","steps":["trace[258332486] 'agreement among raft nodes before linearized reading'  (duration: 121.756423ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:50:23.611356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.078224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-07T10:50:23.611488Z","caller":"traceutil/trace.go:171","msg":"trace[1239306182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2216; }","duration":"104.250318ms","start":"2026-09-07T10:50:23.507202Z","end":"2026-09-07T10:50:23.611452Z","steps":["trace[1239306182] 'agreement among raft nodes before linearized reading'  (duration: 103.909109ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:50:23.744897Z","caller":"traceutil/trace.go:171","msg":"trace[264054558] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"127.651007ms","start":"2026-09-07T10:50:23.617206Z","end":"2026-09-07T10:50:23.744857Z","steps":["trace[264054558] 'process raft request'  (duration: 127.548984ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:50:23.744935Z","caller":"traceutil/trace.go:171","msg":"trace[1571490680] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"128.009857ms","start":"2026-09-07T10:50:23.616889Z","end":"2026-09-07T10:50:23.744899Z","steps":["trace[1571490680] 'process raft request'  (duration: 127.620927ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:50:23.745156Z","caller":"traceutil/trace.go:171","msg":"trace[161900265] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"127.278288ms","start":"2026-09-07T10:50:23.617837Z","end":"2026-09-07T10:50:23.745115Z","steps":["trace[161900265] 'process raft request'  (duration: 126.969121ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:50:57.313822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.317766ms","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:5"}
{"level":"info","ts":"2026-09-07T10:50:57.313953Z","caller":"traceutil/trace.go:171","msg":"trace[527280767] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2412; }","duration":"102.527362ms","start":"2026-09-07T10:50:57.211392Z","end":"2026-09-07T10:50:57.31392Z","steps":["trace[527280767] 'agreement among raft nodes before linearized reading'  (duration: 39.293302ms)","trace[527280767] 'count revisions from in-memory index tree'  (duration: 63.030484ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:01.21042Z","caller":"traceutil/trace.go:171","msg":"trace[1632429814] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"112.499996ms","start":"2026-09-07T10:51:01.09785Z","end":"2026-09-07T10:51:01.21035Z","steps":["trace[1632429814] 'process raft request'  (duration: 102.910651ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:01.210435Z","caller":"traceutil/trace.go:171","msg":"trace[1433080537] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2470; }","duration":"109.436601ms","start":"2026-09-07T10:51:01.100965Z","end":"2026-09-07T10:51:01.210401Z","steps":["trace[1433080537] 'process raft request'  (duration: 109.293078ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:01.518156Z","caller":"traceutil/trace.go:171","msg":"trace[527645603] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"173.841039ms","start":"2026-09-07T10:51:01.34428Z","end":"2026-09-07T10:51:01.518121Z","steps":["trace[527645603] 'process raft request'  (duration: 131.254015ms)","trace[527645603] 'compare'  (duration: 42.40562ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:01.526111Z","caller":"traceutil/trace.go:171","msg":"trace[893204925] linearizableReadLoop","detail":"{readStateIndex:2577; appliedIndex:2575; }","duration":"176.226447ms","start":"2026-09-07T10:51:01.349866Z","end":"2026-09-07T10:51:01.526092Z","steps":["trace[893204925] 'read index received'  (duration: 125.648938ms)","trace[893204925] 'applied index is now lower than readState.Index'  (duration: 50.576219ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:01.526218Z","caller":"traceutil/trace.go:171","msg":"trace[1965242337] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"169.232956ms","start":"2026-09-07T10:51:01.356946Z","end":"2026-09-07T10:51:01.526179Z","steps":["trace[1965242337] 'process raft request'  (duration: 169.055152ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:01.526282Z","caller":"traceutil/trace.go:171","msg":"trace[803592376] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"180.614175ms","start":"2026-09-07T10:51:01.345607Z","end":"2026-09-07T10:51:01.526221Z","steps":["trace[803592376] 'process raft request'  (duration: 180.306478ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:01.526376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.498435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/valkey-ca\" ","response":"range_response_count:1 size:2410"}
{"level":"info","ts":"2026-09-07T10:51:01.526419Z","caller":"traceutil/trace.go:171","msg":"trace[94671772] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/valkey-ca; range_end:; response_count:1; response_revision:2484; }","duration":"176.659799ms","start":"2026-09-07T10:51:01.349747Z","end":"2026-09-07T10:51:01.526407Z","steps":["trace[94671772] 'agreement among raft nodes before linearized reading'  (duration: 176.448073ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:02.643917Z","caller":"traceutil/trace.go:171","msg":"trace[196569134] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"162.418809ms","start":"2026-09-07T10:51:02.481466Z","end":"2026-09-07T10:51:02.643885Z","steps":["trace[196569134] 'process raft request'  (duration: 93.564372ms)","trace[196569134] 'compare'  (duration: 68.59593ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:02.644031Z","caller":"traceutil/trace.go:171","msg":"trace[162821147] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"162.047701ms","start":"2026-09-07T10:51:02.481948Z","end":"2026-09-07T10:51:02.643996Z","steps":["trace[162821147] 'process raft request'  (duration: 161.806525ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:02.644036Z","caller":"traceutil/trace.go:171","msg":"trace[111279220] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2592; }","duration":"146.802897ms","start":"2026-09-07T10:51:02.497192Z","end":"2026-09-07T10:51:02.643995Z","steps":["trace[111279220] 'read index received'  (duration: 77.861147ms)","trace[111279220] 'applied index is now lower than readState.Index'  (duration: 68.937879ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:02.644462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.445234ms","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-09-07T10:51:02.644534Z","caller":"traceutil/trace.go:171","msg":"trace[1459255512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2499; }","duration":"123.577118ms","start":"2026-09-07T10:51:02.520933Z","end":"2026-09-07T10:51:02.644511Z","steps":["trace[1459255512] 'agreement among raft nodes before linearized reading'  (duration: 123.311791ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:02.6445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.272367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-07T10:51:02.644655Z","caller":"traceutil/trace.go:171","msg":"trace[1950452612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2499; }","duration":"147.500733ms","start":"2026-09-07T10:51:02.497127Z","end":"2026-09-07T10:51:02.644627Z","steps":["trace[1950452612] 'agreement among raft nodes before linearized reading'  (duration: 147.031372ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:02.858321Z","caller":"traceutil/trace.go:171","msg":"trace[1812315773] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"122.138953ms","start":"2026-09-07T10:51:02.736138Z","end":"2026-09-07T10:51:02.858277Z","steps":["trace[1812315773] 'process raft request'  (duration: 44.065831ms)","trace[1812315773] 'compare'  (duration: 77.904468ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:02.888193Z","caller":"traceutil/trace.go:171","msg":"trace[682050683] linearizableReadLoop","detail":"{readStateIndex:2602; appliedIndex:2600; }","duration":"129.317528ms","start":"2026-09-07T10:51:02.75882Z","end":"2026-09-07T10:51:02.888137Z","steps":["trace[682050683] 'read index received'  (duration: 21.450175ms)","trace[682050683] 'applied index is now lower than readState.Index'  (duration: 107.866003ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:02.888206Z","caller":"traceutil/trace.go:171","msg":"trace[363004497] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"149.78624ms","start":"2026-09-07T10:51:02.738396Z","end":"2026-09-07T10:51:02.888182Z","steps":["trace[363004497] 'process raft request'  (duration: 149.585865ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:02.888398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.570534ms","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-09-07T10:51:02.88844Z","caller":"traceutil/trace.go:171","msg":"trace[1982622939] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2507; }","duration":"129.680988ms","start":"2026-09-07T10:51:02.758746Z","end":"2026-09-07T10:51:02.888427Z","steps":["trace[1982622939] 'agreement among raft nodes before linearized reading'  (duration: 129.493302ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:03.054329Z","caller":"traceutil/trace.go:171","msg":"trace[1672096656] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"119.385355ms","start":"2026-09-07T10:51:02.934917Z","end":"2026-09-07T10:51:03.054302Z","steps":["trace[1672096656] 'process raft request'  (duration: 98.099813ms)","trace[1672096656] 'compare'  (duration: 21.086707ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:03.054518Z","caller":"traceutil/trace.go:171","msg":"trace[2012436613] linearizableReadLoop","detail":"{readStateIndex:2606; appliedIndex:2605; }","duration":"105.858753ms","start":"2026-09-07T10:51:02.94863Z","end":"2026-09-07T10:51:03.054488Z","steps":["trace[2012436613] 'read index received'  (duration: 84.346706ms)","trace[2012436613] 'applied index is now lower than readState.Index'  (duration: 21.508967ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:03.054568Z","caller":"traceutil/trace.go:171","msg":"trace[1218706048] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"104.401008ms","start":"2026-09-07T10:51:02.950123Z","end":"2026-09-07T10:51:03.054524Z","steps":["trace[1218706048] 'process raft request'  (duration: 104.189982ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:03.054809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.147911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:8598"}
{"level":"info","ts":"2026-09-07T10:51:03.054859Z","caller":"traceutil/trace.go:171","msg":"trace[1249205708] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2513; }","duration":"106.244524ms","start":"2026-09-07T10:51:02.948602Z","end":"2026-09-07T10:51:03.054847Z","steps":["trace[1249205708] 'agreement among raft nodes before linearized reading'  (duration: 106.027389ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:03.054444Z","caller":"traceutil/trace.go:171","msg":"trace[1216533006] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"103.318921ms","start":"2026-09-07T10:51:02.951096Z","end":"2026-09-07T10:51:03.054415Z","steps":["trace[1216533006] 'process raft request'  (duration: 103.269569ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:03.245648Z","caller":"traceutil/trace.go:171","msg":"trace[1059003430] linearizableReadLoop","detail":"{readStateIndex:2613; appliedIndex:2612; }","duration":"105.758401ms","start":"2026-09-07T10:51:03.139857Z","end":"2026-09-07T10:51:03.245615Z","steps":["trace[1059003430] 'read index received'  (duration: 54.765923ms)","trace[1059003430] 'applied index is now lower than readState.Index'  (duration: 50.990058ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:03.245698Z","caller":"traceutil/trace.go:171","msg":"trace[810189204] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"106.439348ms","start":"2026-09-07T10:51:03.139217Z","end":"2026-09-07T10:51:03.245656Z","steps":["trace[810189204] 'process raft request'  (duration: 55.47552ms)","trace[810189204] 'compare'  (duration: 50.59441ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:03.245921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.048168ms","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-09-07T10:51:03.245974Z","caller":"traceutil/trace.go:171","msg":"trace[616053883] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2518; }","duration":"106.13513ms","start":"2026-09-07T10:51:03.139824Z","end":"2026-09-07T10:51:03.245959Z","steps":["trace[616053883] 'agreement among raft nodes before linearized reading'  (duration: 105.904944ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:03.413564Z","caller":"traceutil/trace.go:171","msg":"trace[1714194464] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"125.497725ms","start":"2026-09-07T10:51:03.287995Z","end":"2026-09-07T10:51:03.413493Z","steps":["trace[1714194464] 'process raft request'  (duration: 97.599221ms)","trace[1714194464] 'compare'  (duration: 27.666418ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:14.364885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.518296ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685415153 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2647 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:457 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:51:14.364996Z","caller":"traceutil/trace.go:171","msg":"trace[1207982152] linearizableReadLoop","detail":"{readStateIndex:2760; appliedIndex:2759; }","duration":"234.27356ms","start":"2026-09-07T10:51:14.130701Z","end":"2026-09-07T10:51:14.364975Z","steps":["trace[1207982152] 'read index received'  (duration: 30.581849ms)","trace[1207982152] 'applied index is now lower than readState.Index'  (duration: 203.689861ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:14.365018Z","caller":"traceutil/trace.go:171","msg":"trace[85050598] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"272.130878ms","start":"2026-09-07T10:51:14.092857Z","end":"2026-09-07T10:51:14.364988Z","steps":["trace[85050598] 'process raft request'  (duration: 68.422256ms)","trace[85050598] 'compare'  (duration: 203.331232ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:14.365242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.545527ms","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-09-07T10:51:14.36527Z","caller":"traceutil/trace.go:171","msg":"trace[154856990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2661; }","duration":"234.621989ms","start":"2026-09-07T10:51:14.130639Z","end":"2026-09-07T10:51:14.365261Z","steps":["trace[154856990] 'agreement among raft nodes before linearized reading'  (duration: 234.431414ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:14.365417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.710805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-07T10:51:14.365516Z","caller":"traceutil/trace.go:171","msg":"trace[629909063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2661; }","duration":"197.842757ms","start":"2026-09-07T10:51:14.16765Z","end":"2026-09-07T10:51:14.365493Z","steps":["trace[629909063] 'agreement among raft nodes before linearized reading'  (duration: 197.593841ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:14.365735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.618043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-07T10:51:14.365814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.468678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-07T10:51:14.365877Z","caller":"traceutil/trace.go:171","msg":"trace[790529214] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2661; }","duration":"186.55082ms","start":"2026-09-07T10:51:14.179303Z","end":"2026-09-07T10:51:14.365854Z","steps":["trace[790529214] 'agreement among raft nodes before linearized reading'  (duration: 186.236442ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:14.365878Z","caller":"traceutil/trace.go:171","msg":"trace[1132779381] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2661; }","duration":"146.878889ms","start":"2026-09-07T10:51:14.218972Z","end":"2026-09-07T10:51:14.36585Z","steps":["trace[1132779381] 'agreement among raft nodes before linearized reading'  (duration: 146.560761ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:32.710434Z","caller":"traceutil/trace.go:171","msg":"trace[727128439] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"168.074298ms","start":"2026-09-07T10:51:32.542323Z","end":"2026-09-07T10:51:32.710397Z","steps":["trace[727128439] 'process raft request'  (duration: 167.949815ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:32.710612Z","caller":"traceutil/trace.go:171","msg":"trace[166084130] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"168.578629ms","start":"2026-09-07T10:51:32.541956Z","end":"2026-09-07T10:51:32.710535Z","steps":["trace[166084130] 'process raft request'  (duration: 167.461642ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:32.710717Z","caller":"traceutil/trace.go:171","msg":"trace[1971739616] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"168.186301ms","start":"2026-09-07T10:51:32.542503Z","end":"2026-09-07T10:51:32.710689Z","steps":["trace[1971739616] 'process raft request'  (duration: 167.831453ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.041792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.341694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"}
{"level":"warn","ts":"2026-09-07T10:51:43.04188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.795456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-07T10:51:43.041927Z","caller":"traceutil/trace.go:171","msg":"trace[1953563909] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2836; }","duration":"139.547328ms","start":"2026-09-07T10:51:42.902342Z","end":"2026-09-07T10:51:43.041889Z","steps":["trace[1953563909] 'range keys from in-memory index tree'  (duration: 139.053987ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:43.042001Z","caller":"traceutil/trace.go:171","msg":"trace[1293693777] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2836; }","duration":"148.925458ms","start":"2026-09-07T10:51:42.893022Z","end":"2026-09-07T10:51:43.041947Z","steps":["trace[1293693777] 'range keys from in-memory index tree'  (duration: 148.57079ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.042025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.665605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-07T10:51:43.042145Z","caller":"traceutil/trace.go:171","msg":"trace[738917609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2836; }","duration":"111.81154ms","start":"2026-09-07T10:51:42.9303Z","end":"2026-09-07T10:51:43.042112Z","steps":["trace[738917609] 'range keys from in-memory index tree'  (duration: 111.44351ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.042111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.472755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-07T10:51:43.042431Z","caller":"traceutil/trace.go:171","msg":"trace[426878753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2836; }","duration":"125.890424ms","start":"2026-09-07T10:51:42.916512Z","end":"2026-09-07T10:51:43.042402Z","steps":["trace[426878753] 'range keys from in-memory index tree'  (duration: 125.193427ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:43.163002Z","caller":"traceutil/trace.go:171","msg":"trace[658003972] linearizableReadLoop","detail":"{readStateIndex:2943; appliedIndex:2942; }","duration":"116.276418ms","start":"2026-09-07T10:51:43.046703Z","end":"2026-09-07T10:51:43.162979Z","steps":["trace[658003972] 'read index received'  (duration: 116.176756ms)","trace[658003972] 'applied index is now lower than readState.Index'  (duration: 99.032µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:43.163129Z","caller":"traceutil/trace.go:171","msg":"trace[1048698596] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"123.786522ms","start":"2026-09-07T10:51:43.039313Z","end":"2026-09-07T10:51:43.1631Z","steps":["trace[1048698596] 'process raft request'  (duration: 123.483135ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.163245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.526105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"}
{"level":"info","ts":"2026-09-07T10:51:43.163287Z","caller":"traceutil/trace.go:171","msg":"trace[1483412755] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2837; }","duration":"116.620467ms","start":"2026-09-07T10:51:43.046654Z","end":"2026-09-07T10:51:43.163275Z","steps":["trace[1483412755] 'agreement among raft nodes before linearized reading'  (duration: 116.446653ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:43.408762Z","caller":"traceutil/trace.go:171","msg":"trace[1277225449] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"361.439445ms","start":"2026-09-07T10:51:43.047283Z","end":"2026-09-07T10:51:43.408722Z","steps":["trace[1277225449] 'process raft request'  (duration: 361.266041ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:43.408784Z","caller":"traceutil/trace.go:171","msg":"trace[970661107] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"361.519768ms","start":"2026-09-07T10:51:43.047195Z","end":"2026-09-07T10:51:43.408715Z","steps":["trace[970661107] 'process raft request'  (duration: 302.990405ms)","trace[970661107] 'compare'  (duration: 57.923379ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:43.40893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:43.047275Z","time spent":"361.563029ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2827 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-07T10:51:43.409038Z","caller":"traceutil/trace.go:171","msg":"trace[559437339] linearizableReadLoop","detail":"{readStateIndex:2946; appliedIndex:2943; }","duration":"245.938876ms","start":"2026-09-07T10:51:43.163074Z","end":"2026-09-07T10:51:43.409013Z","steps":["trace[559437339] 'read index received'  (duration: 187.130345ms)","trace[559437339] 'applied index is now lower than readState.Index'  (duration: 58.807051ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:43.409055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:43.04718Z","time spent":"361.715862ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:2828 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-07T10:51:43.409147Z","caller":"traceutil/trace.go:171","msg":"trace[1529708565] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"360.81575ms","start":"2026-09-07T10:51:43.048303Z","end":"2026-09-07T10:51:43.409119Z","steps":["trace[1529708565] 'process raft request'  (duration: 360.374779ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.409246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:43.048291Z","time spent":"360.906173ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2829 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-09-07T10:51:43.409357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.51286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-07T10:51:43.409423Z","caller":"traceutil/trace.go:171","msg":"trace[1229755209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2840; }","duration":"266.606233ms","start":"2026-09-07T10:51:43.142796Z","end":"2026-09-07T10:51:43.409402Z","steps":["trace[1229755209] 'agreement among raft nodes before linearized reading'  (duration: 266.375568ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.409419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.199383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"}
{"level":"info","ts":"2026-09-07T10:51:43.40949Z","caller":"traceutil/trace.go:171","msg":"trace[1770346023] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2840; }","duration":"242.268005ms","start":"2026-09-07T10:51:43.167188Z","end":"2026-09-07T10:51:43.409456Z","steps":["trace[1770346023] 'agreement among raft nodes before linearized reading'  (duration: 242.083491ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:43.409417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.62457ms","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-09-07T10:51:43.409637Z","caller":"traceutil/trace.go:171","msg":"trace[1105415028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2840; }","duration":"290.836935ms","start":"2026-09-07T10:51:43.11876Z","end":"2026-09-07T10:51:43.409597Z","steps":["trace[1105415028] 'agreement among raft nodes before linearized reading'  (duration: 290.571699ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:44.468251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.729164ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:51:44.468482Z","caller":"traceutil/trace.go:171","msg":"trace[1371119879] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2841; }","duration":"420.005611ms","start":"2026-09-07T10:51:44.04844Z","end":"2026-09-07T10:51:44.468446Z","steps":["trace[1371119879] 'range keys from in-memory index tree'  (duration: 419.691912ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:44.469747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.949986ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685415629 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2830 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:51:44.469858Z","caller":"traceutil/trace.go:171","msg":"trace[1225300691] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"497.5368ms","start":"2026-09-07T10:51:43.972297Z","end":"2026-09-07T10:51:44.469834Z","steps":["trace[1225300691] 'read index received'  (duration: 51.911µs)","trace[1225300691] 'applied index is now lower than readState.Index'  (duration: 497.482809ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:44.469895Z","caller":"traceutil/trace.go:171","msg":"trace[837504982] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"1.054197199s","start":"2026-09-07T10:51:43.415659Z","end":"2026-09-07T10:51:44.469856Z","steps":["trace[837504982] 'process raft request'  (duration: 147.951225ms)","trace[837504982] 'compare'  (duration: 905.578907ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:44.469978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.656522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:51:44.470074Z","caller":"traceutil/trace.go:171","msg":"trace[1157262284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2842; }","duration":"497.823786ms","start":"2026-09-07T10:51:43.972225Z","end":"2026-09-07T10:51:44.470049Z","steps":["trace[1157262284] 'agreement among raft nodes before linearized reading'  (duration: 497.667252ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:44.470125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:43.972195Z","time spent":"497.920559ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-07T10:51:44.470146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:43.415629Z","time spent":"1.054425203s","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2830 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-07T10:51:44.474747Z","caller":"traceutil/trace.go:171","msg":"trace[86901252] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"415.08362ms","start":"2026-09-07T10:51:44.059626Z","end":"2026-09-07T10:51:44.47471Z","steps":["trace[86901252] 'process raft request'  (duration: 414.834975ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:44.474879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:44.059594Z","time spent":"415.229323ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2833 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-07T10:51:44.501385Z","caller":"traceutil/trace.go:171","msg":"trace[1514044726] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"329.059303ms","start":"2026-09-07T10:51:44.172294Z","end":"2026-09-07T10:51:44.501353Z","steps":["trace[1514044726] 'process raft request'  (duration: 328.875167ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:44.501524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:44.172241Z","time spent":"329.216836ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2834 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-07T10:51:44.501839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.269021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"warn","ts":"2026-09-07T10:51:44.501847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.374967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-07T10:51:44.50193Z","caller":"traceutil/trace.go:171","msg":"trace[895558322] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2844; }","duration":"281.413745ms","start":"2026-09-07T10:51:44.220491Z","end":"2026-09-07T10:51:44.501905Z","steps":["trace[895558322] 'agreement among raft nodes before linearized reading'  (duration: 281.016095ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:44.502014Z","caller":"traceutil/trace.go:171","msg":"trace[2144370154] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2844; }","duration":"426.609973ms","start":"2026-09-07T10:51:44.075335Z","end":"2026-09-07T10:51:44.501945Z","steps":["trace[2144370154] 'agreement among raft nodes before linearized reading'  (duration: 426.147022ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:44.502123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:44.075279Z","time spent":"426.826437ms","remote":"127.0.0.1:48034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "}
{"level":"warn","ts":"2026-09-07T10:51:44.502408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.734487ms","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-09-07T10:51:44.502532Z","caller":"traceutil/trace.go:171","msg":"trace[1864478991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2844; }","duration":"192.339522ms","start":"2026-09-07T10:51:44.310171Z","end":"2026-09-07T10:51:44.502511Z","steps":["trace[1864478991] 'agreement among raft nodes before linearized reading'  (duration: 191.207694ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:50.971506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.481024ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685415743 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2864 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:51:50.97165Z","caller":"traceutil/trace.go:171","msg":"trace[2124121143] linearizableReadLoop","detail":"{readStateIndex:2984; appliedIndex:2982; }","duration":"473.921871ms","start":"2026-09-07T10:51:50.497708Z","end":"2026-09-07T10:51:50.97163Z","steps":["trace[2124121143] 'read index received'  (duration: 10.845465ms)","trace[2124121143] 'applied index is now lower than readState.Index'  (duration: 463.074706ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:51:50.971756Z","caller":"traceutil/trace.go:171","msg":"trace[268346813] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"474.555256ms","start":"2026-09-07T10:51:50.497128Z","end":"2026-09-07T10:51:50.971683Z","steps":["trace[268346813] 'process raft request'  (duration: 288.792845ms)","trace[268346813] 'compare'  (duration: 185.239629ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:50.971851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.149646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-07T10:51:50.971909Z","caller":"traceutil/trace.go:171","msg":"trace[578280577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2877; }","duration":"474.27609ms","start":"2026-09-07T10:51:50.497623Z","end":"2026-09-07T10:51:50.971899Z","steps":["trace[578280577] 'agreement among raft nodes before linearized reading'  (duration: 474.084296ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:50.97195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.497611Z","time spent":"474.331651ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-07T10:51:50.972017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.497091Z","time spent":"474.800502ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2864 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-07T10:51:51.209593Z","caller":"traceutil/trace.go:171","msg":"trace[641335341] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"686.50078ms","start":"2026-09-07T10:51:50.52303Z","end":"2026-09-07T10:51:51.209531Z","steps":["trace[641335341] 'process raft request'  (duration: 686.166702ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:51.209866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.522992Z","time spent":"686.768386ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2866 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-07T10:51:51.306647Z","caller":"traceutil/trace.go:171","msg":"trace[745176396] linearizableReadLoop","detail":"{readStateIndex:2987; appliedIndex:2984; }","duration":"334.944967ms","start":"2026-09-07T10:51:50.971676Z","end":"2026-09-07T10:51:51.306621Z","steps":["trace[745176396] 'read index received'  (duration: 237.435828ms)","trace[745176396] 'applied index is now lower than readState.Index'  (duration: 97.507589ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:51:51.306745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.835706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:51:51.306816Z","caller":"traceutil/trace.go:171","msg":"trace[1598782068] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"329.159894ms","start":"2026-09-07T10:51:50.977626Z","end":"2026-09-07T10:51:51.306786Z","steps":["trace[1598782068] 'process raft request'  (duration: 328.894798ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:51.306865Z","caller":"traceutil/trace.go:171","msg":"trace[536314551] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2880; }","duration":"369.003871ms","start":"2026-09-07T10:51:50.937834Z","end":"2026-09-07T10:51:51.306838Z","steps":["trace[536314551] 'agreement among raft nodes before linearized reading'  (duration: 368.808516ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:51.306878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.510131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-07T10:51:51.306911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.937817Z","time spent":"369.084612ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "}
{"level":"info","ts":"2026-09-07T10:51:51.306945Z","caller":"traceutil/trace.go:171","msg":"trace[272614772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2880; }","duration":"333.635144ms","start":"2026-09-07T10:51:50.973291Z","end":"2026-09-07T10:51:51.306926Z","steps":["trace[272614772] 'agreement among raft nodes before linearized reading'  (duration: 333.517331ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:51.307049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.973268Z","time spent":"333.764537ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-07T10:51:51.307024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.848603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"}
{"level":"warn","ts":"2026-09-07T10:51:51.307071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.9776Z","time spent":"329.316858ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2865 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-07T10:51:51.307155Z","caller":"traceutil/trace.go:171","msg":"trace[881109772] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2880; }","duration":"406.058178ms","start":"2026-09-07T10:51:50.901067Z","end":"2026-09-07T10:51:51.307125Z","steps":["trace[881109772] 'agreement among raft nodes before linearized reading'  (duration: 405.626027ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:51.307215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.901037Z","time spent":"406.167561ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "}
{"level":"warn","ts":"2026-09-07T10:51:51.306962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.065521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-09-07T10:51:51.306725Z","caller":"traceutil/trace.go:171","msg":"trace[712890996] transaction","detail":"{read_only:false; response_revision:2879; number_of_response:1; }","duration":"461.652451ms","start":"2026-09-07T10:51:50.845014Z","end":"2026-09-07T10:51:51.306667Z","steps":["trace[712890996] 'process raft request'  (duration: 461.331813ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:51:51.307277Z","caller":"traceutil/trace.go:171","msg":"trace[336173685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2880; }","duration":"751.41011ms","start":"2026-09-07T10:51:50.555845Z","end":"2026-09-07T10:51:51.307255Z","steps":["trace[336173685] 'agreement among raft nodes before linearized reading'  (duration: 750.937568ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:51:51.307339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.555831Z","time spent":"751.487792ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-09-07T10:51:51.307379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.844976Z","time spent":"462.316738ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2868 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-09-07T10:51:51.306962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.955206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d3045fba30d671\" ","response":"range_response_count:1 size:781"}
{"level":"info","ts":"2026-09-07T10:51:51.307463Z","caller":"traceutil/trace.go:171","msg":"trace[2120872564] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d3045fba30d671; range_end:; response_count:1; response_revision:2880; }","duration":"115.520321ms","start":"2026-09-07T10:51:51.191931Z","end":"2026-09-07T10:51:51.307451Z","steps":["trace[2120872564] 'agreement among raft nodes before linearized reading'  (duration: 114.929576ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:54:40.106836Z","caller":"traceutil/trace.go:171","msg":"trace[1963216599] linearizableReadLoop","detail":"{readStateIndex:4051; appliedIndex:4050; }","duration":"117.494404ms","start":"2026-09-07T10:54:39.989313Z","end":"2026-09-07T10:54:40.106807Z","steps":["trace[1963216599] 'read index received'  (duration: 89.599825ms)","trace[1963216599] 'applied index is now lower than readState.Index'  (duration: 27.893249ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:54:40.106932Z","caller":"traceutil/trace.go:171","msg":"trace[355789473] transaction","detail":"{read_only:false; response_revision:3903; number_of_response:1; }","duration":"117.721509ms","start":"2026-09-07T10:54:39.989162Z","end":"2026-09-07T10:54:40.106884Z","steps":["trace[355789473] 'process raft request'  (duration: 89.735607ms)","trace[355789473] 'compare'  (duration: 27.775737ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:54:40.106995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.654738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/sshkeys.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:54:40.107056Z","caller":"traceutil/trace.go:171","msg":"trace[1939361692] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/sshkeys.secretgen.k14s.io; range_end:; response_count:0; response_revision:3903; }","duration":"117.753201ms","start":"2026-09-07T10:54:39.989285Z","end":"2026-09-07T10:54:40.107038Z","steps":["trace[1939361692] 'agreement among raft nodes before linearized reading'  (duration: 117.643768ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:54:40.107692Z","caller":"traceutil/trace.go:171","msg":"trace[1503189138] transaction","detail":"{read_only:false; response_revision:3904; number_of_response:1; }","duration":"115.561397ms","start":"2026-09-07T10:54:39.992107Z","end":"2026-09-07T10:54:40.107668Z","steps":["trace[1503189138] 'process raft request'  (duration: 115.353691ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:55:14.308044Z","caller":"traceutil/trace.go:171","msg":"trace[1950038078] transaction","detail":"{read_only:false; response_revision:4144; number_of_response:1; }","duration":"204.987237ms","start":"2026-09-07T10:55:14.103023Z","end":"2026-09-07T10:55:14.30801Z","steps":["trace[1950038078] 'process raft request'  (duration: 204.710891ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:55:14.632314Z","caller":"traceutil/trace.go:171","msg":"trace[186221296] linearizableReadLoop","detail":"{readStateIndex:4301; appliedIndex:4300; }","duration":"144.168202ms","start":"2026-09-07T10:55:14.4881Z","end":"2026-09-07T10:55:14.632268Z","steps":["trace[186221296] 'read index received'  (duration: 123.929501ms)","trace[186221296] 'applied index is now lower than readState.Index'  (duration: 20.237501ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:55:14.632346Z","caller":"traceutil/trace.go:171","msg":"trace[1316601060] transaction","detail":"{read_only:false; response_revision:4146; number_of_response:1; }","duration":"161.984143ms","start":"2026-09-07T10:55:14.470329Z","end":"2026-09-07T10:55:14.632314Z","steps":["trace[1316601060] 'process raft request'  (duration: 141.665861ms)","trace[1316601060] 'compare'  (duration: 19.927563ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:55:14.632511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.385918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:55:14.632609Z","caller":"traceutil/trace.go:171","msg":"trace[145126694] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4146; }","duration":"144.548542ms","start":"2026-09-07T10:55:14.488043Z","end":"2026-09-07T10:55:14.632591Z","steps":["trace[145126694] 'agreement among raft nodes before linearized reading'  (duration: 144.377798ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:56:40.937389Z","caller":"traceutil/trace.go:171","msg":"trace[2124729465] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"199.302292ms","start":"2026-09-07T10:56:40.738053Z","end":"2026-09-07T10:56:40.937355Z","steps":["trace[2124729465] 'process raft request'  (duration: 199.036216ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:56:40.937325Z","caller":"traceutil/trace.go:171","msg":"trace[738187545] linearizableReadLoop","detail":"{readStateIndex:5307; appliedIndex:5304; }","duration":"188.146809ms","start":"2026-09-07T10:56:40.74915Z","end":"2026-09-07T10:56:40.937297Z","steps":["trace[738187545] 'read index received'  (duration: 4.161911ms)","trace[738187545] 'applied index is now lower than readState.Index'  (duration: 183.983628ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:56:40.937625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.456467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"}
{"level":"info","ts":"2026-09-07T10:56:40.937713Z","caller":"traceutil/trace.go:171","msg":"trace[71911750] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5113; }","duration":"188.60142ms","start":"2026-09-07T10:56:40.749095Z","end":"2026-09-07T10:56:40.937697Z","steps":["trace[71911750] 'agreement among raft nodes before linearized reading'  (duration: 188.371605ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:56:40.939866Z","caller":"traceutil/trace.go:171","msg":"trace[277338621] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"181.505146ms","start":"2026-09-07T10:56:40.758323Z","end":"2026-09-07T10:56:40.939828Z","steps":["trace[277338621] 'process raft request'  (duration: 180.397849ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:56:46.221417Z","caller":"traceutil/trace.go:171","msg":"trace[1010854646] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5255; }","duration":"151.0961ms","start":"2026-09-07T10:56:46.070269Z","end":"2026-09-07T10:56:46.221366Z","steps":["trace[1010854646] 'process raft request'  (duration: 150.478606ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:46.221859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.54227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:56:46.221975Z","caller":"traceutil/trace.go:171","msg":"trace[1144346101] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5255; }","duration":"117.702263ms","start":"2026-09-07T10:56:46.104245Z","end":"2026-09-07T10:56:46.221947Z","steps":["trace[1144346101] 'agreement among raft nodes before linearized reading'  (duration: 117.107588ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:46.221953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.854551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"}
{"level":"info","ts":"2026-09-07T10:56:46.222105Z","caller":"traceutil/trace.go:171","msg":"trace[480659823] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5255; }","duration":"159.004665ms","start":"2026-09-07T10:56:46.063032Z","end":"2026-09-07T10:56:46.222036Z","steps":["trace[480659823] 'agreement among raft nodes before linearized reading'  (duration: 158.328208ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:56:51.453439Z","caller":"traceutil/trace.go:171","msg":"trace[1262434463] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"178.953605ms","start":"2026-09-07T10:56:51.274449Z","end":"2026-09-07T10:56:51.453403Z","steps":["trace[1262434463] 'process raft request'  (duration: 178.702128ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.675151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.367695ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685422058 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:5383 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:56:51.675305Z","caller":"traceutil/trace.go:171","msg":"trace[1265136396] linearizableReadLoop","detail":"{readStateIndex:5630; appliedIndex:5628; }","duration":"334.409793ms","start":"2026-09-07T10:56:51.340869Z","end":"2026-09-07T10:56:51.675279Z","steps":["trace[1265136396] 'read index received'  (duration: 112.437245ms)","trace[1265136396] 'applied index is now lower than readState.Index'  (duration: 221.970788ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:56:51.675372Z","caller":"traceutil/trace.go:171","msg":"trace[468585797] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"400.44243ms","start":"2026-09-07T10:56:51.274886Z","end":"2026-09-07T10:56:51.675328Z","steps":["trace[468585797] 'process raft request'  (duration: 204.760237ms)","trace[468585797] 'compare'  (duration: 195.193151ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:56:51.675602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.274849Z","time spent":"400.621494ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:5383 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-09-07T10:56:51.675619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.71777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"}
{"level":"info","ts":"2026-09-07T10:56:51.675654Z","caller":"traceutil/trace.go:171","msg":"trace[1641343661] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5425; }","duration":"334.824933ms","start":"2026-09-07T10:56:51.340816Z","end":"2026-09-07T10:56:51.675641Z","steps":["trace[1641343661] 'agreement among raft nodes before linearized reading'  (duration: 334.608697ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.67572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.340793Z","time spent":"334.916495ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":917,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "}
{"level":"warn","ts":"2026-09-07T10:56:51.676083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.967497ms","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":"warn","ts":"2026-09-07T10:56:51.676089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.145781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c16da40-1e21-4421-9050-36257a1bc758\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-09-07T10:56:51.67631Z","caller":"traceutil/trace.go:171","msg":"trace[1838240128] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5425; }","duration":"302.208643ms","start":"2026-09-07T10:56:51.374089Z","end":"2026-09-07T10:56:51.676297Z","steps":["trace[1838240128] 'agreement among raft nodes before linearized reading'  (duration: 301.889426ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.676343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.374082Z","time spent":"302.253414ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-09-07T10:56:51.676333Z","caller":"traceutil/trace.go:171","msg":"trace[1791984413] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c16da40-1e21-4421-9050-36257a1bc758; range_end:; response_count:1; response_revision:5425; }","duration":"313.452408ms","start":"2026-09-07T10:56:51.362865Z","end":"2026-09-07T10:56:51.676318Z","steps":["trace[1791984413] 'agreement among raft nodes before linearized reading'  (duration: 313.056989ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.676313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.110089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"warn","ts":"2026-09-07T10:56:51.676391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.362834Z","time spent":"313.54794ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c16da40-1e21-4421-9050-36257a1bc758\" "}
{"level":"info","ts":"2026-09-07T10:56:51.676419Z","caller":"traceutil/trace.go:171","msg":"trace[1634158056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5425; }","duration":"308.444826ms","start":"2026-09-07T10:56:51.367952Z","end":"2026-09-07T10:56:51.676397Z","steps":["trace[1634158056] 'agreement among raft nodes before linearized reading'  (duration: 307.922374ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.676465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.367938Z","time spent":"308.517867ms","remote":"127.0.0.1:42984","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-09-07T10:56:51.676605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.308937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786924"}
{"level":"info","ts":"2026-09-07T10:56:51.676688Z","caller":"traceutil/trace.go:171","msg":"trace[1031779203] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5425; }","duration":"278.39457ms","start":"2026-09-07T10:56:51.398255Z","end":"2026-09-07T10:56:51.676649Z","steps":["trace[1031779203] 'agreement among raft nodes before linearized reading'  (duration: 277.632201ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.676634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.825416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"warn","ts":"2026-09-07T10:56:51.676589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.761749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-09-07T10:56:51.676792Z","caller":"traceutil/trace.go:171","msg":"trace[455297512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5425; }","duration":"329.015701ms","start":"2026-09-07T10:56:51.347739Z","end":"2026-09-07T10:56:51.676755Z","steps":["trace[455297512] 'agreement among raft nodes before linearized reading'  (duration: 328.325194ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.676859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.347733Z","time spent":"329.116693ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-07T10:56:51.676929Z","caller":"traceutil/trace.go:171","msg":"trace[1115893478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5425; }","duration":"330.141988ms","start":"2026-09-07T10:56:51.346682Z","end":"2026-09-07T10:56:51.676824Z","steps":["trace[1115893478] 'agreement among raft nodes before linearized reading'  (duration: 329.165404ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:56:51.677415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:56:51.346638Z","time spent":"330.759623ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-09-07T10:56:52.144691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.524357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T10:56:52.144833Z","caller":"traceutil/trace.go:171","msg":"trace[1948314856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5430; }","duration":"172.794963ms","start":"2026-09-07T10:56:51.972005Z","end":"2026-09-07T10:56:52.144799Z","steps":["trace[1948314856] 'range keys from in-memory index tree'  (duration: 172.401843ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:05.990719Z","caller":"traceutil/trace.go:171","msg":"trace[1596902047] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"138.040892ms","start":"2026-09-07T10:57:05.852647Z","end":"2026-09-07T10:57:05.990688Z","steps":["trace[1596902047] 'process raft request'  (duration: 97.408816ms)","trace[1596902047] 'compare'  (duration: 40.353549ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:57:05.991065Z","caller":"traceutil/trace.go:171","msg":"trace[1755004847] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"138.201885ms","start":"2026-09-07T10:57:05.852798Z","end":"2026-09-07T10:57:05.991Z","steps":["trace[1755004847] 'process raft request'  (duration: 137.817206ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:05.991187Z","caller":"traceutil/trace.go:171","msg":"trace[1353282755] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"138.334929ms","start":"2026-09-07T10:57:05.852824Z","end":"2026-09-07T10:57:05.991159Z","steps":["trace[1353282755] 'process raft request'  (duration: 138.059873ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:05.991181Z","caller":"traceutil/trace.go:171","msg":"trace[1533691790] linearizableReadLoop","detail":"{readStateIndex:5895; appliedIndex:5891; }","duration":"111.252166ms","start":"2026-09-07T10:57:05.87989Z","end":"2026-09-07T10:57:05.991142Z","steps":["trace[1533691790] 'read index received'  (duration: 70.084497ms)","trace[1533691790] 'applied index is now lower than readState.Index'  (duration: 41.166179ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:57:05.991214Z","caller":"traceutil/trace.go:171","msg":"trace[747983403] transaction","detail":"{read_only:false; response_revision:5683; number_of_response:1; }","duration":"138.269317ms","start":"2026-09-07T10:57:05.852902Z","end":"2026-09-07T10:57:05.991171Z","steps":["trace[747983403] 'process raft request'  (duration: 138.128334ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:05.991391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.483132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-07T10:57:05.991474Z","caller":"traceutil/trace.go:171","msg":"trace[1533142814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5683; }","duration":"111.637986ms","start":"2026-09-07T10:57:05.879812Z","end":"2026-09-07T10:57:05.991451Z","steps":["trace[1533142814] 'agreement among raft nodes before linearized reading'  (duration: 111.424851ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:06.601516Z","caller":"traceutil/trace.go:171","msg":"trace[1700464940] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"549.265385ms","start":"2026-09-07T10:57:06.052197Z","end":"2026-09-07T10:57:06.601462Z","steps":["trace[1700464940] 'process raft request'  (duration: 548.942158ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:06.601746Z","caller":"traceutil/trace.go:171","msg":"trace[1723817415] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"549.230715ms","start":"2026-09-07T10:57:06.052467Z","end":"2026-09-07T10:57:06.601698Z","steps":["trace[1723817415] 'process raft request'  (duration: 548.890527ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:06.601853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:57:06.052131Z","time spent":"549.579203ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/openstack/memcached\" mod_revision:5650 > success:<request_put:<key:\"/registry/services/endpoints/openstack/memcached\" value_size:626 >> failure:<request_range:<key:\"/registry/services/endpoints/openstack/memcached\" > >"}
{"level":"info","ts":"2026-09-07T10:57:06.601931Z","caller":"traceutil/trace.go:171","msg":"trace[2079537318] linearizableReadLoop","detail":"{readStateIndex:5900; appliedIndex:5897; }","duration":"434.188427ms","start":"2026-09-07T10:57:06.16772Z","end":"2026-09-07T10:57:06.601908Z","steps":["trace[2079537318] 'read index received'  (duration: 433.299445ms)","trace[2079537318] 'applied index is now lower than readState.Index'  (duration: 888.342µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:57:06.602012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:57:06.052436Z","time spent":"549.40815ms","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/memcached-metrics-ktvn7\" mod_revision:5676 > success:<request_put:<key:\"/registry/endpointslices/openstack/memcached-metrics-ktvn7\" value_size:1002 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/memcached-metrics-ktvn7\" > >"}
{"level":"info","ts":"2026-09-07T10:57:06.602028Z","caller":"traceutil/trace.go:171","msg":"trace[946639572] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"548.944688ms","start":"2026-09-07T10:57:06.05307Z","end":"2026-09-07T10:57:06.602014Z","steps":["trace[946639572] 'process raft request'  (duration: 548.352904ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:06.602197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:57:06.053057Z","time spent":"549.076461ms","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/memcached-p64w7\" mod_revision:5649 > success:<request_put:<key:\"/registry/endpointslices/openstack/memcached-p64w7\" value_size:981 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/memcached-p64w7\" > >"}
{"level":"warn","ts":"2026-09-07T10:57:06.602301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.549865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-07T10:57:06.602453Z","caller":"traceutil/trace.go:171","msg":"trace[1082264062] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5688; }","duration":"434.831073ms","start":"2026-09-07T10:57:06.167595Z","end":"2026-09-07T10:57:06.602426Z","steps":["trace[1082264062] 'agreement among raft nodes before linearized reading'  (duration: 434.454104ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:06.602519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:57:06.16756Z","time spent":"434.939705ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "}
{"level":"warn","ts":"2026-09-07T10:57:06.602725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.710727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-78686485df-vfms5\" ","response":"range_response_count:1 size:11848"}
{"level":"info","ts":"2026-09-07T10:57:06.602783Z","caller":"traceutil/trace.go:171","msg":"trace[1499541262] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-78686485df-vfms5; range_end:; response_count:1; response_revision:5689; }","duration":"302.831229ms","start":"2026-09-07T10:57:06.299938Z","end":"2026-09-07T10:57:06.602769Z","steps":["trace[1499541262] 'agreement among raft nodes before linearized reading'  (duration: 302.586512ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:06.602817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:57:06.299905Z","time spent":"302.902791ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11871,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-78686485df-vfms5\" "}
{"level":"info","ts":"2026-09-07T10:57:06.602731Z","caller":"traceutil/trace.go:171","msg":"trace[812175899] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"134.125836ms","start":"2026-09-07T10:57:06.468591Z","end":"2026-09-07T10:57:06.602716Z","steps":["trace[812175899] 'process raft request'  (duration: 133.852158ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:11.97993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2997}
{"level":"info","ts":"2026-09-07T10:57:12.057417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2997,"took":"75.964611ms","hash":3738682526,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-07T10:57:12.057487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3738682526,"revision":2997,"compact-revision":-1}
{"level":"info","ts":"2026-09-07T10:57:16.030056Z","caller":"traceutil/trace.go:171","msg":"trace[817500353] transaction","detail":"{read_only:false; response_revision:5887; number_of_response:1; }","duration":"121.600269ms","start":"2026-09-07T10:57:15.908415Z","end":"2026-09-07T10:57:16.030015Z","steps":["trace[817500353] 'process raft request'  (duration: 121.141858ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:16.235184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.463285ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685423141 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:5801 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T10:57:16.235372Z","caller":"traceutil/trace.go:171","msg":"trace[382357494] linearizableReadLoop","detail":"{readStateIndex:6108; appliedIndex:6107; }","duration":"177.766475ms","start":"2026-09-07T10:57:16.057567Z","end":"2026-09-07T10:57:16.235334Z","steps":["trace[382357494] 'read index received'  (duration: 74.066694ms)","trace[382357494] 'applied index is now lower than readState.Index'  (duration: 103.697761ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T10:57:16.235483Z","caller":"traceutil/trace.go:171","msg":"trace[309449694] transaction","detail":"{read_only:false; response_revision:5888; number_of_response:1; }","duration":"200.230225ms","start":"2026-09-07T10:57:16.035236Z","end":"2026-09-07T10:57:16.235467Z","steps":["trace[309449694] 'process raft request'  (duration: 96.383241ms)","trace[309449694] 'compare'  (duration: 103.329072ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T10:57:16.235836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.207794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-07T10:57:16.235909Z","caller":"traceutil/trace.go:171","msg":"trace[919006720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5888; }","duration":"162.333657ms","start":"2026-09-07T10:57:16.073559Z","end":"2026-09-07T10:57:16.235893Z","steps":["trace[919006720] 'agreement among raft nodes before linearized reading'  (duration: 162.101771ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:16.235802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.743385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-07T10:57:16.236087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.141283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"warn","ts":"2026-09-07T10:57:16.236109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.607479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6d7432b2-74ce-44a2-9ad8-aa3559aac732\" ","response":"range_response_count:1 size:3646"}
{"level":"info","ts":"2026-09-07T10:57:16.236179Z","caller":"traceutil/trace.go:171","msg":"trace[1157997656] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5888; }","duration":"162.240235ms","start":"2026-09-07T10:57:16.073902Z","end":"2026-09-07T10:57:16.236142Z","steps":["trace[1157997656] 'agreement among raft nodes before linearized reading'  (duration: 161.975518ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T10:57:16.236009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.38988ms","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-09-07T10:57:16.236168Z","caller":"traceutil/trace.go:171","msg":"trace[1523500576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5888; }","duration":"149.108093ms","start":"2026-09-07T10:57:16.08701Z","end":"2026-09-07T10:57:16.236118Z","steps":["trace[1523500576] 'agreement among raft nodes before linearized reading'  (duration: 148.684903ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:16.236256Z","caller":"traceutil/trace.go:171","msg":"trace[804918470] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6d7432b2-74ce-44a2-9ad8-aa3559aac732; range_end:; response_count:1; response_revision:5888; }","duration":"141.826004ms","start":"2026-09-07T10:57:16.094394Z","end":"2026-09-07T10:57:16.23622Z","steps":["trace[804918470] 'agreement among raft nodes before linearized reading'  (duration: 141.353943ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:57:16.236308Z","caller":"traceutil/trace.go:171","msg":"trace[943481713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5888; }","duration":"178.7883ms","start":"2026-09-07T10:57:16.057494Z","end":"2026-09-07T10:57:16.236282Z","steps":["trace[943481713] 'agreement among raft nodes before linearized reading'  (duration: 178.137105ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T10:58:09.845954Z","caller":"traceutil/trace.go:171","msg":"trace[708465107] transaction","detail":"{read_only:false; response_revision:6372; number_of_response:1; }","duration":"114.310899ms","start":"2026-09-07T10:58:09.731602Z","end":"2026-09-07T10:58:09.845913Z","steps":["trace[708465107] 'process raft request'  (duration: 113.964381ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:02:11.987581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5775}
{"level":"info","ts":"2026-09-07T11:02:12.076414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5775,"took":"86.499217ms","hash":1403807230,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":26673152,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-07T11:02:12.076517Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1403807230,"revision":5775,"compact-revision":2997}
{"level":"info","ts":"2026-09-07T11:03:34.813783Z","caller":"traceutil/trace.go:171","msg":"trace[537699212] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"102.124778ms","start":"2026-09-07T11:03:34.711626Z","end":"2026-09-07T11:03:34.813751Z","steps":["trace[537699212] 'process raft request'  (duration: 101.873312ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:03:36.034528Z","caller":"traceutil/trace.go:171","msg":"trace[982353830] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"133.102867ms","start":"2026-09-07T11:03:35.901391Z","end":"2026-09-07T11:03:36.034493Z","steps":["trace[982353830] 'process raft request'  (duration: 132.462321ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:03:36.317121Z","caller":"traceutil/trace.go:171","msg":"trace[1771247961] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"263.016897ms","start":"2026-09-07T11:03:36.054038Z","end":"2026-09-07T11:03:36.317055Z","steps":["trace[1771247961] 'process raft request'  (duration: 234.954009ms)","trace[1771247961] 'compare'  (duration: 27.394981ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:03:36.3172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.138719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-07T11:03:36.317317Z","caller":"traceutil/trace.go:171","msg":"trace[1569758211] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:8756; }","duration":"114.335113ms","start":"2026-09-07T11:03:36.202959Z","end":"2026-09-07T11:03:36.317294Z","steps":["trace[1569758211] 'agreement among raft nodes before linearized reading'  (duration: 114.104698ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:03:36.317422Z","caller":"traceutil/trace.go:171","msg":"trace[1801452823] linearizableReadLoop","detail":"{readStateIndex:9065; appliedIndex:9064; }","duration":"114.033206ms","start":"2026-09-07T11:03:36.203023Z","end":"2026-09-07T11:03:36.317056Z","steps":["trace[1801452823] 'read index received'  (duration: 86.09308ms)","trace[1801452823] 'applied index is now lower than readState.Index'  (duration: 27.938486ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:03:36.73132Z","caller":"traceutil/trace.go:171","msg":"trace[410368301] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"117.814498ms","start":"2026-09-07T11:03:36.613469Z","end":"2026-09-07T11:03:36.731284Z","steps":["trace[410368301] 'process raft request'  (duration: 117.512991ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:03:36.956736Z","caller":"traceutil/trace.go:171","msg":"trace[584123934] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"136.56468ms","start":"2026-09-07T11:03:36.820131Z","end":"2026-09-07T11:03:36.956696Z","steps":["trace[584123934] 'process raft request'  (duration: 136.286104ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:03:36.965415Z","caller":"traceutil/trace.go:171","msg":"trace[1417719021] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"123.29755ms","start":"2026-09-07T11:03:36.842098Z","end":"2026-09-07T11:03:36.965395Z","steps":["trace[1417719021] 'process raft request'  (duration: 123.162736ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:04:04.621208Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243d.wal"}
{"level":"info","ts":"2026-09-07T11:04:06.732243Z","caller":"traceutil/trace.go:171","msg":"trace[1711154055] linearizableReadLoop","detail":"{readStateIndex:9289; appliedIndex:9288; }","duration":"106.143076ms","start":"2026-09-07T11:04:06.626068Z","end":"2026-09-07T11:04:06.732211Z","steps":["trace[1711154055] 'read index received'  (duration: 105.8926ms)","trace[1711154055] 'applied index is now lower than readState.Index'  (duration: 249.336µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:04:06.732356Z","caller":"traceutil/trace.go:171","msg":"trace[1442514037] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"140.698351ms","start":"2026-09-07T11:04:06.591627Z","end":"2026-09-07T11:04:06.732325Z","steps":["trace[1442514037] 'process raft request'  (duration: 140.400624ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:04:06.73255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.467483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-09-07T11:04:06.732594Z","caller":"traceutil/trace.go:171","msg":"trace[1823588357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8971; }","duration":"106.594167ms","start":"2026-09-07T11:04:06.625987Z","end":"2026-09-07T11:04:06.732581Z","steps":["trace[1823588357] 'agreement among raft nodes before linearized reading'  (duration: 106.378742ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:04:12.888783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.550269ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685431594 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:8991 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T11:04:12.888931Z","caller":"traceutil/trace.go:171","msg":"trace[1327321839] linearizableReadLoop","detail":"{readStateIndex:9321; appliedIndex:9320; }","duration":"110.321397ms","start":"2026-09-07T11:04:12.778574Z","end":"2026-09-07T11:04:12.888895Z","steps":["trace[1327321839] 'read index received'  (duration: 82.902µs)","trace[1327321839] 'applied index is now lower than readState.Index'  (duration: 110.236805ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:04:12.889027Z","caller":"traceutil/trace.go:171","msg":"trace[1852781395] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"271.324428ms","start":"2026-09-07T11:04:12.617685Z","end":"2026-09-07T11:04:12.889009Z","steps":["trace[1852781395] 'process raft request'  (duration: 115.427ms)","trace[1852781395] 'compare'  (duration: 155.14686ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:04:12.889408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.413008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-09-07T11:04:12.889526Z","caller":"traceutil/trace.go:171","msg":"trace[606892020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9002; }","duration":"105.576162ms","start":"2026-09-07T11:04:12.783923Z","end":"2026-09-07T11:04:12.8895Z","steps":["trace[606892020] 'agreement among raft nodes before linearized reading'  (duration: 105.227784ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:04:12.889686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.084046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-09-07T11:04:12.889764Z","caller":"traceutil/trace.go:171","msg":"trace[1165518143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9002; }","duration":"111.238409ms","start":"2026-09-07T11:04:12.778506Z","end":"2026-09-07T11:04:12.889744Z","steps":["trace[1165518143] 'agreement among raft nodes before linearized reading'  (duration: 110.969453ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:04:19.788949Z","caller":"traceutil/trace.go:171","msg":"trace[1754651971] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9080; }","duration":"162.089318ms","start":"2026-09-07T11:04:19.626814Z","end":"2026-09-07T11:04:19.788903Z","steps":["trace[1754651971] 'process raft request'  (duration: 102.244811ms)","trace[1754651971] 'compare'  (duration: 59.641762ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:04:19.801396Z","caller":"traceutil/trace.go:171","msg":"trace[2137719720] linearizableReadLoop","detail":"{readStateIndex:9404; appliedIndex:9402; }","duration":"108.366709ms","start":"2026-09-07T11:04:19.692992Z","end":"2026-09-07T11:04:19.801359Z","steps":["trace[2137719720] 'read index received'  (duration: 36.061361ms)","trace[2137719720] 'applied index is now lower than readState.Index'  (duration: 72.303518ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:04:19.801559Z","caller":"traceutil/trace.go:171","msg":"trace[1744397634] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"168.693337ms","start":"2026-09-07T11:04:19.632806Z","end":"2026-09-07T11:04:19.801499Z","steps":["trace[1744397634] 'process raft request'  (duration: 168.36457ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:04:19.802559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.528677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"}
{"level":"info","ts":"2026-09-07T11:04:19.802636Z","caller":"traceutil/trace.go:171","msg":"trace[836769006] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:9081; }","duration":"109.6763ms","start":"2026-09-07T11:04:19.692942Z","end":"2026-09-07T11:04:19.802618Z","steps":["trace[836769006] 'agreement among raft nodes before linearized reading'  (duration: 108.592624ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:04:20.526546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.708419ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685431840 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-n6c9z\" mod_revision:9081 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-n6c9z\" value_size:4575 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-n6c9z\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T11:04:20.526739Z","caller":"traceutil/trace.go:171","msg":"trace[214848751] linearizableReadLoop","detail":"{readStateIndex:9407; appliedIndex:9406; }","duration":"252.567045ms","start":"2026-09-07T11:04:20.274148Z","end":"2026-09-07T11:04:20.526715Z","steps":["trace[214848751] 'read index received'  (duration: 150.62277ms)","trace[214848751] 'applied index is now lower than readState.Index'  (duration: 101.942335ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:04:20.526937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.78582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-74d944b6df-s429q.18d30505f94985a7\" ","response":"range_response_count:1 size:1135"}
{"level":"info","ts":"2026-09-07T11:04:20.526981Z","caller":"traceutil/trace.go:171","msg":"trace[128307500] range","detail":"{range_begin:/registry/events/openstack/barbican-api-74d944b6df-s429q.18d30505f94985a7; range_end:; response_count:1; response_revision:9084; }","duration":"252.895953ms","start":"2026-09-07T11:04:20.27407Z","end":"2026-09-07T11:04:20.526966Z","steps":["trace[128307500] 'agreement among raft nodes before linearized reading'  (duration: 252.703918ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:04:20.526968Z","caller":"traceutil/trace.go:171","msg":"trace[1291674053] transaction","detail":"{read_only:false; response_revision:9084; number_of_response:1; }","duration":"300.064223ms","start":"2026-09-07T11:04:20.226862Z","end":"2026-09-07T11:04:20.526926Z","steps":["trace[1291674053] 'process raft request'  (duration: 197.844472ms)","trace[1291674053] 'compare'  (duration: 101.218126ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:04:20.52717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:20.226842Z","time spent":"300.212837ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-n6c9z\" mod_revision:9081 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-n6c9z\" value_size:4575 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-n6c9z\" > >"}
{"level":"info","ts":"2026-09-07T11:04:20.720595Z","caller":"traceutil/trace.go:171","msg":"trace[2008801518] transaction","detail":"{read_only:false; response_revision:9086; number_of_response:1; }","duration":"122.299636ms","start":"2026-09-07T11:04:20.598269Z","end":"2026-09-07T11:04:20.720568Z","steps":["trace[2008801518] 'process raft request'  (duration: 122.139362ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:04:20.727587Z","caller":"traceutil/trace.go:171","msg":"trace[1689196755] transaction","detail":"{read_only:false; response_revision:9087; number_of_response:1; }","duration":"112.773416ms","start":"2026-09-07T11:04:20.614791Z","end":"2026-09-07T11:04:20.727564Z","steps":["trace[1689196755] 'process raft request'  (duration: 112.541391ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:04:32.439059Z","caller":"traceutil/trace.go:171","msg":"trace[1069835974] transaction","detail":"{read_only:false; response_revision:9182; number_of_response:1; }","duration":"102.690702ms","start":"2026-09-07T11:04:32.33633Z","end":"2026-09-07T11:04:32.43902Z","steps":["trace[1069835974] 'process raft request'  (duration: 102.415585ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:05:21.364489Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-07T11:05:21.381304Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-07T11:05:21.381415Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-09-07T11:05:41.319834Z","caller":"traceutil/trace.go:171","msg":"trace[1490011112] transaction","detail":"{read_only:false; response_revision:9791; number_of_response:1; }","duration":"204.307839ms","start":"2026-09-07T11:05:41.115484Z","end":"2026-09-07T11:05:41.319791Z","steps":["trace[1490011112] 'process raft request'  (duration: 204.074053ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:05:41.703129Z","caller":"traceutil/trace.go:171","msg":"trace[1501775915] transaction","detail":"{read_only:false; response_revision:9795; number_of_response:1; }","duration":"129.446512ms","start":"2026-09-07T11:05:41.573627Z","end":"2026-09-07T11:05:41.703074Z","steps":["trace[1501775915] 'process raft request'  (duration: 105.607123ms)","trace[1501775915] 'compare'  (duration: 23.511021ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:05:41.751058Z","caller":"traceutil/trace.go:171","msg":"trace[1607351687] linearizableReadLoop","detail":"{readStateIndex:10142; appliedIndex:10140; }","duration":"155.306639ms","start":"2026-09-07T11:05:41.595717Z","end":"2026-09-07T11:05:41.751023Z","steps":["trace[1607351687] 'read index received'  (duration: 83.540597ms)","trace[1607351687] 'applied index is now lower than readState.Index'  (duration: 71.764842ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:05:41.751145Z","caller":"traceutil/trace.go:171","msg":"trace[898295695] transaction","detail":"{read_only:false; response_revision:9796; number_of_response:1; }","duration":"161.06034ms","start":"2026-09-07T11:05:41.590052Z","end":"2026-09-07T11:05:41.751112Z","steps":["trace[898295695] 'process raft request'  (duration: 160.808154ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:05:41.751361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.630288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"warn","ts":"2026-09-07T11:05:41.751364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.299854ms","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-09-07T11:05:41.751414Z","caller":"traceutil/trace.go:171","msg":"trace[1419710180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9796; }","duration":"155.74513ms","start":"2026-09-07T11:05:41.595654Z","end":"2026-09-07T11:05:41.751399Z","steps":["trace[1419710180] 'agreement among raft nodes before linearized reading'  (duration: 155.531905ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:05:41.751431Z","caller":"traceutil/trace.go:171","msg":"trace[1050486686] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9796; }","duration":"149.403986ms","start":"2026-09-07T11:05:41.602006Z","end":"2026-09-07T11:05:41.75141Z","steps":["trace[1050486686] 'agreement among raft nodes before linearized reading'  (duration: 149.238472ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:05:41.751466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.675569ms","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-09-07T11:05:41.751561Z","caller":"traceutil/trace.go:171","msg":"trace[434037630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9796; }","duration":"153.797862ms","start":"2026-09-07T11:05:41.59774Z","end":"2026-09-07T11:05:41.751538Z","steps":["trace[434037630] 'agreement among raft nodes before linearized reading'  (duration: 153.491825ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:07:11.994962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8080}
{"level":"info","ts":"2026-09-07T11:07:12.086661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8080,"took":"88.799109ms","hash":2087820829,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":25788416,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-07T11:07:12.086743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2087820829,"revision":8080,"compact-revision":5775}
{"level":"info","ts":"2026-09-07T11:09:25.430439Z","caller":"traceutil/trace.go:171","msg":"trace[1080553452] transaction","detail":"{read_only:false; response_revision:11740; number_of_response:1; }","duration":"238.483029ms","start":"2026-09-07T11:09:25.191905Z","end":"2026-09-07T11:09:25.430388Z","steps":["trace[1080553452] 'process raft request'  (duration: 175.185881ms)","trace[1080553452] 'compare'  (duration: 62.932538ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:12:12.002131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10527}
{"level":"info","ts":"2026-09-07T11:12:12.094354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10527,"took":"89.508957ms","hash":58223512,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":28147712,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-07T11:12:12.094507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":58223512,"revision":10527,"compact-revision":8080}
{"level":"info","ts":"2026-09-07T11:15:37.73535Z","caller":"traceutil/trace.go:171","msg":"trace[370298447] transaction","detail":"{read_only:false; response_revision:14248; number_of_response:1; }","duration":"130.554272ms","start":"2026-09-07T11:15:37.604762Z","end":"2026-09-07T11:15:37.735317Z","steps":["trace[370298447] 'process raft request'  (duration: 130.359548ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:15:45.252049Z","caller":"traceutil/trace.go:171","msg":"trace[620054640] transaction","detail":"{read_only:false; response_revision:14286; number_of_response:1; }","duration":"103.781313ms","start":"2026-09-07T11:15:45.148229Z","end":"2026-09-07T11:15:45.252011Z","steps":["trace[620054640] 'process raft request'  (duration: 103.572338ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:15:53.444607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.548303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-07T11:15:53.444707Z","caller":"traceutil/trace.go:171","msg":"trace[1827056591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14342; }","duration":"149.715237ms","start":"2026-09-07T11:15:53.29497Z","end":"2026-09-07T11:15:53.444685Z","steps":["trace[1827056591] 'range keys from in-memory index tree'  (duration: 149.266637ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:15:53.445945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.629015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-07T11:15:53.446089Z","caller":"traceutil/trace.go:171","msg":"trace[1832185443] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:14342; }","duration":"133.84072ms","start":"2026-09-07T11:15:53.312217Z","end":"2026-09-07T11:15:53.446058Z","steps":["trace[1832185443] 'count revisions from in-memory index tree'  (duration: 133.527112ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:15:54.324825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.538682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-bwnw7\" ","response":"range_response_count:1 size:12364"}
{"level":"info","ts":"2026-09-07T11:15:54.324923Z","caller":"traceutil/trace.go:171","msg":"trace[2040668764] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-bwnw7; range_end:; response_count:1; response_revision:14347; }","duration":"162.696156ms","start":"2026-09-07T11:15:54.162203Z","end":"2026-09-07T11:15:54.324899Z","steps":["trace[2040668764] 'range keys from in-memory index tree'  (duration: 162.322078ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:15:54.325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.550687ms","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:5"}
{"level":"info","ts":"2026-09-07T11:15:54.325095Z","caller":"traceutil/trace.go:171","msg":"trace[661135273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:14347; }","duration":"111.698091ms","start":"2026-09-07T11:15:54.213366Z","end":"2026-09-07T11:15:54.325064Z","steps":["trace[661135273] 'count revisions from in-memory index tree'  (duration: 111.444725ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:15:54.325198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.395651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T11:15:54.325241Z","caller":"traceutil/trace.go:171","msg":"trace[1278023744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14347; }","duration":"353.476224ms","start":"2026-09-07T11:15:53.971752Z","end":"2026-09-07T11:15:54.325229Z","steps":["trace[1278023744] 'range keys from in-memory index tree'  (duration: 353.316919ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:15:54.325272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:15:53.971728Z","time spent":"353.536354ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-07T11:15:54.325442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.589215ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T11:15:54.32553Z","caller":"traceutil/trace.go:171","msg":"trace[613625450] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14347; }","duration":"277.657127ms","start":"2026-09-07T11:15:54.047839Z","end":"2026-09-07T11:15:54.325496Z","steps":["trace[613625450] 'range keys from in-memory index tree'  (duration: 277.580305ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:15:54.418559Z","caller":"traceutil/trace.go:171","msg":"trace[1748748372] transaction","detail":"{read_only:false; response_revision:14348; number_of_response:1; }","duration":"177.420828ms","start":"2026-09-07T11:15:54.241111Z","end":"2026-09-07T11:15:54.418532Z","steps":["trace[1748748372] 'process raft request'  (duration: 177.120701ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:15:54.43107Z","caller":"traceutil/trace.go:171","msg":"trace[249346117] transaction","detail":"{read_only:false; response_revision:14349; number_of_response:1; }","duration":"187.435496ms","start":"2026-09-07T11:15:54.2436Z","end":"2026-09-07T11:15:54.431035Z","steps":["trace[249346117] 'process raft request'  (duration: 185.893589ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:16:10.859455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.436529ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685447125 > lease_revoke:<id:1086a07b7aa9d3c1>","response":"size:28"}
{"level":"info","ts":"2026-09-07T11:16:10.85956Z","caller":"traceutil/trace.go:171","msg":"trace[820138875] linearizableReadLoop","detail":"{readStateIndex:15038; appliedIndex:15037; }","duration":"177.210923ms","start":"2026-09-07T11:16:10.682336Z","end":"2026-09-07T11:16:10.859547Z","steps":["trace[820138875] 'read index received'  (duration: 65.252µs)","trace[820138875] 'applied index is now lower than readState.Index'  (duration: 177.144471ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:16:10.85968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.335646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pqbc8\" ","response":"range_response_count:1 size:6530"}
{"level":"info","ts":"2026-09-07T11:16:10.859699Z","caller":"traceutil/trace.go:171","msg":"trace[458726861] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pqbc8; range_end:; response_count:1; response_revision:14530; }","duration":"177.399787ms","start":"2026-09-07T11:16:10.682294Z","end":"2026-09-07T11:16:10.859694Z","steps":["trace[458726861] 'agreement among raft nodes before linearized reading'  (duration: 177.282195ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:16:16.494569Z","caller":"traceutil/trace.go:171","msg":"trace[1013396971] transaction","detail":"{read_only:false; response_revision:14642; number_of_response:1; }","duration":"122.970301ms","start":"2026-09-07T11:16:16.371565Z","end":"2026-09-07T11:16:16.494535Z","steps":["trace[1013396971] 'process raft request'  (duration: 121.080146ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:16:16.498911Z","caller":"traceutil/trace.go:171","msg":"trace[536762978] linearizableReadLoop","detail":"{readStateIndex:15153; appliedIndex:15151; }","duration":"123.768999ms","start":"2026-09-07T11:16:16.375126Z","end":"2026-09-07T11:16:16.498895Z","steps":["trace[536762978] 'read index received'  (duration: 117.547891ms)","trace[536762978] 'applied index is now lower than readState.Index'  (duration: 6.220448ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:16:16.498979Z","caller":"traceutil/trace.go:171","msg":"trace[1330050622] transaction","detail":"{read_only:false; response_revision:14643; number_of_response:1; }","duration":"123.871642ms","start":"2026-09-07T11:16:16.375069Z","end":"2026-09-07T11:16:16.498941Z","steps":["trace[1330050622] 'process raft request'  (duration: 123.737558ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:16:16.499221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.058907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-nova-server\" ","response":"range_response_count:1 size:9563"}
{"level":"info","ts":"2026-09-07T11:16:16.499292Z","caller":"traceutil/trace.go:171","msg":"trace[1354123965] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-nova-server; range_end:; response_count:1; response_revision:14643; }","duration":"124.19497ms","start":"2026-09-07T11:16:16.375079Z","end":"2026-09-07T11:16:16.499274Z","steps":["trace[1354123965] 'agreement among raft nodes before linearized reading'  (duration: 123.897463ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:17:12.00991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12860}
{"level":"info","ts":"2026-09-07T11:17:12.096854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12860,"took":"84.178735ms","hash":1631565749,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-07T11:17:12.096945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1631565749,"revision":12860,"compact-revision":10527}
{"level":"info","ts":"2026-09-07T11:17:45.614914Z","caller":"traceutil/trace.go:171","msg":"trace[369145495] linearizableReadLoop","detail":"{readStateIndex:16011; appliedIndex:16010; }","duration":"154.453011ms","start":"2026-09-07T11:17:45.460432Z","end":"2026-09-07T11:17:45.614885Z","steps":["trace[369145495] 'read index received'  (duration: 154.245676ms)","trace[369145495] 'applied index is now lower than readState.Index'  (duration: 206.285µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:17:45.615328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.867891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"}
{"level":"info","ts":"2026-09-07T11:17:45.615421Z","caller":"traceutil/trace.go:171","msg":"trace[931867734] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:15476; }","duration":"155.008454ms","start":"2026-09-07T11:17:45.46039Z","end":"2026-09-07T11:17:45.615399Z","steps":["trace[931867734] 'agreement among raft nodes before linearized reading'  (duration: 154.602124ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:22:12.017711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15301}
{"level":"info","ts":"2026-09-07T11:22:12.121084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15301,"took":"100.423276ms","hash":1779075974,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":32129024,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-07T11:22:12.121161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1779075974,"revision":15301,"compact-revision":12860}
{"level":"info","ts":"2026-09-07T11:23:26.504802Z","caller":"traceutil/trace.go:171","msg":"trace[1148439898] linearizableReadLoop","detail":"{readStateIndex:18652; appliedIndex:18650; }","duration":"121.327667ms","start":"2026-09-07T11:23:26.383444Z","end":"2026-09-07T11:23:26.504772Z","steps":["trace[1148439898] 'read index received'  (duration: 24.414875ms)","trace[1148439898] 'applied index is now lower than readState.Index'  (duration: 96.911382ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:23:26.504931Z","caller":"traceutil/trace.go:171","msg":"trace[2067992174] transaction","detail":"{read_only:false; response_revision:18030; number_of_response:1; }","duration":"123.372436ms","start":"2026-09-07T11:23:26.381517Z","end":"2026-09-07T11:23:26.504889Z","steps":["trace[2067992174] 'process raft request'  (duration: 123.059229ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:23:26.505062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.586373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:23:26.505535Z","caller":"traceutil/trace.go:171","msg":"trace[801341048] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:18030; }","duration":"121.982684ms","start":"2026-09-07T11:23:26.383413Z","end":"2026-09-07T11:23:26.505396Z","steps":["trace[801341048] 'agreement among raft nodes before linearized reading'  (duration: 121.570123ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:25:53.639348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-07T11:25:53.647386Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-09-07T11:25:53.647497Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-09-07T11:27:12.025668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17507}
{"level":"info","ts":"2026-09-07T11:27:12.140719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17507,"took":"110.89185ms","hash":2069887521,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":35250176,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-09-07T11:27:12.140813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2069887521,"revision":17507,"compact-revision":15301}
{"level":"info","ts":"2026-09-07T11:28:41.508739Z","caller":"traceutil/trace.go:171","msg":"trace[885938756] transaction","detail":"{read_only:false; response_revision:20539; number_of_response:1; }","duration":"210.245034ms","start":"2026-09-07T11:28:41.298451Z","end":"2026-09-07T11:28:41.508696Z","steps":["trace[885938756] 'process raft request'  (duration: 210.034229ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:28:41.532629Z","caller":"traceutil/trace.go:171","msg":"trace[934682163] transaction","detail":"{read_only:false; response_revision:20540; number_of_response:1; }","duration":"233.656722ms","start":"2026-09-07T11:28:41.298931Z","end":"2026-09-07T11:28:41.532588Z","steps":["trace[934682163] 'process raft request'  (duration: 233.493699ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:28:57.696802Z","caller":"traceutil/trace.go:171","msg":"trace[972198219] transaction","detail":"{read_only:false; response_revision:20620; number_of_response:1; }","duration":"108.190546ms","start":"2026-09-07T11:28:57.588579Z","end":"2026-09-07T11:28:57.69677Z","steps":["trace[972198219] 'process raft request'  (duration: 108.013262ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:28:57.90308Z","caller":"traceutil/trace.go:171","msg":"trace[249910540] transaction","detail":"{read_only:false; response_revision:20622; number_of_response:1; }","duration":"201.041891ms","start":"2026-09-07T11:28:57.701984Z","end":"2026-09-07T11:28:57.903026Z","steps":["trace[249910540] 'process raft request'  (duration: 200.824826ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:28:57.963757Z","caller":"traceutil/trace.go:171","msg":"trace[641685452] transaction","detail":"{read_only:false; response_revision:20623; number_of_response:1; }","duration":"177.647352ms","start":"2026-09-07T11:28:57.786075Z","end":"2026-09-07T11:28:57.963723Z","steps":["trace[641685452] 'process raft request'  (duration: 177.494799ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:28:58.258624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.706205ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685468036 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:20612 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-07T11:28:58.25871Z","caller":"traceutil/trace.go:171","msg":"trace[1396087826] linearizableReadLoop","detail":"{readStateIndex:21331; appliedIndex:21330; }","duration":"156.457298ms","start":"2026-09-07T11:28:58.102238Z","end":"2026-09-07T11:28:58.258695Z","steps":["trace[1396087826] 'read index received'  (duration: 27.457897ms)","trace[1396087826] 'applied index is now lower than readState.Index'  (duration: 128.998161ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:28:58.258813Z","caller":"traceutil/trace.go:171","msg":"trace[1278940668] transaction","detail":"{read_only:false; response_revision:20624; number_of_response:1; }","duration":"292.162493ms","start":"2026-09-07T11:28:57.966639Z","end":"2026-09-07T11:28:58.258802Z","steps":["trace[1278940668] 'process raft request'  (duration: 163.199982ms)","trace[1278940668] 'compare'  (duration: 128.592722ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:28:58.259088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.853528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-09-07T11:28:58.259181Z","caller":"traceutil/trace.go:171","msg":"trace[2122975986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20624; }","duration":"156.996471ms","start":"2026-09-07T11:28:58.10216Z","end":"2026-09-07T11:28:58.259157Z","steps":["trace[2122975986] 'agreement among raft nodes before linearized reading'  (duration: 156.773456ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:28:58.259086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.813517ms","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-09-07T11:28:58.259316Z","caller":"traceutil/trace.go:171","msg":"trace[240620268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20624; }","duration":"157.097823ms","start":"2026-09-07T11:28:58.1022Z","end":"2026-09-07T11:28:58.259298Z","steps":["trace[240620268] 'agreement among raft nodes before linearized reading'  (duration: 156.739385ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:28:58.646557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.029967ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685468040 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:20616 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-07T11:28:58.646661Z","caller":"traceutil/trace.go:171","msg":"trace[842345493] linearizableReadLoop","detail":"{readStateIndex:21333; appliedIndex:21332; }","duration":"299.819209ms","start":"2026-09-07T11:28:58.346825Z","end":"2026-09-07T11:28:58.646645Z","steps":["trace[842345493] 'read index received'  (duration: 113.593688ms)","trace[842345493] 'applied index is now lower than readState.Index'  (duration: 186.223991ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:28:58.646768Z","caller":"traceutil/trace.go:171","msg":"trace[283360730] transaction","detail":"{read_only:false; response_revision:20626; number_of_response:1; }","duration":"380.508998ms","start":"2026-09-07T11:28:58.266224Z","end":"2026-09-07T11:28:58.646733Z","steps":["trace[283360730] 'process raft request'  (duration: 194.191345ms)","trace[283360730] 'compare'  (duration: 185.694047ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:28:58.646852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.020193ms","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-09-07T11:28:58.646895Z","caller":"traceutil/trace.go:171","msg":"trace[1690408452] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20626; }","duration":"300.105405ms","start":"2026-09-07T11:28:58.346775Z","end":"2026-09-07T11:28:58.646881Z","steps":["trace[1690408452] 'agreement among raft nodes before linearized reading'  (duration: 299.953402ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:28:58.646929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:58.266204Z","time spent":"380.640911ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:20616 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-09-07T11:28:58.646944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:58.346756Z","time spent":"300.176687ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-07T11:28:58.646999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.153083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-09-07T11:28:58.647071Z","caller":"traceutil/trace.go:171","msg":"trace[315141286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20626; }","duration":"236.292826ms","start":"2026-09-07T11:28:58.410757Z","end":"2026-09-07T11:28:58.64705Z","steps":["trace[315141286] 'agreement among raft nodes before linearized reading'  (duration: 236.088182ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:32:12.035188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19900}
{"level":"info","ts":"2026-09-07T11:32:12.154562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19900,"took":"115.787931ms","hash":2589852934,"current-db-size-bytes":55431168,"current-db-size":"55 MB","current-db-size-in-use-bytes":30867456,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-07T11:32:12.154633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2589852934,"revision":19900,"compact-revision":17507}
{"level":"info","ts":"2026-09-07T11:32:50.198128Z","caller":"traceutil/trace.go:171","msg":"trace[756779537] linearizableReadLoop","detail":"{readStateIndex:23823; appliedIndex:23822; }","duration":"314.627739ms","start":"2026-09-07T11:32:49.883463Z","end":"2026-09-07T11:32:50.198091Z","steps":["trace[756779537] 'read index received'  (duration: 314.402402ms)","trace[756779537] 'applied index is now lower than readState.Index'  (duration: 224.427µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:32:50.198245Z","caller":"traceutil/trace.go:171","msg":"trace[1943819936] transaction","detail":"{read_only:false; response_revision:23044; number_of_response:1; }","duration":"318.707768ms","start":"2026-09-07T11:32:49.879463Z","end":"2026-09-07T11:32:50.198171Z","steps":["trace[1943819936] 'process raft request'  (duration: 318.413701ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:32:50.198496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:32:49.879436Z","time spent":"318.901642ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:22996 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-09-07T11:32:50.198515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.011297ms","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-09-07T11:32:50.198605Z","caller":"traceutil/trace.go:171","msg":"trace[1367408200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23044; }","duration":"315.157931ms","start":"2026-09-07T11:32:49.883429Z","end":"2026-09-07T11:32:50.198587Z","steps":["trace[1367408200] 'agreement among raft nodes before linearized reading'  (duration: 314.852464ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:32:50.198651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:32:49.883417Z","time spent":"315.222372ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-09-07T11:32:50.19921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.235361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:32:50.199272Z","caller":"traceutil/trace.go:171","msg":"trace[1211047666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23045; }","duration":"228.340054ms","start":"2026-09-07T11:32:49.970918Z","end":"2026-09-07T11:32:50.199258Z","steps":["trace[1211047666] 'agreement among raft nodes before linearized reading'  (duration: 228.19436ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:32:50.19957Z","caller":"traceutil/trace.go:171","msg":"trace[362073836] transaction","detail":"{read_only:false; response_revision:23045; number_of_response:1; }","duration":"284.320712ms","start":"2026-09-07T11:32:49.915237Z","end":"2026-09-07T11:32:50.199558Z","steps":["trace[362073836] 'process raft request'  (duration: 283.757409ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:32:50.200187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.482819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-09-07T11:32:50.200252Z","caller":"traceutil/trace.go:171","msg":"trace[1537405282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23045; }","duration":"198.594302ms","start":"2026-09-07T11:32:50.001631Z","end":"2026-09-07T11:32:50.200225Z","steps":["trace[1537405282] 'agreement among raft nodes before linearized reading'  (duration: 198.427438ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:32:50.200264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.09127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-09-07T11:32:50.200337Z","caller":"traceutil/trace.go:171","msg":"trace[1990784715] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23045; }","duration":"100.207432ms","start":"2026-09-07T11:32:50.100107Z","end":"2026-09-07T11:32:50.200314Z","steps":["trace[1990784715] 'agreement among raft nodes before linearized reading'  (duration: 99.933166ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:15.658269Z","caller":"traceutil/trace.go:171","msg":"trace[1286614807] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"102.052811ms","start":"2026-09-07T11:36:15.556186Z","end":"2026-09-07T11:36:15.658239Z","steps":["trace[1286614807] 'process raft request'  (duration: 45.949273ms)","trace[1286614807] 'compare'  (duration: 55.961455ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:17.790295Z","caller":"traceutil/trace.go:171","msg":"trace[1435183316] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"211.93879ms","start":"2026-09-07T11:36:17.578314Z","end":"2026-09-07T11:36:17.790253Z","steps":["trace[1435183316] 'process raft request'  (duration: 191.299831ms)","trace[1435183316] 'compare'  (duration: 20.493925ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:17.819633Z","caller":"traceutil/trace.go:171","msg":"trace[27294175] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"241.107117ms","start":"2026-09-07T11:36:17.578506Z","end":"2026-09-07T11:36:17.819613Z","steps":["trace[27294175] 'process raft request'  (duration: 241.019235ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:17.819722Z","caller":"traceutil/trace.go:171","msg":"trace[91552284] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"241.337962ms","start":"2026-09-07T11:36:17.578358Z","end":"2026-09-07T11:36:17.819696Z","steps":["trace[91552284] 'process raft request'  (duration: 241.053415ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:17.979003Z","caller":"traceutil/trace.go:171","msg":"trace[904757356] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"124.071774ms","start":"2026-09-07T11:36:17.854887Z","end":"2026-09-07T11:36:17.978959Z","steps":["trace[904757356] 'process raft request'  (duration: 37.19509ms)","trace[904757356] 'compare'  (duration: 86.673579ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:17.979031Z","caller":"traceutil/trace.go:171","msg":"trace[1148563312] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"105.29504ms","start":"2026-09-07T11:36:17.873702Z","end":"2026-09-07T11:36:17.978997Z","steps":["trace[1148563312] 'process raft request'  (duration: 105.179757ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:18.283921Z","caller":"traceutil/trace.go:171","msg":"trace[2049532417] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"103.667369ms","start":"2026-09-07T11:36:18.180229Z","end":"2026-09-07T11:36:18.283896Z","steps":["trace[2049532417] 'process raft request'  (duration: 103.366672ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:18.356885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.636966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:18.356976Z","caller":"traceutil/trace.go:171","msg":"trace[915442290] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24924; }","duration":"133.736089ms","start":"2026-09-07T11:36:18.223217Z","end":"2026-09-07T11:36:18.356954Z","steps":["trace[915442290] 'agreement among raft nodes before linearized reading'  (duration: 133.593385ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:18.357143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.912143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:18.357181Z","caller":"traceutil/trace.go:171","msg":"trace[268781940] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24924; }","duration":"133.994015ms","start":"2026-09-07T11:36:18.223175Z","end":"2026-09-07T11:36:18.357169Z","steps":["trace[268781940] 'agreement among raft nodes before linearized reading'  (duration: 133.932313ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:18.357289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.086206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:18.357323Z","caller":"traceutil/trace.go:171","msg":"trace[36935825] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24924; }","duration":"134.159738ms","start":"2026-09-07T11:36:18.223151Z","end":"2026-09-07T11:36:18.357311Z","steps":["trace[36935825] 'agreement among raft nodes before linearized reading'  (duration: 134.110417ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:18.357494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.344822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-09-07T11:36:18.357477Z","caller":"traceutil/trace.go:171","msg":"trace[576335375] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"134.290381ms","start":"2026-09-07T11:36:18.223158Z","end":"2026-09-07T11:36:18.357448Z","steps":["trace[576335375] 'process raft request'  (duration: 133.118483ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:18.357528Z","caller":"traceutil/trace.go:171","msg":"trace[952250733] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24924; }","duration":"134.402284ms","start":"2026-09-07T11:36:18.223115Z","end":"2026-09-07T11:36:18.357517Z","steps":["trace[952250733] 'agreement among raft nodes before linearized reading'  (duration: 134.273951ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:19.746338Z","caller":"traceutil/trace.go:171","msg":"trace[13113228] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"167.879805ms","start":"2026-09-07T11:36:19.578416Z","end":"2026-09-07T11:36:19.746295Z","steps":["trace[13113228] 'process raft request'  (duration: 89.514968ms)","trace[13113228] 'compare'  (duration: 78.140422ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:19.935265Z","caller":"traceutil/trace.go:171","msg":"trace[2098118860] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"106.19619ms","start":"2026-09-07T11:36:19.829031Z","end":"2026-09-07T11:36:19.935228Z","steps":["trace[2098118860] 'process raft request'  (duration: 106.066817ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:20.099264Z","caller":"traceutil/trace.go:171","msg":"trace[1556542742] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"113.810275ms","start":"2026-09-07T11:36:19.985417Z","end":"2026-09-07T11:36:20.099227Z","steps":["trace[1556542742] 'process raft request'  (duration: 50.307878ms)","trace[1556542742] 'compare'  (duration: 63.321143ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:22.138606Z","caller":"traceutil/trace.go:171","msg":"trace[1605626794] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"122.756581ms","start":"2026-09-07T11:36:22.01582Z","end":"2026-09-07T11:36:22.138576Z","steps":["trace[1605626794] 'process raft request'  (duration: 94.304483ms)","trace[1605626794] 'compare'  (duration: 28.030929ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:22.55798Z","caller":"traceutil/trace.go:171","msg":"trace[833480053] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"147.50869ms","start":"2026-09-07T11:36:22.410425Z","end":"2026-09-07T11:36:22.557933Z","steps":["trace[833480053] 'process raft request'  (duration: 84.460395ms)","trace[833480053] 'compare'  (duration: 62.945313ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:22.763804Z","caller":"traceutil/trace.go:171","msg":"trace[1699826865] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"201.474918ms","start":"2026-09-07T11:36:22.562298Z","end":"2026-09-07T11:36:22.763772Z","steps":["trace[1699826865] 'process raft request'  (duration: 200.627857ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:23.445125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.607004ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685480168 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24943 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-07T11:36:23.445281Z","caller":"traceutil/trace.go:171","msg":"trace[1601688346] linearizableReadLoop","detail":"{readStateIndex:25782; appliedIndex:25781; }","duration":"553.08787ms","start":"2026-09-07T11:36:22.892171Z","end":"2026-09-07T11:36:23.445259Z","steps":["trace[1601688346] 'read index received'  (duration: 236.173307ms)","trace[1601688346] 'applied index is now lower than readState.Index'  (duration: 316.912633ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:23.445335Z","caller":"traceutil/trace.go:171","msg":"trace[57832637] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"567.274394ms","start":"2026-09-07T11:36:22.878016Z","end":"2026-09-07T11:36:23.44529Z","steps":["trace[57832637] 'process raft request'  (duration: 250.393093ms)","trace[57832637] 'compare'  (duration: 315.59874ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:36:23.44553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.360986ms","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-09-07T11:36:23.445609Z","caller":"traceutil/trace.go:171","msg":"trace[1122989023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24956; }","duration":"553.472339ms","start":"2026-09-07T11:36:22.892118Z","end":"2026-09-07T11:36:23.44559Z","steps":["trace[1122989023] 'agreement among raft nodes before linearized reading'  (duration: 553.268454ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:23.445699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:22.892101Z","time spent":"553.55484ms","remote":"127.0.0.1:42984","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-09-07T11:36:23.445714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.031265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:23.445779Z","caller":"traceutil/trace.go:171","msg":"trace[1996130305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24956; }","duration":"474.137247ms","start":"2026-09-07T11:36:22.971623Z","end":"2026-09-07T11:36:23.44576Z","steps":["trace[1996130305] 'agreement among raft nodes before linearized reading'  (duration: 473.992543ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:23.445812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:22.971602Z","time spent":"474.204319ms","remote":"127.0.0.1:42706","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-09-07T11:36:23.445786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.379773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:23.445871Z","caller":"traceutil/trace.go:171","msg":"trace[1715116979] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24956; }","duration":"412.506907ms","start":"2026-09-07T11:36:23.03334Z","end":"2026-09-07T11:36:23.445847Z","steps":["trace[1715116979] 'agreement among raft nodes before linearized reading'  (duration: 412.345372ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:23.445935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:23.033323Z","time spent":"412.585558ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "}
{"level":"warn","ts":"2026-09-07T11:36:23.445777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:22.877989Z","time spent":"567.55837ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24943 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-09-07T11:36:23.446182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.117443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-09-07T11:36:23.446284Z","caller":"traceutil/trace.go:171","msg":"trace[1727657830] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24956; }","duration":"468.241566ms","start":"2026-09-07T11:36:22.977998Z","end":"2026-09-07T11:36:23.446239Z","steps":["trace[1727657830] 'agreement among raft nodes before linearized reading'  (duration: 467.919528ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:23.446336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:22.977971Z","time spent":"468.352919ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "}
{"level":"warn","ts":"2026-09-07T11:36:24.28035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.924136ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685480181 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24949 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-07T11:36:24.280716Z","caller":"traceutil/trace.go:171","msg":"trace[1226034583] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"258.352094ms","start":"2026-09-07T11:36:24.022333Z","end":"2026-09-07T11:36:24.280685Z","steps":["trace[1226034583] 'process raft request'  (duration: 97.064319ms)","trace[1226034583] 'compare'  (duration: 160.509306ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:24.280839Z","caller":"traceutil/trace.go:171","msg":"trace[1603297155] linearizableReadLoop","detail":"{readStateIndex:25789; appliedIndex:25788; }","duration":"170.630131ms","start":"2026-09-07T11:36:24.110184Z","end":"2026-09-07T11:36:24.280814Z","steps":["trace[1603297155] 'read index received'  (duration: 8.916917ms)","trace[1603297155] 'applied index is now lower than readState.Index'  (duration: 161.710224ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:24.280945Z","caller":"traceutil/trace.go:171","msg":"trace[1366724797] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"136.554366ms","start":"2026-09-07T11:36:24.144323Z","end":"2026-09-07T11:36:24.280877Z","steps":["trace[1366724797] 'process raft request'  (duration: 136.369492ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:24.281017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.821555ms","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-09-07T11:36:24.281224Z","caller":"traceutil/trace.go:171","msg":"trace[1847711561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24964; }","duration":"171.135813ms","start":"2026-09-07T11:36:24.110048Z","end":"2026-09-07T11:36:24.281183Z","steps":["trace[1847711561] 'agreement among raft nodes before linearized reading'  (duration: 170.841426ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:24.300252Z","caller":"traceutil/trace.go:171","msg":"trace[622813500] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"137.188002ms","start":"2026-09-07T11:36:24.16304Z","end":"2026-09-07T11:36:24.300228Z","steps":["trace[622813500] 'process raft request'  (duration: 137.044128ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:24.470218Z","caller":"traceutil/trace.go:171","msg":"trace[1718083525] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"114.126782ms","start":"2026-09-07T11:36:24.356065Z","end":"2026-09-07T11:36:24.470192Z","steps":["trace[1718083525] 'process raft request'  (duration: 113.852715ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:24.698031Z","caller":"traceutil/trace.go:171","msg":"trace[1791012744] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"159.996614ms","start":"2026-09-07T11:36:24.538002Z","end":"2026-09-07T11:36:24.697999Z","steps":["trace[1791012744] 'process raft request'  (duration: 62.640737ms)","trace[1791012744] 'compare'  (duration: 97.199643ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:24.699019Z","caller":"traceutil/trace.go:171","msg":"trace[77042559] linearizableReadLoop","detail":"{readStateIndex:25794; appliedIndex:25792; }","duration":"106.861107ms","start":"2026-09-07T11:36:24.592145Z","end":"2026-09-07T11:36:24.699006Z","steps":["trace[77042559] 'read index received'  (duration: 8.489225ms)","trace[77042559] 'applied index is now lower than readState.Index'  (duration: 98.370472ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:36:24.699251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.083672ms","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-09-07T11:36:24.699296Z","caller":"traceutil/trace.go:171","msg":"trace[286730959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24968; }","duration":"107.186685ms","start":"2026-09-07T11:36:24.592095Z","end":"2026-09-07T11:36:24.699282Z","steps":["trace[286730959] 'agreement among raft nodes before linearized reading'  (duration: 106.96416ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:24.699294Z","caller":"traceutil/trace.go:171","msg":"trace[754993343] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"135.736446ms","start":"2026-09-07T11:36:24.563425Z","end":"2026-09-07T11:36:24.699161Z","steps":["trace[754993343] 'process raft request'  (duration: 135.49661ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:24.954919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.91044ms","expected-duration":"100ms","prefix":"","request":"header:<ID:1190815603685480194 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:24955 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-07T11:36:24.955057Z","caller":"traceutil/trace.go:171","msg":"trace[143069229] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"181.224557ms","start":"2026-09-07T11:36:24.773807Z","end":"2026-09-07T11:36:24.955032Z","steps":["trace[143069229] 'process raft request'  (duration: 38.114513ms)","trace[143069229] 'compare'  (duration: 142.769837ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:25.465115Z","caller":"traceutil/trace.go:171","msg":"trace[636046545] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"105.36684ms","start":"2026-09-07T11:36:25.359718Z","end":"2026-09-07T11:36:25.465084Z","steps":["trace[636046545] 'process raft request'  (duration: 105.106444ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:25.626326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.687932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.118\" ","response":"range_response_count:1 size:140"}
{"level":"info","ts":"2026-09-07T11:36:25.626407Z","caller":"traceutil/trace.go:171","msg":"trace[890103265] range","detail":"{range_begin:/registry/masterleases/162.253.55.118; range_end:; response_count:1; response_revision:24973; }","duration":"132.819875ms","start":"2026-09-07T11:36:25.49357Z","end":"2026-09-07T11:36:25.62639Z","steps":["trace[890103265] 'agreement among raft nodes before linearized reading'  (duration: 132.559379ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.286449Z","caller":"traceutil/trace.go:171","msg":"trace[437930696] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"182.509928ms","start":"2026-09-07T11:36:26.103915Z","end":"2026-09-07T11:36:26.286425Z","steps":["trace[437930696] 'process raft request'  (duration: 182.372875ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.337481Z","caller":"traceutil/trace.go:171","msg":"trace[712854918] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"233.065133ms","start":"2026-09-07T11:36:26.104367Z","end":"2026-09-07T11:36:26.337433Z","steps":["trace[712854918] 'process raft request'  (duration: 207.603777ms)","trace[712854918] 'compare'  (duration: 25.20031ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:26.337512Z","caller":"traceutil/trace.go:171","msg":"trace[1948437952] linearizableReadLoop","detail":"{readStateIndex:25805; appliedIndex:25803; }","duration":"183.763018ms","start":"2026-09-07T11:36:26.153721Z","end":"2026-09-07T11:36:26.337484Z","steps":["trace[1948437952] 'read index received'  (duration: 132.149689ms)","trace[1948437952] 'applied index is now lower than readState.Index'  (duration: 51.610459ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:36:26.337875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.137827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-09-07T11:36:26.337921Z","caller":"traceutil/trace.go:171","msg":"trace[1420643118] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24980; }","duration":"184.227069ms","start":"2026-09-07T11:36:26.153683Z","end":"2026-09-07T11:36:26.33791Z","steps":["trace[1420643118] 'agreement among raft nodes before linearized reading'  (duration: 183.971433ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.639126Z","caller":"traceutil/trace.go:171","msg":"trace[444578636] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"162.258268ms","start":"2026-09-07T11:36:26.476847Z","end":"2026-09-07T11:36:26.639105Z","steps":["trace[444578636] 'read index received'  (duration: 162.021083ms)","trace[444578636] 'applied index is now lower than readState.Index'  (duration: 236.215µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:26.639216Z","caller":"traceutil/trace.go:171","msg":"trace[1789241071] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"162.490523ms","start":"2026-09-07T11:36:26.476692Z","end":"2026-09-07T11:36:26.639183Z","steps":["trace[1789241071] 'process raft request'  (duration: 162.279798ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:26.639271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.31329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T11:36:26.639272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.407652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:26.639303Z","caller":"traceutil/trace.go:171","msg":"trace[493973004] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24982; }","duration":"162.376931ms","start":"2026-09-07T11:36:26.476917Z","end":"2026-09-07T11:36:26.639293Z","steps":["trace[493973004] 'agreement among raft nodes before linearized reading'  (duration: 162.311349ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.639325Z","caller":"traceutil/trace.go:171","msg":"trace[477796534] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24982; }","duration":"162.515434ms","start":"2026-09-07T11:36:26.476795Z","end":"2026-09-07T11:36:26.639311Z","steps":["trace[477796534] 'agreement among raft nodes before linearized reading'  (duration: 162.412642ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:26.639421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.399362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"warn","ts":"2026-09-07T11:36:26.639453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.475523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:26.63947Z","caller":"traceutil/trace.go:171","msg":"trace[934178912] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24982; }","duration":"162.464133ms","start":"2026-09-07T11:36:26.476993Z","end":"2026-09-07T11:36:26.639457Z","steps":["trace[934178912] 'agreement among raft nodes before linearized reading'  (duration: 162.341ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.639478Z","caller":"traceutil/trace.go:171","msg":"trace[419053644] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24982; }","duration":"162.528125ms","start":"2026-09-07T11:36:26.476942Z","end":"2026-09-07T11:36:26.63947Z","steps":["trace[419053644] 'agreement among raft nodes before linearized reading'  (duration: 162.488414ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:26.639294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.275688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T11:36:26.639545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.591256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:26.639592Z","caller":"traceutil/trace.go:171","msg":"trace[127402356] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24982; }","duration":"162.593827ms","start":"2026-09-07T11:36:26.47699Z","end":"2026-09-07T11:36:26.639584Z","steps":["trace[127402356] 'agreement among raft nodes before linearized reading'  (duration: 162.252159ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.639597Z","caller":"traceutil/trace.go:171","msg":"trace[829144871] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24982; }","duration":"162.658438ms","start":"2026-09-07T11:36:26.476931Z","end":"2026-09-07T11:36:26.639589Z","steps":["trace[829144871] 'agreement among raft nodes before linearized reading'  (duration: 162.590666ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:26.639676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.696169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T11:36:26.639699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.668519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-09-07T11:36:26.639806Z","caller":"traceutil/trace.go:171","msg":"trace[115260270] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24982; }","duration":"162.834762ms","start":"2026-09-07T11:36:26.476962Z","end":"2026-09-07T11:36:26.639796Z","steps":["trace[115260270] 'agreement among raft nodes before linearized reading'  (duration: 162.692659ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.63987Z","caller":"traceutil/trace.go:171","msg":"trace[1387506032] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24982; }","duration":"162.862834ms","start":"2026-09-07T11:36:26.476989Z","end":"2026-09-07T11:36:26.639852Z","steps":["trace[1387506032] 'agreement among raft nodes before linearized reading'  (duration: 162.397512ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:26.639714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.693839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:26.640107Z","caller":"traceutil/trace.go:171","msg":"trace[1139845115] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24982; }","duration":"163.099848ms","start":"2026-09-07T11:36:26.476993Z","end":"2026-09-07T11:36:26.640093Z","steps":["trace[1139845115] 'agreement among raft nodes before linearized reading'  (duration: 162.649968ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:26.92906Z","caller":"traceutil/trace.go:171","msg":"trace[1115535428] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"111.935739ms","start":"2026-09-07T11:36:26.817092Z","end":"2026-09-07T11:36:26.929028Z","steps":["trace[1115535428] 'process raft request'  (duration: 105.544705ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:28.45996Z","caller":"traceutil/trace.go:171","msg":"trace[933104219] linearizableReadLoop","detail":"{readStateIndex:25819; appliedIndex:25818; }","duration":"117.104805ms","start":"2026-09-07T11:36:28.342836Z","end":"2026-09-07T11:36:28.45994Z","steps":["trace[933104219] 'read index received'  (duration: 116.969272ms)","trace[933104219] 'applied index is now lower than readState.Index'  (duration: 134.283µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T11:36:28.460038Z","caller":"traceutil/trace.go:171","msg":"trace[1808169916] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"161.51889ms","start":"2026-09-07T11:36:28.298487Z","end":"2026-09-07T11:36:28.460006Z","steps":["trace[1808169916] 'process raft request'  (duration: 161.312135ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.460356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.487354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.461148Z","caller":"traceutil/trace.go:171","msg":"trace[609800219] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24992; }","duration":"117.639178ms","start":"2026-09-07T11:36:28.342797Z","end":"2026-09-07T11:36:28.460436Z","steps":["trace[609800219] 'agreement among raft nodes before linearized reading'  (duration: 117.252988ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:28.944766Z","caller":"traceutil/trace.go:171","msg":"trace[1684511948] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"601.00818ms","start":"2026-09-07T11:36:28.343733Z","end":"2026-09-07T11:36:28.944742Z","steps":["trace[1684511948] 'process raft request'  (duration: 600.770583ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.944954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.34372Z","time spent":"601.124792ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:24979 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-07T11:36:28.94507Z","caller":"traceutil/trace.go:171","msg":"trace[1912510422] linearizableReadLoop","detail":"{readStateIndex:25821; appliedIndex:25819; }","duration":"484.92011ms","start":"2026-09-07T11:36:28.46009Z","end":"2026-09-07T11:36:28.94501Z","steps":["trace[1912510422] 'read index received'  (duration: 484.407717ms)","trace[1912510422] 'applied index is now lower than readState.Index'  (duration: 473.252µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T11:36:28.945331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.53959ms","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-09-07T11:36:28.945173Z","caller":"traceutil/trace.go:171","msg":"trace[932609891] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"600.252351ms","start":"2026-09-07T11:36:28.344899Z","end":"2026-09-07T11:36:28.945151Z","steps":["trace[932609891] 'process raft request'  (duration: 599.799271ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:28.945398Z","caller":"traceutil/trace.go:171","msg":"trace[153285550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24994; }","duration":"598.635622ms","start":"2026-09-07T11:36:28.346748Z","end":"2026-09-07T11:36:28.945383Z","steps":["trace[153285550] 'agreement among raft nodes before linearized reading'  (duration: 598.468068ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.945437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.346738Z","time spent":"598.690714ms","remote":"127.0.0.1:42984","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-09-07T11:36:28.945537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.344851Z","time spent":"600.568439ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24980 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"warn","ts":"2026-09-07T11:36:28.94613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.670659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.946186Z","caller":"traceutil/trace.go:171","msg":"trace[820926047] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24996; }","duration":"300.832733ms","start":"2026-09-07T11:36:28.64534Z","end":"2026-09-07T11:36:28.946173Z","steps":["trace[820926047] 'agreement among raft nodes before linearized reading'  (duration: 300.669019ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.946222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.64532Z","time spent":"300.894524ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "}
{"level":"warn","ts":"2026-09-07T11:36:28.946401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.316112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T11:36:28.946438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.467835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.946446Z","caller":"traceutil/trace.go:171","msg":"trace[757484454] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24996; }","duration":"298.375863ms","start":"2026-09-07T11:36:28.648057Z","end":"2026-09-07T11:36:28.946433Z","steps":["trace[757484454] 'agreement among raft nodes before linearized reading'  (duration: 298.289001ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:28.946466Z","caller":"traceutil/trace.go:171","msg":"trace[597516320] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24996; }","duration":"298.515136ms","start":"2026-09-07T11:36:28.647942Z","end":"2026-09-07T11:36:28.946457Z","steps":["trace[597516320] 'agreement among raft nodes before linearized reading'  (duration: 298.469035ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.946658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.742002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.946692Z","caller":"traceutil/trace.go:171","msg":"trace[1181244420] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24996; }","duration":"298.795224ms","start":"2026-09-07T11:36:28.647889Z","end":"2026-09-07T11:36:28.946684Z","steps":["trace[1181244420] 'agreement among raft nodes before linearized reading'  (duration: 298.745003ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.946874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.496811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.946911Z","caller":"traceutil/trace.go:171","msg":"trace[263113181] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24996; }","duration":"299.556382ms","start":"2026-09-07T11:36:28.647346Z","end":"2026-09-07T11:36:28.946902Z","steps":["trace[263113181] 'agreement among raft nodes before linearized reading'  (duration: 299.497061ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T11:36:28.947281Z","caller":"traceutil/trace.go:171","msg":"trace[536230332] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"301.15924ms","start":"2026-09-07T11:36:28.646101Z","end":"2026-09-07T11:36:28.947261Z","steps":["trace[536230332] 'process raft request'  (duration: 299.768517ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.947333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.428628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-09-07T11:36:28.947388Z","caller":"traceutil/trace.go:171","msg":"trace[8251960] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24996; }","duration":"301.485529ms","start":"2026-09-07T11:36:28.645889Z","end":"2026-09-07T11:36:28.947375Z","steps":["trace[8251960] 'agreement among raft nodes before linearized reading'  (duration: 301.346206ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.947431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.645862Z","time spent":"301.559831ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5105,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "}
{"level":"warn","ts":"2026-09-07T11:36:28.947518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.646075Z","time spent":"301.258553ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24982 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-07T11:36:28.947776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.262628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.947821Z","caller":"traceutil/trace.go:171","msg":"trace[1410559831] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24996; }","duration":"302.34863ms","start":"2026-09-07T11:36:28.645461Z","end":"2026-09-07T11:36:28.94781Z","steps":["trace[1410559831] 'agreement among raft nodes before linearized reading'  (duration: 302.283728ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.94785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.64543Z","time spent":"302.413512ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "}
{"level":"warn","ts":"2026-09-07T11:36:28.948108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.683778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.948144Z","caller":"traceutil/trace.go:171","msg":"trace[1178678503] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24996; }","duration":"302.746599ms","start":"2026-09-07T11:36:28.645389Z","end":"2026-09-07T11:36:28.948136Z","steps":["trace[1178678503] 'agreement among raft nodes before linearized reading'  (duration: 302.689998ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.948169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.645374Z","time spent":"302.78918ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "}
{"level":"info","ts":"2026-09-07T11:36:28.94783Z","caller":"traceutil/trace.go:171","msg":"trace[663337666] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"185.954832ms","start":"2026-09-07T11:36:28.761856Z","end":"2026-09-07T11:36:28.947811Z","steps":["trace[663337666] 'process raft request'  (duration: 184.110597ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.948589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.850541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T11:36:28.94871Z","caller":"traceutil/trace.go:171","msg":"trace[1308747656] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24996; }","duration":"209.979573ms","start":"2026-09-07T11:36:28.738659Z","end":"2026-09-07T11:36:28.948639Z","steps":["trace[1308747656] 'agreement among raft nodes before linearized reading'  (duration: 209.84526ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.948757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.368607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"warn","ts":"2026-09-07T11:36:28.94962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.640207ms","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-09-07T11:36:28.949715Z","caller":"traceutil/trace.go:171","msg":"trace[665065472] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24994; }","duration":"585.756022ms","start":"2026-09-07T11:36:28.363942Z","end":"2026-09-07T11:36:28.949698Z","steps":["trace[665065472] 'agreement among raft nodes before linearized reading'  (duration: 581.230032ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.94977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.36393Z","time spent":"585.822773ms","remote":"127.0.0.1:42984","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-09-07T11:36:28.955392Z","caller":"traceutil/trace.go:171","msg":"trace[304008873] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24996; }","duration":"299.691065ms","start":"2026-09-07T11:36:28.649355Z","end":"2026-09-07T11:36:28.949046Z","steps":["trace[304008873] 'agreement among raft nodes before linearized reading'  (duration: 299.299376ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T11:36:28.955599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:28.649342Z","time spent":"306.113951ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5105,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "}
{"level":"info","ts":"2026-09-07T11:37:12.044235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22228}
{"level":"info","ts":"2026-09-07T11:37:12.180016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22228,"took":"129.655819ms","hash":202751172,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":42373120,"current-db-size-in-use":"42 MB"}
{"level":"info","ts":"2026-09-07T11:37:12.180117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":202751172,"revision":22228,"compact-revision":19900}
{"level":"info","ts":"2026-09-07T11:42:12.053488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25312}
{"level":"info","ts":"2026-09-07T11:42:12.214495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25312,"took":"154.224251ms","hash":3129747386,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":29499392,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-07T11:42:12.214612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3129747386,"revision":25312,"compact-revision":22228}