{"level":"warn","ts":"2026-10-02T09:15:22.267972Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:15:22.268133Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T09:15:22.268289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:15:22.268308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-10-02T09:15:22.268341Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T09:15:22.269586Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-10-02T09:15:22.26974Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T09:15:22.273301Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.153411ms"} {"level":"info","ts":"2026-10-02T09:15:22.279781Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-10-02T09:15:22.279912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T09:15:22.279951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-10-02T09:15:22.27997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T09:15:22.279988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-10-02T09:15:22.280044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-10-02T09:15:22.288908Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T09:15:22.290994Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T09:15:22.292779Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T09:15:22.295818Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T09:15:22.296187Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T09:15:22.296334Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T09:15:22.296373Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T09:15:22.296331Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T09:15:22.298136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-10-02T09:15:22.299347Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-10-02T09:15:22.301338Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T09:15:22.301491Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-10-02T09:15:22.301524Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-10-02T09:15:22.301839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T09:15:22.301889Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T09:15:22.980535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T09:15:22.98061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T09:15:22.980674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-10-02T09:15:22.980695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-10-02T09:15:22.980706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-10-02T09:15:22.980723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-10-02T09:15:22.980737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-10-02T09:15:22.989251Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:15:22.994386Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T09:15:22.994409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:15:22.994667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:15:22.994924Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T09:15:22.994999Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T09:15:22.995503Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:15:22.995657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:15:22.995703Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:15:22.998686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-10-02T09:15:23.000516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T09:16:09.247266Z","caller":"traceutil/trace.go:171","msg":"trace[1359914792] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"125.891411ms","start":"2026-10-02T09:16:09.121346Z","end":"2026-10-02T09:16:09.247237Z","steps":["trace[1359914792] 'process raft request' (duration: 94.021082ms)","trace[1359914792] 'compare' (duration: 30.789547ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:16:09.962054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.454384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:16:09.962303Z","caller":"traceutil/trace.go:171","msg":"trace[475935905] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"414.185059ms","start":"2026-10-02T09:16:09.548051Z","end":"2026-10-02T09:16:09.962236Z","steps":["trace[475935905] 'process raft request' (duration: 241.847688ms)","trace[475935905] 'compare' (duration: 171.2536ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:16:09.962219Z","caller":"traceutil/trace.go:171","msg":"trace[370708989] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"269.974724ms","start":"2026-10-02T09:16:09.692226Z","end":"2026-10-02T09:16:09.962201Z","steps":["trace[370708989] 'read index received' (duration: 97.663653ms)","trace[370708989] 'applied index is now lower than readState.Index' (duration: 172.309721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:16:09.962698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:16:09.54801Z","time spent":"414.385982ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:16:09.962491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.219287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hm4lf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-02T09:16:09.962832Z","caller":"traceutil/trace.go:171","msg":"trace[1383362000] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hm4lf; range_end:; response_count:1; response_revision:645; }","duration":"270.637956ms","start":"2026-10-02T09:16:09.692165Z","end":"2026-10-02T09:16:09.962803Z","steps":["trace[1383362000] 'agreement among raft nodes before linearized reading' (duration: 270.164497ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:16:09.974803Z","caller":"traceutil/trace.go:171","msg":"trace[912217339] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"149.031829ms","start":"2026-10-02T09:16:09.825739Z","end":"2026-10-02T09:16:09.974771Z","steps":["trace[912217339] 'process raft request' (duration: 148.310915ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:29.845877Z","caller":"traceutil/trace.go:171","msg":"trace[529526919] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"166.998348ms","start":"2026-10-02T09:19:29.678818Z","end":"2026-10-02T09:19:29.845817Z","steps":["trace[529526919] 'process raft request' (duration: 166.353696ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:24:36.463029Z","caller":"traceutil/trace.go:171","msg":"trace[1830205424] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"103.227254ms","start":"2026-10-02T09:24:36.359772Z","end":"2026-10-02T09:24:36.462999Z","steps":["trace[1830205424] 'process raft request' (duration: 16.063975ms)","trace[1830205424] 'compare' (duration: 86.920705ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:24:55.613477Z","caller":"traceutil/trace.go:171","msg":"trace[96184044] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"102.560811ms","start":"2026-10-02T09:24:55.510885Z","end":"2026-10-02T09:24:55.613446Z","steps":["trace[96184044] 'process raft request' (duration: 72.503403ms)","trace[96184044] 'compare' (duration: 29.800443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:25:03.889103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.383122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:25:03.889332Z","caller":"traceutil/trace.go:171","msg":"trace[519123441] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"140.281016ms","start":"2026-10-02T09:25:03.749018Z","end":"2026-10-02T09:25:03.889299Z","steps":["trace[519123441] 'process raft request' (duration: 31.569768ms)","trace[519123441] 'compare' (duration: 108.201148ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:25:04.162712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.673331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:25:04.162877Z","caller":"traceutil/trace.go:171","msg":"trace[161313243] linearizableReadLoop","detail":"{readStateIndex:5928; appliedIndex:5926; }","duration":"266.581137ms","start":"2026-10-02T09:25:03.896255Z","end":"2026-10-02T09:25:04.162837Z","steps":["trace[161313243] 'read index received' (duration: 67.943917ms)","trace[161313243] 'applied index is now lower than readState.Index' (duration: 198.63569ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:25:04.163087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.856472ms","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-10-02T09:25:04.162939Z","caller":"traceutil/trace.go:171","msg":"trace[1679112872] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"268.85972ms","start":"2026-10-02T09:25:03.894043Z","end":"2026-10-02T09:25:04.162902Z","steps":["trace[1679112872] 'process raft request' (duration: 125.853332ms)","trace[1679112872] 'compare' (duration: 142.498918ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:25:04.163149Z","caller":"traceutil/trace.go:171","msg":"trace[1665877361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5723; }","duration":"266.953844ms","start":"2026-10-02T09:25:03.896179Z","end":"2026-10-02T09:25:04.163133Z","steps":["trace[1665877361] 'agreement among raft nodes before linearized reading' (duration: 266.777351ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:25:04.318028Z","caller":"traceutil/trace.go:171","msg":"trace[1902253381] linearizableReadLoop","detail":"{readStateIndex:5931; appliedIndex:5929; }","duration":"138.992382ms","start":"2026-10-02T09:25:04.179011Z","end":"2026-10-02T09:25:04.318004Z","steps":["trace[1902253381] 'read index received' (duration: 105.800673ms)","trace[1902253381] 'applied index is now lower than readState.Index' (duration: 33.190599ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:25:04.318276Z","caller":"traceutil/trace.go:171","msg":"trace[809209985] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"149.98948ms","start":"2026-10-02T09:25:04.168165Z","end":"2026-10-02T09:25:04.318154Z","steps":["trace[809209985] 'process raft request' (duration: 116.632808ms)","trace[809209985] 'compare' (duration: 32.918364ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:25:04.318352Z","caller":"traceutil/trace.go:171","msg":"trace[70437220] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"145.145508ms","start":"2026-10-02T09:25:04.173178Z","end":"2026-10-02T09:25:04.318323Z","steps":["trace[70437220] 'process raft request' (duration: 144.776571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:25:04.318363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.338537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-02T09:25:04.318441Z","caller":"traceutil/trace.go:171","msg":"trace[820755729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5726; }","duration":"139.46064ms","start":"2026-10-02T09:25:04.178961Z","end":"2026-10-02T09:25:04.318422Z","steps":["trace[820755729] 'agreement among raft nodes before linearized reading' (duration: 139.236967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:25:04.318727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.732929ms","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-10-02T09:25:04.318778Z","caller":"traceutil/trace.go:171","msg":"trace[935724156] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5726; }","duration":"117.821181ms","start":"2026-10-02T09:25:04.200944Z","end":"2026-10-02T09:25:04.318765Z","steps":["trace[935724156] 'agreement among raft nodes before linearized reading' (duration: 117.606307ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:25:23.039817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-10-02T09:25:23.115446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"74.257616ms","hash":1611694300,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":32575488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T09:25:23.115546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1611694300,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-10-02T09:30:23.047656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5914} {"level":"info","ts":"2026-10-02T09:30:23.138296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5914,"took":"88.640925ms","hash":2906644066,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":26570752,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-02T09:30:23.138368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2906644066,"revision":5914,"compact-revision":2991} {"level":"info","ts":"2026-10-02T09:31:24.696603Z","caller":"traceutil/trace.go:171","msg":"trace[889368898] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"104.17098ms","start":"2026-10-02T09:31:24.592406Z","end":"2026-10-02T09:31:24.696577Z","steps":["trace[889368898] 'process raft request' (duration: 83.424046ms)","trace[889368898] 'compare' (duration: 20.567411ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:31:24.69684Z","caller":"traceutil/trace.go:171","msg":"trace[856734618] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"103.365076ms","start":"2026-10-02T09:31:24.59344Z","end":"2026-10-02T09:31:24.696805Z","steps":["trace[856734618] 'process raft request' (duration: 103.095441ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:31:55.036833Z","caller":"traceutil/trace.go:171","msg":"trace[122054026] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"130.642592ms","start":"2026-10-02T09:31:54.906154Z","end":"2026-10-02T09:31:55.036797Z","steps":["trace[122054026] 'process raft request' (duration: 73.421234ms)","trace[122054026] 'compare' (duration: 57.027205ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:32:02.274224Z","caller":"traceutil/trace.go:171","msg":"trace[1255706905] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"114.811893ms","start":"2026-10-02T09:32:02.159388Z","end":"2026-10-02T09:32:02.2742Z","steps":["trace[1255706905] 'process raft request' (duration: 114.476076ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:32:06.872283Z","caller":"traceutil/trace.go:171","msg":"trace[2116473051] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8901; }","duration":"134.097569ms","start":"2026-10-02T09:32:06.738155Z","end":"2026-10-02T09:32:06.872253Z","steps":["trace[2116473051] 'process raft request' (duration: 123.182981ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:32:06.917299Z","caller":"traceutil/trace.go:171","msg":"trace[768511676] transaction","detail":"{read_only:false; response_revision:8902; number_of_response:1; }","duration":"176.543055ms","start":"2026-10-02T09:32:06.74068Z","end":"2026-10-02T09:32:06.917223Z","steps":["trace[768511676] 'process raft request' (duration: 176.234449ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:32:06.917569Z","caller":"traceutil/trace.go:171","msg":"trace[1940135475] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"124.726521ms","start":"2026-10-02T09:32:06.792813Z","end":"2026-10-02T09:32:06.917539Z","steps":["trace[1940135475] 'process raft request' (duration: 124.277422ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:32:07.170243Z","caller":"traceutil/trace.go:171","msg":"trace[943041631] linearizableReadLoop","detail":"{readStateIndex:9216; appliedIndex:9214; }","duration":"246.354693ms","start":"2026-10-02T09:32:06.923868Z","end":"2026-10-02T09:32:07.170223Z","steps":["trace[943041631] 'read index received' (duration: 246.158989ms)","trace[943041631] 'applied index is now lower than readState.Index' (duration: 194.854µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:32:07.170585Z","caller":"traceutil/trace.go:171","msg":"trace[1784136294] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"285.730612ms","start":"2026-10-02T09:32:06.88484Z","end":"2026-10-02T09:32:07.170571Z","steps":["trace[1784136294] 'process raft request' (duration: 285.187121ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:32:07.170912Z","caller":"traceutil/trace.go:171","msg":"trace[2069960339] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"265.845663ms","start":"2026-10-02T09:32:06.905054Z","end":"2026-10-02T09:32:07.170899Z","steps":["trace[2069960339] 'process raft request' (duration: 265.128129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:32:07.171317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.430654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6hbkm\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-10-02T09:32:07.171396Z","caller":"traceutil/trace.go:171","msg":"trace[225675698] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6hbkm; range_end:; response_count:1; response_revision:8906; }","duration":"247.562256ms","start":"2026-10-02T09:32:06.923815Z","end":"2026-10-02T09:32:07.171378Z","steps":["trace[225675698] 'agreement among raft nodes before linearized reading' (duration: 247.346322ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:32:14.68562Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245d.wal"} {"level":"info","ts":"2026-10-02T09:33:21.442992Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T09:33:21.449927Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-02T09:33:21.450014Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-02T09:35:23.054201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8083} {"level":"info","ts":"2026-10-02T09:35:23.122067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8083,"took":"65.652719ms","hash":1218072544,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":28831744,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T09:35:23.122141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1218072544,"revision":8083,"compact-revision":5914} {"level":"warn","ts":"2026-10-02T09:37:22.046076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.236297ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-02T09:37:22.046205Z","caller":"traceutil/trace.go:171","msg":"trace[1893102059] linearizableReadLoop","detail":"{readStateIndex:11994; appliedIndex:11993; }","duration":"157.185171ms","start":"2026-10-02T09:37:21.888993Z","end":"2026-10-02T09:37:22.046178Z","steps":["trace[1893102059] 'read index received' (duration: 55.644279ms)","trace[1893102059] 'applied index is now lower than readState.Index' (duration: 101.538772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:37:22.046472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.705875ms","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:5"} {"level":"warn","ts":"2026-10-02T09:37:22.046529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.536848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-02T09:37:22.046597Z","caller":"traceutil/trace.go:171","msg":"trace[512987003] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11602; }","duration":"157.64021ms","start":"2026-10-02T09:37:21.888939Z","end":"2026-10-02T09:37:22.046579Z","steps":["trace[512987003] 'agreement among raft nodes before linearized reading' (duration: 157.382605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:37:22.046677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.416278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T09:37:22.046755Z","caller":"traceutil/trace.go:171","msg":"trace[1345756679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11602; }","duration":"103.50508ms","start":"2026-10-02T09:37:21.943229Z","end":"2026-10-02T09:37:22.046734Z","steps":["trace[1345756679] 'agreement among raft nodes before linearized reading' (duration: 103.264386ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:37:22.046748Z","caller":"traceutil/trace.go:171","msg":"trace[1125446269] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:11602; }","duration":"132.777667ms","start":"2026-10-02T09:37:21.913736Z","end":"2026-10-02T09:37:22.046514Z","steps":["trace[1125446269] 'agreement among raft nodes before linearized reading' (duration: 132.674085ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:37:22.378662Z","caller":"traceutil/trace.go:171","msg":"trace[782040575] linearizableReadLoop","detail":"{readStateIndex:11999; appliedIndex:11998; }","duration":"170.77894ms","start":"2026-10-02T09:37:22.207823Z","end":"2026-10-02T09:37:22.378602Z","steps":["trace[782040575] 'read index received' (duration: 170.581906ms)","trace[782040575] 'applied index is now lower than readState.Index' (duration: 195.294µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:37:22.378718Z","caller":"traceutil/trace.go:171","msg":"trace[1924076355] transaction","detail":"{read_only:false; response_revision:11607; number_of_response:1; }","duration":"175.778374ms","start":"2026-10-02T09:37:22.202902Z","end":"2026-10-02T09:37:22.37868Z","steps":["trace[1924076355] 'process raft request' (duration: 175.490429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:37:22.37897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.123786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-02T09:37:22.379035Z","caller":"traceutil/trace.go:171","msg":"trace[1922632791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11607; }","duration":"171.256229ms","start":"2026-10-02T09:37:22.207764Z","end":"2026-10-02T09:37:22.37902Z","steps":["trace[1922632791] 'agreement among raft nodes before linearized reading' (duration: 171.020824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:37:22.389692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.302909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:37:22.389804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.795939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:37:22.389842Z","caller":"traceutil/trace.go:171","msg":"trace[300352982] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11608; }","duration":"150.84151ms","start":"2026-10-02T09:37:22.238986Z","end":"2026-10-02T09:37:22.389828Z","steps":["trace[300352982] 'agreement among raft nodes before linearized reading' (duration: 150.777779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:37:22.389849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.251228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:37:22.389882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.150976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:37:22.389871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.161734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-02T09:37:22.389911Z","caller":"traceutil/trace.go:171","msg":"trace[1407439772] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11608; }","duration":"150.3331ms","start":"2026-10-02T09:37:22.239561Z","end":"2026-10-02T09:37:22.389895Z","steps":["trace[1407439772] 'agreement among raft nodes before linearized reading' (duration: 150.209467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:37:22.389906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.011603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-02T09:37:22.389939Z","caller":"traceutil/trace.go:171","msg":"trace[656437533] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11608; }","duration":"151.217577ms","start":"2026-10-02T09:37:22.238699Z","end":"2026-10-02T09:37:22.389916Z","steps":["trace[656437533] 'agreement among raft nodes before linearized reading' (duration: 151.124195ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:37:22.38997Z","caller":"traceutil/trace.go:171","msg":"trace[1058846015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11608; }","duration":"132.293438ms","start":"2026-10-02T09:37:22.257653Z","end":"2026-10-02T09:37:22.389946Z","steps":["trace[1058846015] 'agreement among raft nodes before linearized reading' (duration: 132.040163ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:37:22.389985Z","caller":"traceutil/trace.go:171","msg":"trace[70990771] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11608; }","duration":"151.087395ms","start":"2026-10-02T09:37:22.238879Z","end":"2026-10-02T09:37:22.389967Z","steps":["trace[70990771] 'agreement among raft nodes before linearized reading' (duration: 150.908471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:37:22.389719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.53819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-02T09:37:22.390038Z","caller":"traceutil/trace.go:171","msg":"trace[1336630118] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11608; }","duration":"144.877207ms","start":"2026-10-02T09:37:22.24514Z","end":"2026-10-02T09:37:22.390017Z","steps":["trace[1336630118] 'agreement among raft nodes before linearized reading' (duration: 144.457289ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:37:22.391121Z","caller":"traceutil/trace.go:171","msg":"trace[186792586] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11608; }","duration":"151.442231ms","start":"2026-10-02T09:37:22.238336Z","end":"2026-10-02T09:37:22.389778Z","steps":["trace[186792586] 'agreement among raft nodes before linearized reading' (duration: 151.233037ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:40:23.061612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10521} {"level":"info","ts":"2026-10-02T09:40:23.160808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10521,"took":"96.872422ms","hash":3975791128,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":27602944,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T09:40:23.16088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3975791128,"revision":10521,"compact-revision":8083} {"level":"info","ts":"2026-10-02T09:43:08.447414Z","caller":"traceutil/trace.go:171","msg":"trace[789795178] transaction","detail":"{read_only:false; response_revision:13976; number_of_response:1; }","duration":"132.789544ms","start":"2026-10-02T09:43:08.31459Z","end":"2026-10-02T09:43:08.44738Z","steps":["trace[789795178] 'process raft request' (duration: 132.614882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:36.762608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b8181020-6aec-47f7-8b51-568e96ef1405\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-10-02T09:43:36.762649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.623762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T09:43:36.762715Z","caller":"traceutil/trace.go:171","msg":"trace[700791493] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b8181020-6aec-47f7-8b51-568e96ef1405; range_end:; response_count:1; response_revision:14128; }","duration":"136.075452ms","start":"2026-10-02T09:43:36.626614Z","end":"2026-10-02T09:43:36.762689Z","steps":["trace[700791493] 'range keys from in-memory index tree' (duration: 135.736885ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:43:36.762777Z","caller":"traceutil/trace.go:171","msg":"trace[166645574] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:14128; }","duration":"136.755015ms","start":"2026-10-02T09:43:36.625967Z","end":"2026-10-02T09:43:36.762722Z","steps":["trace[166645574] 'range keys from in-memory index tree' (duration: 136.388538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:45.703178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.01056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:43:45.703314Z","caller":"traceutil/trace.go:171","msg":"trace[1246127113] transaction","detail":"{read_only:false; response_revision:14186; number_of_response:1; }","duration":"259.920673ms","start":"2026-10-02T09:43:45.443367Z","end":"2026-10-02T09:43:45.703287Z","steps":["trace[1246127113] 'process raft request' (duration: 142.710059ms)","trace[1246127113] 'compare' (duration: 116.644013ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:43:45.703559Z","caller":"traceutil/trace.go:171","msg":"trace[1569248225] linearizableReadLoop","detail":"{readStateIndex:14669; appliedIndex:14667; }","duration":"125.697524ms","start":"2026-10-02T09:43:45.577842Z","end":"2026-10-02T09:43:45.703539Z","steps":["trace[1569248225] 'read index received' (duration: 8.228487ms)","trace[1569248225] 'applied index is now lower than readState.Index' (duration: 117.468207ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:43:45.703639Z","caller":"traceutil/trace.go:171","msg":"trace[852856443] transaction","detail":"{read_only:false; response_revision:14187; number_of_response:1; }","duration":"255.213401ms","start":"2026-10-02T09:43:45.448418Z","end":"2026-10-02T09:43:45.703631Z","steps":["trace[852856443] 'process raft request' (duration: 255.001838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:45.703739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.882338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:43:45.703763Z","caller":"traceutil/trace.go:171","msg":"trace[585926789] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:14187; }","duration":"125.96322ms","start":"2026-10-02T09:43:45.577793Z","end":"2026-10-02T09:43:45.703756Z","steps":["trace[585926789] 'agreement among raft nodes before linearized reading' (duration: 125.910429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:45.718349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.557656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:43:45.71847Z","caller":"traceutil/trace.go:171","msg":"trace[1185782876] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:14188; }","duration":"130.73328ms","start":"2026-10-02T09:43:45.587716Z","end":"2026-10-02T09:43:45.718449Z","steps":["trace[1185782876] 'agreement among raft nodes before linearized reading' (duration: 130.438254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:45.719059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.119583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-02T09:43:45.719101Z","caller":"traceutil/trace.go:171","msg":"trace[960086084] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14188; }","duration":"125.199975ms","start":"2026-10-02T09:43:45.593886Z","end":"2026-10-02T09:43:45.719086Z","steps":["trace[960086084] 'agreement among raft nodes before linearized reading' (duration: 125.055182ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:43:47.562054Z","caller":"traceutil/trace.go:171","msg":"trace[264678329] transaction","detail":"{read_only:false; response_revision:14202; number_of_response:1; }","duration":"108.580609ms","start":"2026-10-02T09:43:47.453446Z","end":"2026-10-02T09:43:47.562027Z","steps":["trace[264678329] 'process raft request' (duration: 108.414296ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:43:55.869881Z","caller":"traceutil/trace.go:171","msg":"trace[2004061547] transaction","detail":"{read_only:false; response_revision:14360; number_of_response:1; }","duration":"120.014787ms","start":"2026-10-02T09:43:55.749834Z","end":"2026-10-02T09:43:55.869848Z","steps":["trace[2004061547] 'process raft request' (duration: 80.989873ms)","trace[2004061547] 'compare' (duration: 38.907432ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:43:55.915384Z","caller":"traceutil/trace.go:171","msg":"trace[1736468297] transaction","detail":"{read_only:false; response_revision:14361; number_of_response:1; }","duration":"164.606545ms","start":"2026-10-02T09:43:55.750728Z","end":"2026-10-02T09:43:55.915334Z","steps":["trace[1736468297] 'process raft request' (duration: 164.431542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:56.09271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.639928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T09:43:56.092803Z","caller":"traceutil/trace.go:171","msg":"trace[1518197767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14362; }","duration":"120.77349ms","start":"2026-10-02T09:43:55.972008Z","end":"2026-10-02T09:43:56.092782Z","steps":["trace[1518197767] 'range keys from in-memory index tree' (duration: 120.443044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:43:56.092715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.575096ms","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-10-02T09:43:56.092979Z","caller":"traceutil/trace.go:171","msg":"trace[1930256590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14362; }","duration":"111.875981ms","start":"2026-10-02T09:43:55.981091Z","end":"2026-10-02T09:43:56.092967Z","steps":["trace[1930256590] 'range keys from in-memory index tree' (duration: 111.416853ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:45:23.07172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12798} {"level":"info","ts":"2026-10-02T09:45:23.164003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12798,"took":"88.987875ms","hash":557828672,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":30572544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T09:45:23.164075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":557828672,"revision":12798,"compact-revision":10521} {"level":"warn","ts":"2026-10-02T09:45:32.220929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.708869ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-02T09:45:32.221039Z","caller":"traceutil/trace.go:171","msg":"trace[452550702] linearizableReadLoop","detail":"{readStateIndex:15950; appliedIndex:15949; }","duration":"107.061379ms","start":"2026-10-02T09:45:32.11396Z","end":"2026-10-02T09:45:32.221022Z","steps":["trace[452550702] 'read index received' (duration: 33.04µs)","trace[452550702] 'applied index is now lower than readState.Index' (duration: 107.026449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:45:32.221163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.190322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:45:32.221198Z","caller":"traceutil/trace.go:171","msg":"trace[906561413] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15429; }","duration":"107.269054ms","start":"2026-10-02T09:45:32.113919Z","end":"2026-10-02T09:45:32.221188Z","steps":["trace[906561413] 'agreement among raft nodes before linearized reading' (duration: 107.170882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:45:32.232153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.12055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:45:32.232214Z","caller":"traceutil/trace.go:171","msg":"trace[1684962480] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15430; }","duration":"118.227473ms","start":"2026-10-02T09:45:32.113972Z","end":"2026-10-02T09:45:32.2322Z","steps":["trace[1684962480] 'agreement among raft nodes before linearized reading' (duration: 118.09557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:45:32.232468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.806565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-02T09:45:32.232505Z","caller":"traceutil/trace.go:171","msg":"trace[1125481080] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15430; }","duration":"117.858535ms","start":"2026-10-02T09:45:32.114637Z","end":"2026-10-02T09:45:32.232495Z","steps":["trace[1125481080] 'agreement among raft nodes before linearized reading' (duration: 117.745183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:45:32.232643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.59133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:45:32.232677Z","caller":"traceutil/trace.go:171","msg":"trace[9024529] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15430; }","duration":"118.658791ms","start":"2026-10-02T09:45:32.114009Z","end":"2026-10-02T09:45:32.232668Z","steps":["trace[9024529] 'agreement among raft nodes before linearized reading' (duration: 118.61403ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:45:33.145247Z","caller":"traceutil/trace.go:171","msg":"trace[1060771494] transaction","detail":"{read_only:false; response_revision:15438; number_of_response:1; }","duration":"113.93557ms","start":"2026-10-02T09:45:33.031282Z","end":"2026-10-02T09:45:33.145218Z","steps":["trace[1060771494] 'process raft request' (duration: 111.422393ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:50:23.081475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15388} {"level":"info","ts":"2026-10-02T09:50:23.196755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15388,"took":"112.257165ms","hash":322901259,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":32595968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T09:50:23.196815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":322901259,"revision":15388,"compact-revision":12798} {"level":"info","ts":"2026-10-02T09:51:19.735094Z","caller":"traceutil/trace.go:171","msg":"trace[768978243] transaction","detail":"{read_only:false; response_revision:18089; number_of_response:1; }","duration":"120.163285ms","start":"2026-10-02T09:51:19.614868Z","end":"2026-10-02T09:51:19.735031Z","steps":["trace[768978243] 'process raft request' (duration: 73.212572ms)","trace[768978243] 'compare' (duration: 46.75275ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:51:20.720294Z","caller":"traceutil/trace.go:171","msg":"trace[1112432522] linearizableReadLoop","detail":"{readStateIndex:18697; appliedIndex:18696; }","duration":"277.724032ms","start":"2026-10-02T09:51:20.44253Z","end":"2026-10-02T09:51:20.720254Z","steps":["trace[1112432522] 'read index received' (duration: 277.65127ms)","trace[1112432522] 'applied index is now lower than readState.Index' (duration: 71.692µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:51:20.72042Z","caller":"traceutil/trace.go:171","msg":"trace[1512394545] transaction","detail":"{read_only:false; response_revision:18091; number_of_response:1; }","duration":"285.720844ms","start":"2026-10-02T09:51:20.434687Z","end":"2026-10-02T09:51:20.720408Z","steps":["trace[1512394545] 'process raft request' (duration: 285.421698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:51:20.721089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.534366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T09:51:20.721136Z","caller":"traceutil/trace.go:171","msg":"trace[362683432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18091; }","duration":"278.618657ms","start":"2026-10-02T09:51:20.442505Z","end":"2026-10-02T09:51:20.721124Z","steps":["trace[362683432] 'agreement among raft nodes before linearized reading' (duration: 278.058448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:51:20.735745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.588603ms","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-10-02T09:51:20.735799Z","caller":"traceutil/trace.go:171","msg":"trace[767064702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18092; }","duration":"282.685026ms","start":"2026-10-02T09:51:20.453101Z","end":"2026-10-02T09:51:20.735786Z","steps":["trace[767064702] 'agreement among raft nodes before linearized reading' (duration: 282.543443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:51:20.736173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.73004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-02T09:51:20.736233Z","caller":"traceutil/trace.go:171","msg":"trace[1306606987] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:18092; }","duration":"116.808301ms","start":"2026-10-02T09:51:20.619396Z","end":"2026-10-02T09:51:20.736204Z","steps":["trace[1306606987] 'agreement among raft nodes before linearized reading' (duration: 116.707879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:51:20.736415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.091349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-02T09:51:20.73645Z","caller":"traceutil/trace.go:171","msg":"trace[1718022927] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18092; }","duration":"248.147779ms","start":"2026-10-02T09:51:20.488293Z","end":"2026-10-02T09:51:20.736441Z","steps":["trace[1718022927] 'agreement among raft nodes before linearized reading' (duration: 248.063468ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:51:41.246108Z","caller":"traceutil/trace.go:171","msg":"trace[1666282826] transaction","detail":"{read_only:false; response_revision:18443; number_of_response:1; }","duration":"130.639404ms","start":"2026-10-02T09:51:41.115425Z","end":"2026-10-02T09:51:41.246065Z","steps":["trace[1666282826] 'process raft request' (duration: 103.258434ms)","trace[1666282826] 'compare' (duration: 26.872841ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:53:49.001944Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T09:53:49.009804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-02T09:53:49.009953Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-02T09:55:23.094474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17603} {"level":"info","ts":"2026-10-02T09:55:23.198378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17603,"took":"99.331856ms","hash":3244866557,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":34947072,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-02T09:55:23.198452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3244866557,"revision":17603,"compact-revision":15388} {"level":"warn","ts":"2026-10-02T09:56:56.964193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.985371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T09:56:56.964516Z","caller":"traceutil/trace.go:171","msg":"trace[910519177] linearizableReadLoop","detail":"{readStateIndex:21326; appliedIndex:21323; }","duration":"122.313034ms","start":"2026-10-02T09:56:56.842181Z","end":"2026-10-02T09:56:56.964494Z","steps":["trace[910519177] 'read index received' (duration: 120.434437ms)","trace[910519177] 'applied index is now lower than readState.Index' (duration: 1.874867ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:56:56.964572Z","caller":"traceutil/trace.go:171","msg":"trace[1467858551] transaction","detail":"{read_only:false; response_revision:20631; number_of_response:1; }","duration":"221.443784ms","start":"2026-10-02T09:56:56.743101Z","end":"2026-10-02T09:56:56.964544Z","steps":["trace[1467858551] 'process raft request' (duration: 92.982835ms)","trace[1467858551] 'compare' (duration: 127.624804ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:56:56.96475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.547257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T09:56:56.964568Z","caller":"traceutil/trace.go:171","msg":"trace[1915029051] transaction","detail":"{read_only:false; response_revision:20632; number_of_response:1; }","duration":"191.780841ms","start":"2026-10-02T09:56:56.772748Z","end":"2026-10-02T09:56:56.964529Z","steps":["trace[1915029051] 'process raft request' (duration: 191.578919ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:56:56.964813Z","caller":"traceutil/trace.go:171","msg":"trace[5856094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20633; }","duration":"122.67048ms","start":"2026-10-02T09:56:56.842128Z","end":"2026-10-02T09:56:56.964799Z","steps":["trace[5856094] 'agreement among raft nodes before linearized reading' (duration: 122.437655ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:56:56.964586Z","caller":"traceutil/trace.go:171","msg":"trace[7964412] transaction","detail":"{read_only:false; response_revision:20633; number_of_response:1; }","duration":"160.6095ms","start":"2026-10-02T09:56:56.803939Z","end":"2026-10-02T09:56:56.964549Z","steps":["trace[7964412] 'process raft request' (duration: 160.475858ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:56:57.256046Z","caller":"traceutil/trace.go:171","msg":"trace[1885031668] transaction","detail":"{read_only:false; response_revision:20635; number_of_response:1; }","duration":"217.579101ms","start":"2026-10-02T09:56:57.038409Z","end":"2026-10-02T09:56:57.255988Z","steps":["trace[1885031668] 'process raft request' (duration: 217.426798ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:56:57.269587Z","caller":"traceutil/trace.go:171","msg":"trace[1267930539] transaction","detail":"{read_only:false; response_revision:20636; number_of_response:1; }","duration":"200.734343ms","start":"2026-10-02T09:56:57.068817Z","end":"2026-10-02T09:56:57.269552Z","steps":["trace[1267930539] 'process raft request' (duration: 200.59426ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:00:23.10302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20123} {"level":"info","ts":"2026-10-02T10:00:23.220401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20123,"took":"113.842741ms","hash":3042725451,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:00:23.220474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3042725451,"revision":20123,"compact-revision":17603} {"level":"info","ts":"2026-10-02T10:00:54.375514Z","caller":"traceutil/trace.go:171","msg":"trace[36237895] linearizableReadLoop","detail":"{readStateIndex:23926; appliedIndex:23925; }","duration":"130.047117ms","start":"2026-10-02T10:00:54.245407Z","end":"2026-10-02T10:00:54.375454Z","steps":["trace[36237895] 'read index received' (duration: 112.83255ms)","trace[36237895] 'applied index is now lower than readState.Index' (duration: 17.213407ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:00:54.375798Z","caller":"traceutil/trace.go:171","msg":"trace[428766521] transaction","detail":"{read_only:false; response_revision:23161; number_of_response:1; }","duration":"131.948734ms","start":"2026-10-02T10:00:54.243792Z","end":"2026-10-02T10:00:54.375741Z","steps":["trace[428766521] 'process raft request' (duration: 114.527953ms)","trace[428766521] 'compare' (duration: 17.002702ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:00:54.375861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.417854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T10:00:54.376354Z","caller":"traceutil/trace.go:171","msg":"trace[724386720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23161; }","duration":"130.951215ms","start":"2026-10-02T10:00:54.245382Z","end":"2026-10-02T10:00:54.376333Z","steps":["trace[724386720] 'agreement among raft nodes before linearized reading' (duration: 130.295762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:54.681467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.28388ms","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-10-02T10:00:54.681553Z","caller":"traceutil/trace.go:171","msg":"trace[904054513] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23162; }","duration":"103.412643ms","start":"2026-10-02T10:00:54.578116Z","end":"2026-10-02T10:00:54.681529Z","steps":["trace[904054513] 'range keys from in-memory index tree' (duration: 103.057216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:54.681582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.408655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-02T10:00:54.68168Z","caller":"traceutil/trace.go:171","msg":"trace[1920252885] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23162; }","duration":"178.575027ms","start":"2026-10-02T10:00:54.503086Z","end":"2026-10-02T10:00:54.681661Z","steps":["trace[1920252885] 'range keys from in-memory index tree' (duration: 178.182301ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:29.19499Z","caller":"traceutil/trace.go:171","msg":"trace[1065000901] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"251.353548ms","start":"2026-10-02T10:04:28.943597Z","end":"2026-10-02T10:04:29.19495Z","steps":["trace[1065000901] 'process raft request' (duration: 194.03824ms)","trace[1065000901] 'compare' (duration: 56.955301ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:29.195036Z","caller":"traceutil/trace.go:171","msg":"trace[1703265808] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"248.678387ms","start":"2026-10-02T10:04:28.946319Z","end":"2026-10-02T10:04:29.194997Z","steps":["trace[1703265808] 'process raft request' (duration: 248.537244ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:29.195249Z","caller":"traceutil/trace.go:171","msg":"trace[217423657] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"179.362852ms","start":"2026-10-02T10:04:29.015875Z","end":"2026-10-02T10:04:29.195238Z","steps":["trace[217423657] 'process raft request' (duration: 179.071337ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:29.403969Z","caller":"traceutil/trace.go:171","msg":"trace[2066199659] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"143.111064ms","start":"2026-10-02T10:04:29.260829Z","end":"2026-10-02T10:04:29.40394Z","steps":["trace[2066199659] 'process raft request' (duration: 49.187063ms)","trace[2066199659] 'compare' (duration: 93.656316ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:29.791136Z","caller":"traceutil/trace.go:171","msg":"trace[2037293657] linearizableReadLoop","detail":"{readStateIndex:26126; appliedIndex:26125; }","duration":"291.257304ms","start":"2026-10-02T10:04:29.499851Z","end":"2026-10-02T10:04:29.791109Z","steps":["trace[2037293657] 'read index received' (duration: 291.135962ms)","trace[2037293657] 'applied index is now lower than readState.Index' (duration: 120.442µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:29.791189Z","caller":"traceutil/trace.go:171","msg":"trace[1641280996] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"293.314423ms","start":"2026-10-02T10:04:29.497847Z","end":"2026-10-02T10:04:29.791161Z","steps":["trace[1641280996] 'process raft request' (duration: 293.095939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:29.791382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.507999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T10:04:29.791451Z","caller":"traceutil/trace.go:171","msg":"trace[1625674685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25305; }","duration":"291.615191ms","start":"2026-10-02T10:04:29.499825Z","end":"2026-10-02T10:04:29.791441Z","steps":["trace[1625674685] 'agreement among raft nodes before linearized reading' (duration: 291.408177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:30.132709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.57617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:30.132917Z","caller":"traceutil/trace.go:171","msg":"trace[1236662540] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"336.926002ms","start":"2026-10-02T10:04:29.795973Z","end":"2026-10-02T10:04:30.132899Z","steps":["trace[1236662540] 'process raft request' (duration: 336.85312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:30.132986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:29.795947Z","time spent":"337.008683ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:30.132979Z","caller":"traceutil/trace.go:171","msg":"trace[167562820] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"359.075491ms","start":"2026-10-02T10:04:29.773846Z","end":"2026-10-02T10:04:30.132922Z","steps":["trace[167562820] 'process raft request' (duration: 86.199695ms)","trace[167562820] 'compare' (duration: 272.444578ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:30.133193Z","caller":"traceutil/trace.go:171","msg":"trace[860500344] linearizableReadLoop","detail":"{readStateIndex:26127; appliedIndex:26126; }","duration":"341.972787ms","start":"2026-10-02T10:04:29.79121Z","end":"2026-10-02T10:04:30.133183Z","steps":["trace[860500344] 'read index received' (duration: 68.862656ms)","trace[860500344] 'applied index is now lower than readState.Index' (duration: 273.109131ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:30.133435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.905892ms","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-10-02T10:04:30.133472Z","caller":"traceutil/trace.go:171","msg":"trace[304411954] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25307; }","duration":"258.979883ms","start":"2026-10-02T10:04:29.874483Z","end":"2026-10-02T10:04:30.133463Z","steps":["trace[304411954] 'agreement among raft nodes before linearized reading' (duration: 258.86212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:30.133543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.396264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-10-02T10:04:30.133532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.049635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-02T10:04:30.133602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:29.77382Z","time spent":"359.226215ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:30.13362Z","caller":"traceutil/trace.go:171","msg":"trace[1320157353] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25307; }","duration":"411.528576ms","start":"2026-10-02T10:04:29.72207Z","end":"2026-10-02T10:04:30.133598Z","steps":["trace[1320157353] 'agreement among raft nodes before linearized reading' (duration: 411.302742ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:30.133633Z","caller":"traceutil/trace.go:171","msg":"trace[1631080228] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25307; }","duration":"481.200648ms","start":"2026-10-02T10:04:29.652409Z","end":"2026-10-02T10:04:30.13361Z","steps":["trace[1631080228] 'agreement among raft nodes before linearized reading' (duration: 480.908463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:30.133643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.334002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-02T10:04:30.133665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:29.722046Z","time spent":"411.612837ms","remote":"127.0.0.1:57660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-02T10:04:30.133678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:29.652386Z","time spent":"481.28321ms","remote":"127.0.0.1:57380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-02T10:04:30.133701Z","caller":"traceutil/trace.go:171","msg":"trace[1928383917] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25307; }","duration":"131.459314ms","start":"2026-10-02T10:04:30.002228Z","end":"2026-10-02T10:04:30.133688Z","steps":["trace[1928383917] 'agreement among raft nodes before linearized reading' (duration: 131.217869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:34.800993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.214068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:34.801096Z","caller":"traceutil/trace.go:171","msg":"trace[1553674004] linearizableReadLoop","detail":"{readStateIndex:26159; appliedIndex:26158; }","duration":"151.485584ms","start":"2026-10-02T10:04:34.649594Z","end":"2026-10-02T10:04:34.80108Z","steps":["trace[1553674004] 'read index received' (duration: 49.120283ms)","trace[1553674004] 'applied index is now lower than readState.Index' (duration: 102.364081ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:34.801166Z","caller":"traceutil/trace.go:171","msg":"trace[331088418] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"258.528873ms","start":"2026-10-02T10:04:34.542624Z","end":"2026-10-02T10:04:34.801153Z","steps":["trace[331088418] 'process raft request' (duration: 156.07402ms)","trace[331088418] 'compare' (duration: 102.065956ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:34.801313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.704178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-02T10:04:34.801415Z","caller":"traceutil/trace.go:171","msg":"trace[306373569] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:25337; }","duration":"151.857871ms","start":"2026-10-02T10:04:34.649531Z","end":"2026-10-02T10:04:34.801389Z","steps":["trace[306373569] 'agreement among raft nodes before linearized reading' (duration: 151.630876ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:34.809408Z","caller":"traceutil/trace.go:171","msg":"trace[1003900316] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"115.963429ms","start":"2026-10-02T10:04:34.693428Z","end":"2026-10-02T10:04:34.809391Z","steps":["trace[1003900316] 'process raft request' (duration: 115.856487ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.015943Z","caller":"traceutil/trace.go:171","msg":"trace[563762590] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"108.133222ms","start":"2026-10-02T10:04:34.90779Z","end":"2026-10-02T10:04:35.015923Z","steps":["trace[563762590] 'process raft request' (duration: 108.090451ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.016076Z","caller":"traceutil/trace.go:171","msg":"trace[669878855] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"300.468339ms","start":"2026-10-02T10:04:34.7156Z","end":"2026-10-02T10:04:35.016068Z","steps":["trace[669878855] 'process raft request' (duration: 218.720109ms)","trace[669878855] 'compare' (duration: 81.331952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:35.016156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:34.715575Z","time spent":"300.51559ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:35.016291Z","caller":"traceutil/trace.go:171","msg":"trace[1162618187] linearizableReadLoop","detail":"{readStateIndex:26161; appliedIndex:26159; }","duration":"215.14889ms","start":"2026-10-02T10:04:34.801119Z","end":"2026-10-02T10:04:35.016268Z","steps":["trace[1162618187] 'read index received' (duration: 8.186825ms)","trace[1162618187] 'applied index is now lower than readState.Index' (duration: 206.960625ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:35.016436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.131132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-02T10:04:35.01645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.008499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:04:35.01647Z","caller":"traceutil/trace.go:171","msg":"trace[1321741468] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:25340; }","duration":"261.194703ms","start":"2026-10-02T10:04:34.755267Z","end":"2026-10-02T10:04:35.016461Z","steps":["trace[1321741468] 'agreement among raft nodes before linearized reading' (duration: 261.114411ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.016487Z","caller":"traceutil/trace.go:171","msg":"trace[678523302] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25340; }","duration":"282.09313ms","start":"2026-10-02T10:04:34.73438Z","end":"2026-10-02T10:04:35.016473Z","steps":["trace[678523302] 'agreement among raft nodes before linearized reading' (duration: 282.011919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.016767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.93775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ae1d5b4f-cfb1-4b00-a7ff-000417c1d492\" ","response":"range_response_count:1 size:1284"} {"level":"warn","ts":"2026-10-02T10:04:35.016809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.561985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:04:35.01684Z","caller":"traceutil/trace.go:171","msg":"trace[1153688541] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25340; }","duration":"141.632446ms","start":"2026-10-02T10:04:34.875201Z","end":"2026-10-02T10:04:35.016833Z","steps":["trace[1153688541] 'agreement among raft nodes before linearized reading' (duration: 141.562505ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.01684Z","caller":"traceutil/trace.go:171","msg":"trace[625890175] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ae1d5b4f-cfb1-4b00-a7ff-000417c1d492; range_end:; response_count:1; response_revision:25340; }","duration":"212.062222ms","start":"2026-10-02T10:04:34.804767Z","end":"2026-10-02T10:04:35.016829Z","steps":["trace[625890175] 'agreement among raft nodes before linearized reading' (duration: 211.910059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.017801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.990445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T10:04:35.01788Z","caller":"traceutil/trace.go:171","msg":"trace[1557536079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25340; }","duration":"198.134948ms","start":"2026-10-02T10:04:34.819728Z","end":"2026-10-02T10:04:35.017863Z","steps":["trace[1557536079] 'agreement among raft nodes before linearized reading' (duration: 197.21589ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.405296Z","caller":"traceutil/trace.go:171","msg":"trace[608483712] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"265.184861ms","start":"2026-10-02T10:04:35.14008Z","end":"2026-10-02T10:04:35.405265Z","steps":["trace[608483712] 'process raft request' (duration: 264.988997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.624055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.036398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:35.624263Z","caller":"traceutil/trace.go:171","msg":"trace[1513860180] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"197.529156ms","start":"2026-10-02T10:04:35.426716Z","end":"2026-10-02T10:04:35.624245Z","steps":["trace[1513860180] 'process raft request' (duration: 197.489276ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.624375Z","caller":"traceutil/trace.go:171","msg":"trace[866377999] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"406.988619ms","start":"2026-10-02T10:04:35.217378Z","end":"2026-10-02T10:04:35.624367Z","steps":["trace[866377999] 'process raft request' (duration: 406.765414ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.624394Z","caller":"traceutil/trace.go:171","msg":"trace[614526453] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"409.185252ms","start":"2026-10-02T10:04:35.215179Z","end":"2026-10-02T10:04:35.624364Z","steps":["trace[614526453] 'process raft request' (duration: 299.761306ms)","trace[614526453] 'compare' (duration: 108.913676ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:35.624424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:35.217348Z","time spent":"407.04353ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:35.624528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:35.215154Z","time spent":"409.300614ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:35.624535Z","caller":"traceutil/trace.go:171","msg":"trace[1649003523] linearizableReadLoop","detail":"{readStateIndex:26166; appliedIndex:26163; }","duration":"403.988812ms","start":"2026-10-02T10:04:35.220508Z","end":"2026-10-02T10:04:35.624497Z","steps":["trace[1649003523] 'read index received' (duration: 184.475519ms)","trace[1649003523] 'applied index is now lower than readState.Index' (duration: 219.499793ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:35.624704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.192856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T10:04:35.624737Z","caller":"traceutil/trace.go:171","msg":"trace[1936906082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25345; }","duration":"404.249397ms","start":"2026-10-02T10:04:35.220477Z","end":"2026-10-02T10:04:35.624726Z","steps":["trace[1936906082] 'agreement among raft nodes before linearized reading' (duration: 404.137665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.624758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.735859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-02T10:04:35.624766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:35.220464Z","time spent":"404.295457ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T10:04:35.624794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.661286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T10:04:35.624813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.706873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-02T10:04:35.624846Z","caller":"traceutil/trace.go:171","msg":"trace[64104696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25345; }","duration":"271.784375ms","start":"2026-10-02T10:04:35.353052Z","end":"2026-10-02T10:04:35.624836Z","steps":["trace[64104696] 'agreement among raft nodes before linearized reading' (duration: 271.606261ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:35.624846Z","caller":"traceutil/trace.go:171","msg":"trace[2116540327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25345; }","duration":"249.749738ms","start":"2026-10-02T10:04:35.375083Z","end":"2026-10-02T10:04:35.624833Z","steps":["trace[2116540327] 'agreement among raft nodes before linearized reading' (duration: 249.627105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.624861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.410712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:35.62489Z","caller":"traceutil/trace.go:171","msg":"trace[384768670] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25345; }","duration":"345.491053ms","start":"2026-10-02T10:04:35.279389Z","end":"2026-10-02T10:04:35.62488Z","steps":["trace[384768670] 'agreement among raft nodes before linearized reading' (duration: 345.441852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.624917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:35.279367Z","time spent":"345.541853ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"info","ts":"2026-10-02T10:04:35.624812Z","caller":"traceutil/trace.go:171","msg":"trace[465769583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25345; }","duration":"402.8336ms","start":"2026-10-02T10:04:35.221969Z","end":"2026-10-02T10:04:35.624802Z","steps":["trace[465769583] 'agreement among raft nodes before linearized reading' (duration: 402.680407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.624973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:35.221949Z","time spent":"403.011264ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-02T10:04:35.94934Z","caller":"traceutil/trace.go:171","msg":"trace[385511555] linearizableReadLoop","detail":"{readStateIndex:26169; appliedIndex:26168; }","duration":"231.318478ms","start":"2026-10-02T10:04:35.718005Z","end":"2026-10-02T10:04:35.949323Z","steps":["trace[385511555] 'read index received' (duration: 231.248797ms)","trace[385511555] 'applied index is now lower than readState.Index' (duration: 69.041µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:35.949424Z","caller":"traceutil/trace.go:171","msg":"trace[2142471373] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"303.264283ms","start":"2026-10-02T10:04:35.646152Z","end":"2026-10-02T10:04:35.949416Z","steps":["trace[2142471373] 'process raft request' (duration: 303.078319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.949481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:35.646132Z","time spent":"303.304442ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:35.949568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.564153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:35.949582Z","caller":"traceutil/trace.go:171","msg":"trace[1062339112] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25347; }","duration":"231.609463ms","start":"2026-10-02T10:04:35.717968Z","end":"2026-10-02T10:04:35.949578Z","steps":["trace[1062339112] 'agreement among raft nodes before linearized reading' (duration: 231.571462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:35.949724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.8413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T10:04:35.949739Z","caller":"traceutil/trace.go:171","msg":"trace[1875722873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25347; }","duration":"123.88305ms","start":"2026-10-02T10:04:35.825851Z","end":"2026-10-02T10:04:35.949734Z","steps":["trace[1875722873] 'agreement among raft nodes before linearized reading' (duration: 123.816949ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:36.079348Z","caller":"traceutil/trace.go:171","msg":"trace[678615532] linearizableReadLoop","detail":"{readStateIndex:26170; appliedIndex:26169; }","duration":"122.937102ms","start":"2026-10-02T10:04:35.956393Z","end":"2026-10-02T10:04:36.07933Z","steps":["trace[678615532] 'read index received' (duration: 122.30455ms)","trace[678615532] 'applied index is now lower than readState.Index' (duration: 631.962µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:36.079487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.070564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-02T10:04:36.079504Z","caller":"traceutil/trace.go:171","msg":"trace[728232501] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25348; }","duration":"123.152265ms","start":"2026-10-02T10:04:35.956347Z","end":"2026-10-02T10:04:36.079499Z","steps":["trace[728232501] 'agreement among raft nodes before linearized reading' (duration: 123.043853ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:36.079518Z","caller":"traceutil/trace.go:171","msg":"trace[2026535636] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"124.492721ms","start":"2026-10-02T10:04:35.954968Z","end":"2026-10-02T10:04:36.079461Z","steps":["trace[2026535636] 'process raft request' (duration: 123.823469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:36.380722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.295538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:36.38089Z","caller":"traceutil/trace.go:171","msg":"trace[795549439] linearizableReadLoop","detail":"{readStateIndex:26171; appliedIndex:26170; }","duration":"188.577307ms","start":"2026-10-02T10:04:36.192291Z","end":"2026-10-02T10:04:36.380869Z","steps":["trace[795549439] 'read index received' (duration: 53.985334ms)","trace[795549439] 'applied index is now lower than readState.Index' (duration: 134.589953ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:36.380938Z","caller":"traceutil/trace.go:171","msg":"trace[1854486986] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"216.423965ms","start":"2026-10-02T10:04:36.164484Z","end":"2026-10-02T10:04:36.380908Z","steps":["trace[1854486986] 'process raft request' (duration: 81.840692ms)","trace[1854486986] 'compare' (duration: 133.724817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:36.3811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.806432ms","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-10-02T10:04:36.381143Z","caller":"traceutil/trace.go:171","msg":"trace[1781088789] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25349; }","duration":"188.897953ms","start":"2026-10-02T10:04:36.192233Z","end":"2026-10-02T10:04:36.381131Z","steps":["trace[1781088789] 'agreement among raft nodes before linearized reading' (duration: 188.753011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:36.661733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.960079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:36.661922Z","caller":"traceutil/trace.go:171","msg":"trace[856710748] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"273.006889ms","start":"2026-10-02T10:04:36.388902Z","end":"2026-10-02T10:04:36.661909Z","steps":["trace[856710748] 'process raft request' (duration: 272.931367ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:36.662025Z","caller":"traceutil/trace.go:171","msg":"trace[1924142438] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"274.169521ms","start":"2026-10-02T10:04:36.387847Z","end":"2026-10-02T10:04:36.662017Z","steps":["trace[1924142438] 'process raft request' (duration: 117.848695ms)","trace[1924142438] 'compare' (duration: 155.803476ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:36.990933Z","caller":"traceutil/trace.go:171","msg":"trace[117271737] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"184.946058ms","start":"2026-10-02T10:04:36.805955Z","end":"2026-10-02T10:04:36.990901Z","steps":["trace[117271737] 'process raft request' (duration: 184.728364ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:37.026242Z","caller":"traceutil/trace.go:171","msg":"trace[736374873] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"189.326051ms","start":"2026-10-02T10:04:36.836879Z","end":"2026-10-02T10:04:37.026205Z","steps":["trace[736374873] 'process raft request' (duration: 189.25466ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:37.026533Z","caller":"traceutil/trace.go:171","msg":"trace[1667616028] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"212.920769ms","start":"2026-10-02T10:04:36.81358Z","end":"2026-10-02T10:04:37.026501Z","steps":["trace[1667616028] 'process raft request' (duration: 212.41006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:37.285388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.172225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:37.285823Z","caller":"traceutil/trace.go:171","msg":"trace[222551670] linearizableReadLoop","detail":"{readStateIndex:26181; appliedIndex:26178; }","duration":"205.831135ms","start":"2026-10-02T10:04:37.079907Z","end":"2026-10-02T10:04:37.285738Z","steps":["trace[222551670] 'read index received' (duration: 50.09221ms)","trace[222551670] 'applied index is now lower than readState.Index' (duration: 155.713525ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:37.285847Z","caller":"traceutil/trace.go:171","msg":"trace[1221260544] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"290.844667ms","start":"2026-10-02T10:04:36.994952Z","end":"2026-10-02T10:04:37.285797Z","steps":["trace[1221260544] 'process raft request' (duration: 135.140553ms)","trace[1221260544] 'compare' (duration: 154.019592ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:37.286043Z","caller":"traceutil/trace.go:171","msg":"trace[1649304221] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"264.438656ms","start":"2026-10-02T10:04:37.021568Z","end":"2026-10-02T10:04:37.286006Z","steps":["trace[1649304221] 'process raft request' (duration: 263.951097ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:37.286219Z","caller":"traceutil/trace.go:171","msg":"trace[1402014151] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"263.221263ms","start":"2026-10-02T10:04:37.022854Z","end":"2026-10-02T10:04:37.286076Z","steps":["trace[1402014151] 'process raft request' (duration: 262.794135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:37.286645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.713282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T10:04:37.286749Z","caller":"traceutil/trace.go:171","msg":"trace[362023154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25358; }","duration":"206.864303ms","start":"2026-10-02T10:04:37.079859Z","end":"2026-10-02T10:04:37.286723Z","steps":["trace[362023154] 'agreement among raft nodes before linearized reading' (duration: 205.987817ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:37.457183Z","caller":"traceutil/trace.go:171","msg":"trace[2008314335] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"162.953182ms","start":"2026-10-02T10:04:37.294186Z","end":"2026-10-02T10:04:37.457139Z","steps":["trace[2008314335] 'process raft request' (duration: 117.751994ms)","trace[2008314335] 'compare' (duration: 44.868591ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:37.743379Z","caller":"traceutil/trace.go:171","msg":"trace[889805571] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"113.88508ms","start":"2026-10-02T10:04:37.629461Z","end":"2026-10-02T10:04:37.743346Z","steps":["trace[889805571] 'process raft request' (duration: 113.629125ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:37.892874Z","caller":"traceutil/trace.go:171","msg":"trace[444600720] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"263.240264ms","start":"2026-10-02T10:04:37.629609Z","end":"2026-10-02T10:04:37.892849Z","steps":["trace[444600720] 'process raft request' (duration: 245.433935ms)","trace[444600720] 'compare' (duration: 17.577594ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:37.893079Z","caller":"traceutil/trace.go:171","msg":"trace[1489143866] linearizableReadLoop","detail":"{readStateIndex:26185; appliedIndex:26182; }","duration":"249.995192ms","start":"2026-10-02T10:04:37.64304Z","end":"2026-10-02T10:04:37.893035Z","steps":["trace[1489143866] 'read index received' (duration: 100.014757ms)","trace[1489143866] 'applied index is now lower than readState.Index' (duration: 149.977085ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:37.893134Z","caller":"traceutil/trace.go:171","msg":"trace[2101302785] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"263.119941ms","start":"2026-10-02T10:04:37.630003Z","end":"2026-10-02T10:04:37.893123Z","steps":["trace[2101302785] 'process raft request' (duration: 262.799495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:37.893283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.406163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:37.893321Z","caller":"traceutil/trace.go:171","msg":"trace[1331172001] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25362; }","duration":"237.501244ms","start":"2026-10-02T10:04:37.655807Z","end":"2026-10-02T10:04:37.893308Z","steps":["trace[1331172001] 'agreement among raft nodes before linearized reading' (duration: 237.408673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:37.893375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.323138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T10:04:37.893451Z","caller":"traceutil/trace.go:171","msg":"trace[2063452683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25362; }","duration":"250.448201ms","start":"2026-10-02T10:04:37.642982Z","end":"2026-10-02T10:04:37.893431Z","steps":["trace[2063452683] 'agreement among raft nodes before linearized reading' (duration: 250.211416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:37.893381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.942395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-02T10:04:37.893829Z","caller":"traceutil/trace.go:171","msg":"trace[1891887319] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25362; }","duration":"110.440515ms","start":"2026-10-02T10:04:37.783373Z","end":"2026-10-02T10:04:37.893813Z","steps":["trace[1891887319] 'agreement among raft nodes before linearized reading' (duration: 109.860474ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:38.195846Z","caller":"traceutil/trace.go:171","msg":"trace[386099536] linearizableReadLoop","detail":"{readStateIndex:26187; appliedIndex:26186; }","duration":"112.068367ms","start":"2026-10-02T10:04:38.083748Z","end":"2026-10-02T10:04:38.195816Z","steps":["trace[386099536] 'read index received' (duration: 92.552796ms)","trace[386099536] 'applied index is now lower than readState.Index' (duration: 19.514561ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:38.19592Z","caller":"traceutil/trace.go:171","msg":"trace[2119863826] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"236.266102ms","start":"2026-10-02T10:04:37.959618Z","end":"2026-10-02T10:04:38.195884Z","steps":["trace[2119863826] 'process raft request' (duration: 216.739311ms)","trace[2119863826] 'compare' (duration: 19.284926ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:38.196076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.310341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T10:04:38.196119Z","caller":"traceutil/trace.go:171","msg":"trace[764164868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25364; }","duration":"112.399682ms","start":"2026-10-02T10:04:38.083706Z","end":"2026-10-02T10:04:38.196106Z","steps":["trace[764164868] 'agreement among raft nodes before linearized reading' (duration: 112.252969ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:38.339797Z","caller":"traceutil/trace.go:171","msg":"trace[1830017500] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"138.953855ms","start":"2026-10-02T10:04:38.200797Z","end":"2026-10-02T10:04:38.339751Z","steps":["trace[1830017500] 'process raft request' (duration: 135.111973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:38.670892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.608826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:38.671168Z","caller":"traceutil/trace.go:171","msg":"trace[2105646552] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"279.254817ms","start":"2026-10-02T10:04:38.391879Z","end":"2026-10-02T10:04:38.671133Z","steps":["trace[2105646552] 'process raft request' (duration: 146.340925ms)","trace[2105646552] 'compare' (duration: 132.503094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:39.061269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.642467ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:39.061384Z","caller":"traceutil/trace.go:171","msg":"trace[1341175352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25367; }","duration":"170.76842ms","start":"2026-10-02T10:04:38.890599Z","end":"2026-10-02T10:04:39.061367Z","steps":["trace[1341175352] 'range keys from in-memory index tree' (duration: 170.628226ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:39.121089Z","caller":"traceutil/trace.go:171","msg":"trace[1493923328] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"125.172024ms","start":"2026-10-02T10:04:38.995886Z","end":"2026-10-02T10:04:39.121058Z","steps":["trace[1493923328] 'process raft request' (duration: 124.988711ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:39.32579Z","caller":"traceutil/trace.go:171","msg":"trace[36831557] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"293.475717ms","start":"2026-10-02T10:04:39.032282Z","end":"2026-10-02T10:04:39.325757Z","steps":["trace[36831557] 'process raft request' (duration: 205.608731ms)","trace[36831557] 'compare' (duration: 87.731934ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:39.331851Z","caller":"traceutil/trace.go:171","msg":"trace[850354503] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"100.488786ms","start":"2026-10-02T10:04:39.231336Z","end":"2026-10-02T10:04:39.331825Z","steps":["trace[850354503] 'process raft request' (duration: 100.323053ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:39.497215Z","caller":"traceutil/trace.go:171","msg":"trace[1966890466] linearizableReadLoop","detail":"{readStateIndex:26195; appliedIndex:26193; }","duration":"123.442531ms","start":"2026-10-02T10:04:39.373749Z","end":"2026-10-02T10:04:39.497192Z","steps":["trace[1966890466] 'read index received' (duration: 100.495866ms)","trace[1966890466] 'applied index is now lower than readState.Index' (duration: 22.945745ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:39.497251Z","caller":"traceutil/trace.go:171","msg":"trace[1259734508] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"202.355999ms","start":"2026-10-02T10:04:39.294865Z","end":"2026-10-02T10:04:39.497221Z","steps":["trace[1259734508] 'process raft request' (duration: 179.399063ms)","trace[1259734508] 'compare' (duration: 22.620349ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:39.497347Z","caller":"traceutil/trace.go:171","msg":"trace[2047960831] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"201.941891ms","start":"2026-10-02T10:04:39.295394Z","end":"2026-10-02T10:04:39.497335Z","steps":["trace[2047960831] 'process raft request' (duration: 201.683816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:39.497532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.781168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:39.497564Z","caller":"traceutil/trace.go:171","msg":"trace[1413569271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25372; }","duration":"123.86433ms","start":"2026-10-02T10:04:39.373689Z","end":"2026-10-02T10:04:39.497553Z","steps":["trace[1413569271] 'agreement among raft nodes before linearized reading' (duration: 123.794879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:39.831284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.916832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:39.831368Z","caller":"traceutil/trace.go:171","msg":"trace[1922317529] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25373; }","duration":"181.050185ms","start":"2026-10-02T10:04:39.650298Z","end":"2026-10-02T10:04:39.831348Z","steps":["trace[1922317529] 'count revisions from in-memory index tree' (duration: 180.79273ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:39.894104Z","caller":"traceutil/trace.go:171","msg":"trace[130165738] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"145.382878ms","start":"2026-10-02T10:04:39.74869Z","end":"2026-10-02T10:04:39.894073Z","steps":["trace[130165738] 'process raft request' (duration: 145.191904ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.181381Z","caller":"traceutil/trace.go:171","msg":"trace[1974445373] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"280.231035ms","start":"2026-10-02T10:04:39.901119Z","end":"2026-10-02T10:04:40.18135Z","steps":["trace[1974445373] 'process raft request' (duration: 241.431629ms)","trace[1974445373] 'compare' (duration: 38.427659ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:40.215127Z","caller":"traceutil/trace.go:171","msg":"trace[544688451] linearizableReadLoop","detail":"{readStateIndex:26199; appliedIndex:26197; }","duration":"283.334264ms","start":"2026-10-02T10:04:39.931766Z","end":"2026-10-02T10:04:40.2151Z","steps":["trace[544688451] 'read index received' (duration: 210.799608ms)","trace[544688451] 'applied index is now lower than readState.Index' (duration: 72.532976ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:40.215371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.999436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:04:40.215419Z","caller":"traceutil/trace.go:171","msg":"trace[2037707731] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25376; }","duration":"159.105378ms","start":"2026-10-02T10:04:40.0563Z","end":"2026-10-02T10:04:40.215406Z","steps":["trace[2037707731] 'agreement among raft nodes before linearized reading' (duration: 158.976155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.215603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.809343ms","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-10-02T10:04:40.215752Z","caller":"traceutil/trace.go:171","msg":"trace[49658786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25376; }","duration":"283.964396ms","start":"2026-10-02T10:04:39.931759Z","end":"2026-10-02T10:04:40.215723Z","steps":["trace[49658786] 'agreement among raft nodes before linearized reading' (duration: 283.540738ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.215196Z","caller":"traceutil/trace.go:171","msg":"trace[1751606548] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"313.992955ms","start":"2026-10-02T10:04:39.901163Z","end":"2026-10-02T10:04:40.215156Z","steps":["trace[1751606548] 'process raft request' (duration: 313.789632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.21647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:39.901138Z","time spent":"315.007675ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:40.461215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.720698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:40.461436Z","caller":"traceutil/trace.go:171","msg":"trace[788258301] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"241.789086ms","start":"2026-10-02T10:04:40.219623Z","end":"2026-10-02T10:04:40.461412Z","steps":["trace[788258301] 'process raft request' (duration: 108.772413ms)","trace[788258301] 'compare' (duration: 132.332591ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:40.461581Z","caller":"traceutil/trace.go:171","msg":"trace[593300642] linearizableReadLoop","detail":"{readStateIndex:26201; appliedIndex:26199; }","duration":"118.369395ms","start":"2026-10-02T10:04:40.343192Z","end":"2026-10-02T10:04:40.461562Z","steps":["trace[593300642] 'read index received' (duration: 107.363546ms)","trace[593300642] 'applied index is now lower than readState.Index' (duration: 11.004769ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:40.461665Z","caller":"traceutil/trace.go:171","msg":"trace[1109773845] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"241.407548ms","start":"2026-10-02T10:04:40.220232Z","end":"2026-10-02T10:04:40.461639Z","steps":["trace[1109773845] 'process raft request' (duration: 241.106373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.462005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.796893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-02T10:04:40.462117Z","caller":"traceutil/trace.go:171","msg":"trace[652662517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25378; }","duration":"118.913005ms","start":"2026-10-02T10:04:40.343151Z","end":"2026-10-02T10:04:40.462064Z","steps":["trace[652662517] 'agreement among raft nodes before linearized reading' (duration: 118.491057ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.513112Z","caller":"traceutil/trace.go:171","msg":"trace[508924298] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"103.068375ms","start":"2026-10-02T10:04:40.409999Z","end":"2026-10-02T10:04:40.513067Z","steps":["trace[508924298] 'process raft request' (duration: 102.855861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.513154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.143989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:40.513249Z","caller":"traceutil/trace.go:171","msg":"trace[1189534992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25379; }","duration":"139.242211ms","start":"2026-10-02T10:04:40.373982Z","end":"2026-10-02T10:04:40.513225Z","steps":["trace[1189534992] 'agreement among raft nodes before linearized reading' (duration: 139.076038ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.801933Z","caller":"traceutil/trace.go:171","msg":"trace[1550904689] linearizableReadLoop","detail":"{readStateIndex:26203; appliedIndex:26202; }","duration":"288.87539ms","start":"2026-10-02T10:04:40.513032Z","end":"2026-10-02T10:04:40.801907Z","steps":["trace[1550904689] 'read index received' (duration: 288.577864ms)","trace[1550904689] 'applied index is now lower than readState.Index' (duration: 296.366µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:40.802197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.246182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-02T10:04:40.802231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.242149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T10:04:40.802208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.692727ms","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:6"} {"level":"info","ts":"2026-10-02T10:04:40.802255Z","caller":"traceutil/trace.go:171","msg":"trace[108264977] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25380; }","duration":"323.365894ms","start":"2026-10-02T10:04:40.478873Z","end":"2026-10-02T10:04:40.802239Z","steps":["trace[108264977] 'agreement among raft nodes before linearized reading' (duration: 323.14712ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.802266Z","caller":"traceutil/trace.go:171","msg":"trace[369556487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25380; }","duration":"287.461974ms","start":"2026-10-02T10:04:40.514794Z","end":"2026-10-02T10:04:40.802256Z","steps":["trace[369556487] 'agreement among raft nodes before linearized reading' (duration: 287.25489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.802291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:40.478849Z","time spent":"323.433956ms","remote":"127.0.0.1:57294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-10-02T10:04:40.8023Z","caller":"traceutil/trace.go:171","msg":"trace[2124465456] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25380; }","duration":"132.82167ms","start":"2026-10-02T10:04:40.669447Z","end":"2026-10-02T10:04:40.802268Z","steps":["trace[2124465456] 'agreement among raft nodes before linearized reading' (duration: 132.665327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.802307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.423036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419124"} {"level":"warn","ts":"2026-10-02T10:04:40.802278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.216543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T10:04:40.802349Z","caller":"traceutil/trace.go:171","msg":"trace[331007832] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25380; }","duration":"157.499418ms","start":"2026-10-02T10:04:40.644837Z","end":"2026-10-02T10:04:40.802336Z","steps":["trace[331007832] 'agreement among raft nodes before linearized reading' (duration: 157.322015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.802322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.614544ms","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-10-02T10:04:40.802365Z","caller":"traceutil/trace.go:171","msg":"trace[188586729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25380; }","duration":"127.348277ms","start":"2026-10-02T10:04:40.675002Z","end":"2026-10-02T10:04:40.802351Z","steps":["trace[188586729] 'agreement among raft nodes before linearized reading' (duration: 127.066971ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.802411Z","caller":"traceutil/trace.go:171","msg":"trace[1399716603] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25380; }","duration":"105.751016ms","start":"2026-10-02T10:04:40.696635Z","end":"2026-10-02T10:04:40.802386Z","steps":["trace[1399716603] 'agreement among raft nodes before linearized reading' (duration: 105.483551ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:40.802793Z","caller":"traceutil/trace.go:171","msg":"trace[1361614878] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"336.021514ms","start":"2026-10-02T10:04:40.465975Z","end":"2026-10-02T10:04:40.801996Z","steps":["trace[1361614878] 'process raft request' (duration: 335.708568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:40.802925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:40.465953Z","time spent":"336.887031ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:49.954731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.369602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:49.954877Z","caller":"traceutil/trace.go:171","msg":"trace[578885788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25447; }","duration":"111.566556ms","start":"2026-10-02T10:04:49.843289Z","end":"2026-10-02T10:04:49.954856Z","steps":["trace[578885788] 'count revisions from in-memory index tree' (duration: 111.27793ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:50.167619Z","caller":"traceutil/trace.go:171","msg":"trace[488077717] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"219.559335ms","start":"2026-10-02T10:04:49.948033Z","end":"2026-10-02T10:04:50.167593Z","steps":["trace[488077717] 'process raft request' (duration: 219.362501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:51.071325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.450591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:51.071451Z","caller":"traceutil/trace.go:171","msg":"trace[434811846] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"854.150221ms","start":"2026-10-02T10:04:50.21728Z","end":"2026-10-02T10:04:51.07143Z","steps":["trace[434811846] 'process raft request' (duration: 352.463845ms)","trace[434811846] 'compare' (duration: 500.961182ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:51.071542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.217255Z","time spent":"854.252563ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:51.886172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.775685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:04:51.886463Z","caller":"traceutil/trace.go:171","msg":"trace[3411555] linearizableReadLoop","detail":"{readStateIndex:26275; appliedIndex:26273; }","duration":"1.511810635s","start":"2026-10-02T10:04:50.37463Z","end":"2026-10-02T10:04:51.886441Z","steps":["trace[3411555] 'read index received' (duration: 195.190722ms)","trace[3411555] 'applied index is now lower than readState.Index' (duration: 1.316618663s)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:51.88656Z","caller":"traceutil/trace.go:171","msg":"trace[275991122] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"1.361519804s","start":"2026-10-02T10:04:50.525025Z","end":"2026-10-02T10:04:51.886545Z","steps":["trace[275991122] 'process raft request' (duration: 1.36128562s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:51.886638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.525008Z","time spent":"1.361584105s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:51.88667Z","caller":"traceutil/trace.go:171","msg":"trace[1181194861] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"1.614773228s","start":"2026-10-02T10:04:50.271865Z","end":"2026-10-02T10:04:51.886639Z","steps":["trace[1181194861] 'process raft request' (duration: 1.129422183s)","trace[1181194861] 'compare' (duration: 484.619202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:51.886833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.271843Z","time spent":"1.614889051s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:51.886937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512295166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:51.886971Z","caller":"traceutil/trace.go:171","msg":"trace[483476355] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"521.44629ms","start":"2026-10-02T10:04:51.365512Z","end":"2026-10-02T10:04:51.886958Z","steps":["trace[483476355] 'process raft request' (duration: 521.376759ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:51.886976Z","caller":"traceutil/trace.go:171","msg":"trace[53353607] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"721.986104ms","start":"2026-10-02T10:04:51.164976Z","end":"2026-10-02T10:04:51.886962Z","steps":["trace[53353607] 'process raft request' (duration: 721.423314ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:51.886999Z","caller":"traceutil/trace.go:171","msg":"trace[954561258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25452; }","duration":"1.512395087s","start":"2026-10-02T10:04:50.374584Z","end":"2026-10-02T10:04:51.886979Z","steps":["trace[954561258] 'agreement among raft nodes before linearized reading' (duration: 1.512244904s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:51.887032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.365494Z","time spent":"521.506561ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:51.887039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.374561Z","time spent":"1.512469229s","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T10:04:51.887045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.164947Z","time spent":"722.061676ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:52.386846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835595930443595236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T10:04:52.563002Z","caller":"traceutil/trace.go:171","msg":"trace[1391480312] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"1.007458469s","start":"2026-10-02T10:04:51.555511Z","end":"2026-10-02T10:04:52.56297Z","steps":["trace[1391480312] 'process raft request' (duration: 1.007327787s)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:52.563064Z","caller":"traceutil/trace.go:171","msg":"trace[649030041] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"1.017703014s","start":"2026-10-02T10:04:51.5453Z","end":"2026-10-02T10:04:52.563003Z","steps":["trace[649030041] 'process raft request' (duration: 990.710181ms)","trace[649030041] 'compare' (duration: 26.534174ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:52.563182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.545269Z","time spent":"1.017848367s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:52.56318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.555498Z","time spent":"1.007591292s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:52.685375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.909286ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-02T10:04:52.685946Z","caller":"traceutil/trace.go:171","msg":"trace[1019476708] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"512.830057ms","start":"2026-10-02T10:04:52.173096Z","end":"2026-10-02T10:04:52.685926Z","steps":["trace[1019476708] 'process raft request' (duration: 512.371958ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:52.68595Z","caller":"traceutil/trace.go:171","msg":"trace[1073408561] linearizableReadLoop","detail":"{readStateIndex:26282; appliedIndex:26278; }","duration":"799.399663ms","start":"2026-10-02T10:04:51.8865Z","end":"2026-10-02T10:04:52.685899Z","steps":["trace[1073408561] 'read index received' (duration: 649.53959ms)","trace[1073408561] 'applied index is now lower than readState.Index' (duration: 149.858243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:04:52.686052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:52.173069Z","time spent":"512.912818ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:52.688384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.176567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T10:04:52.688433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021389393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-02T10:04:52.688441Z","caller":"traceutil/trace.go:171","msg":"trace[1352875856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25456; }","duration":"800.281339ms","start":"2026-10-02T10:04:51.888143Z","end":"2026-10-02T10:04:52.688424Z","steps":["trace[1352875856] 'agreement among raft nodes before linearized reading' (duration: 797.929935ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:52.688472Z","caller":"traceutil/trace.go:171","msg":"trace[414393927] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25456; }","duration":"1.021492565s","start":"2026-10-02T10:04:51.666966Z","end":"2026-10-02T10:04:52.688459Z","steps":["trace[414393927] 'agreement among raft nodes before linearized reading' (duration: 1.019033649s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.68848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.888127Z","time spent":"800.34562ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T10:04:52.688498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.666935Z","time spent":"1.021557197s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-02T10:04:52.68871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115773154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T10:04:52.688733Z","caller":"traceutil/trace.go:171","msg":"trace[482351115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25456; }","duration":"1.115832555s","start":"2026-10-02T10:04:51.572894Z","end":"2026-10-02T10:04:52.688726Z","steps":["trace[482351115] 'agreement among raft nodes before linearized reading' (duration: 1.113006462s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.688753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.572871Z","time spent":"1.115876136s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T10:04:52.688806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833662041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T10:04:52.688848Z","caller":"traceutil/trace.go:171","msg":"trace[510043626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25456; }","duration":"1.833705991s","start":"2026-10-02T10:04:50.855127Z","end":"2026-10-02T10:04:52.688833Z","steps":["trace[510043626] 'agreement among raft nodes before linearized reading' (duration: 1.830884958s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.688873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.048419754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"warn","ts":"2026-10-02T10:04:52.688845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437807303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:04:52.6889Z","caller":"traceutil/trace.go:171","msg":"trace[215705152] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25456; }","duration":"2.048489356s","start":"2026-10-02T10:04:50.640403Z","end":"2026-10-02T10:04:52.688892Z","steps":["trace[215705152] 'agreement among raft nodes before linearized reading' (duration: 2.045646592s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.688924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.64038Z","time spent":"2.048536266s","remote":"127.0.0.1:57402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":870,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-10-02T10:04:52.688925Z","caller":"traceutil/trace.go:171","msg":"trace[297498094] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25456; }","duration":"1.437969946s","start":"2026-10-02T10:04:51.250937Z","end":"2026-10-02T10:04:52.688907Z","steps":["trace[297498094] 'agreement among raft nodes before linearized reading' (duration: 1.43504186s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.688956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182810764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-02T10:04:52.688969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.250913Z","time spent":"1.438044707s","remote":"127.0.0.1:55036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-02T10:04:52.688991Z","caller":"traceutil/trace.go:171","msg":"trace[426788307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25456; }","duration":"2.182880515s","start":"2026-10-02T10:04:50.5061Z","end":"2026-10-02T10:04:52.688981Z","steps":["trace[426788307] 'agreement among raft nodes before linearized reading' (duration: 2.17996292s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.689027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.506078Z","time spent":"2.182937996s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T10:04:52.689027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593340332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T10:04:52.689078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814164211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T10:04:52.689104Z","caller":"traceutil/trace.go:171","msg":"trace[538664600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25456; }","duration":"1.814213731s","start":"2026-10-02T10:04:50.874883Z","end":"2026-10-02T10:04:52.689097Z","steps":["trace[538664600] 'agreement among raft nodes before linearized reading' (duration: 1.811139993s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.689124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.874871Z","time spent":"1.814247682s","remote":"127.0.0.1:57470","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-10-02T10:04:52.689113Z","caller":"traceutil/trace.go:171","msg":"trace[1604393154] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25456; }","duration":"1.593482135s","start":"2026-10-02T10:04:51.095608Z","end":"2026-10-02T10:04:52.68909Z","steps":["trace[1604393154] 'agreement among raft nodes before linearized reading' (duration: 1.590380096s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.689158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.095579Z","time spent":"1.593568806s","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-02T10:04:52.68921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179801357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T10:04:52.689233Z","caller":"traceutil/trace.go:171","msg":"trace[1896480922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25456; }","duration":"2.179851618s","start":"2026-10-02T10:04:50.509374Z","end":"2026-10-02T10:04:52.689226Z","steps":["trace[1896480922] 'agreement among raft nodes before linearized reading' (duration: 2.176662127s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.689254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.509358Z","time spent":"2.179891258s","remote":"127.0.0.1:57470","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-10-02T10:04:52.689291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807600147s","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-10-02T10:04:52.689329Z","caller":"traceutil/trace.go:171","msg":"trace[126347081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25456; }","duration":"1.807671228s","start":"2026-10-02T10:04:50.881645Z","end":"2026-10-02T10:04:52.689316Z","steps":["trace[126347081] 'agreement among raft nodes before linearized reading' (duration: 1.804285893s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.689356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.881632Z","time spent":"1.807717059s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-02T10:04:52.689491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33747029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-02T10:04:52.689514Z","caller":"traceutil/trace.go:171","msg":"trace[1376964751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25456; }","duration":"1.33751485s","start":"2026-10-02T10:04:51.351991Z","end":"2026-10-02T10:04:52.689506Z","steps":["trace[1376964751] 'agreement among raft nodes before linearized reading' (duration: 1.333975733s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:52.689536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:51.351979Z","time spent":"1.337550901s","remote":"127.0.0.1:57380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-02T10:04:52.688879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:50.855064Z","time spent":"1.833807753s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T10:04:55.312162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.797898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa40e5e0-1372-42d0-b16a-798f7b3ea4a5\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-02T10:04:55.312262Z","caller":"traceutil/trace.go:171","msg":"trace[1704762854] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa40e5e0-1372-42d0-b16a-798f7b3ea4a5; range_end:; response_count:1; response_revision:25535; }","duration":"114.96453ms","start":"2026-10-02T10:04:55.197275Z","end":"2026-10-02T10:04:55.31224Z","steps":["trace[1704762854] 'range keys from in-memory index tree' (duration: 114.596483ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:55.807608Z","caller":"traceutil/trace.go:171","msg":"trace[1950955659] linearizableReadLoop","detail":"{readStateIndex:26363; appliedIndex:26362; }","duration":"487.710632ms","start":"2026-10-02T10:04:55.319865Z","end":"2026-10-02T10:04:55.807575Z","steps":["trace[1950955659] 'read index received' (duration: 487.475007ms)","trace[1950955659] 'applied index is now lower than readState.Index' (duration: 234.455µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:55.807657Z","caller":"traceutil/trace.go:171","msg":"trace[50506418] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"488.17703ms","start":"2026-10-02T10:04:55.319457Z","end":"2026-10-02T10:04:55.807634Z","steps":["trace[50506418] 'process raft request' (duration: 487.884634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:55.80781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:55.319444Z","time spent":"488.267032ms","remote":"127.0.0.1:57380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:55.807848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.168035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T10:04:55.807849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.959576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:04:55.807906Z","caller":"traceutil/trace.go:171","msg":"trace[1723508637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25536; }","duration":"434.265897ms","start":"2026-10-02T10:04:55.373626Z","end":"2026-10-02T10:04:55.807892Z","steps":["trace[1723508637] 'agreement among raft nodes before linearized reading' (duration: 434.128954ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:55.807923Z","caller":"traceutil/trace.go:171","msg":"trace[1849647761] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25536; }","duration":"488.090679ms","start":"2026-10-02T10:04:55.319815Z","end":"2026-10-02T10:04:55.807905Z","steps":["trace[1849647761] 'agreement among raft nodes before linearized reading' (duration: 487.919806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:55.807954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:55.373605Z","time spent":"434.338979ms","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T10:04:55.807967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:55.319796Z","time spent":"488.16083ms","remote":"127.0.0.1:54972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-02T10:04:56.197956Z","caller":"traceutil/trace.go:171","msg":"trace[2088215456] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"299.125293ms","start":"2026-10-02T10:04:55.898799Z","end":"2026-10-02T10:04:56.197924Z","steps":["trace[2088215456] 'process raft request' (duration: 298.94458ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:56.272381Z","caller":"traceutil/trace.go:171","msg":"trace[816348280] linearizableReadLoop","detail":"{readStateIndex:26366; appliedIndex:26363; }","duration":"234.679681ms","start":"2026-10-02T10:04:56.037681Z","end":"2026-10-02T10:04:56.27236Z","steps":["trace[816348280] 'read index received' (duration: 160.151627ms)","trace[816348280] 'applied index is now lower than readState.Index' (duration: 74.527314ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:56.272485Z","caller":"traceutil/trace.go:171","msg":"trace[1587950063] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"372.411524ms","start":"2026-10-02T10:04:55.900023Z","end":"2026-10-02T10:04:56.272435Z","steps":["trace[1587950063] 'process raft request' (duration: 368.456419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:56.272575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.872115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa40e5e0-1372-42d0-b16a-798f7b3ea4a5\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-10-02T10:04:56.272617Z","caller":"traceutil/trace.go:171","msg":"trace[441998551] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa40e5e0-1372-42d0-b16a-798f7b3ea4a5; range_end:; response_count:1; response_revision:25539; }","duration":"234.962306ms","start":"2026-10-02T10:04:56.037642Z","end":"2026-10-02T10:04:56.272604Z","steps":["trace[441998551] 'agreement among raft nodes before linearized reading' (duration: 234.793393ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:56.272626Z","caller":"traceutil/trace.go:171","msg":"trace[865098213] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"370.754073ms","start":"2026-10-02T10:04:55.901842Z","end":"2026-10-02T10:04:56.272596Z","steps":["trace[865098213] 'process raft request' (duration: 370.470208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:56.27265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:55.900011Z","time spent":"372.551476ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:56.27273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:55.901823Z","time spent":"370.863065ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:58.880875Z","caller":"traceutil/trace.go:171","msg":"trace[1904306970] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"290.371458ms","start":"2026-10-02T10:04:58.590476Z","end":"2026-10-02T10:04:58.880848Z","steps":["trace[1904306970] 'process raft request' (duration: 290.076662ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:59.082695Z","caller":"traceutil/trace.go:171","msg":"trace[1277256731] linearizableReadLoop","detail":"{readStateIndex:26387; appliedIndex:26385; }","duration":"433.674256ms","start":"2026-10-02T10:04:58.648997Z","end":"2026-10-02T10:04:59.082671Z","steps":["trace[1277256731] 'read index received' (duration: 231.662034ms)","trace[1277256731] 'applied index is now lower than readState.Index' (duration: 202.011142ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:04:59.082706Z","caller":"traceutil/trace.go:171","msg":"trace[625723183] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"484.47932ms","start":"2026-10-02T10:04:58.598201Z","end":"2026-10-02T10:04:59.08268Z","steps":["trace[625723183] 'process raft request' (duration: 484.132603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.082898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.89267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-02T10:04:59.082953Z","caller":"traceutil/trace.go:171","msg":"trace[296192434] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25560; }","duration":"433.993082ms","start":"2026-10-02T10:04:58.648946Z","end":"2026-10-02T10:04:59.082939Z","steps":["trace[296192434] 'agreement among raft nodes before linearized reading' (duration: 433.843419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.082988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.648928Z","time spent":"434.053923ms","remote":"127.0.0.1:57362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-10-02T10:04:59.083001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.598182Z","time spent":"484.741325ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:04:59.083048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.079109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:04:59.083102Z","caller":"traceutil/trace.go:171","msg":"trace[687642223] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25560; }","duration":"430.17575ms","start":"2026-10-02T10:04:58.652914Z","end":"2026-10-02T10:04:59.083089Z","steps":["trace[687642223] 'agreement among raft nodes before linearized reading' (duration: 430.054968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.083116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.339185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-02T10:04:59.083142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.6529Z","time spent":"430.231871ms","remote":"127.0.0.1:36028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:04:59.083136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.802106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T10:04:59.08317Z","caller":"traceutil/trace.go:171","msg":"trace[1587770275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25560; }","duration":"321.422376ms","start":"2026-10-02T10:04:58.761732Z","end":"2026-10-02T10:04:59.083155Z","steps":["trace[1587770275] 'agreement among raft nodes before linearized reading' (duration: 321.255933ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:59.083197Z","caller":"traceutil/trace.go:171","msg":"trace[793735252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25560; }","duration":"320.883337ms","start":"2026-10-02T10:04:58.7623Z","end":"2026-10-02T10:04:59.083184Z","steps":["trace[793735252] 'agreement among raft nodes before linearized reading' (duration: 320.715484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.083206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.761721Z","time spent":"321.477217ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-02T10:04:59.083224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.01858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-02T10:04:59.083235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.762293Z","time spent":"320.934178ms","remote":"127.0.0.1:57470","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-10-02T10:04:59.083249Z","caller":"traceutil/trace.go:171","msg":"trace[608758369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25560; }","duration":"321.0641ms","start":"2026-10-02T10:04:58.762179Z","end":"2026-10-02T10:04:59.083244Z","steps":["trace[608758369] 'agreement among raft nodes before linearized reading' (duration: 320.996949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.083266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.76217Z","time spent":"321.092371ms","remote":"127.0.0.1:57470","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":"info","ts":"2026-10-02T10:04:59.082725Z","caller":"traceutil/trace.go:171","msg":"trace[804311203] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"359.483679ms","start":"2026-10-02T10:04:58.723209Z","end":"2026-10-02T10:04:59.082692Z","steps":["trace[804311203] 'process raft request' (duration: 359.348527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.083293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.099553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-10-02T10:04:59.083362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.395139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-02T10:04:59.08338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.882875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T10:04:59.083379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.723187Z","time spent":"360.137912ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:04:59.083379Z","caller":"traceutil/trace.go:171","msg":"trace[945610334] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25560; }","duration":"289.219386ms","start":"2026-10-02T10:04:58.794136Z","end":"2026-10-02T10:04:59.083355Z","steps":["trace[945610334] 'agreement among raft nodes before linearized reading' (duration: 288.962171ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:59.083399Z","caller":"traceutil/trace.go:171","msg":"trace[1406936459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25560; }","duration":"322.932886ms","start":"2026-10-02T10:04:58.76046Z","end":"2026-10-02T10:04:59.083393Z","steps":["trace[1406936459] 'agreement among raft nodes before linearized reading' (duration: 322.848844ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:04:59.083395Z","caller":"traceutil/trace.go:171","msg":"trace[1741451282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25560; }","duration":"347.440051ms","start":"2026-10-02T10:04:58.735946Z","end":"2026-10-02T10:04:59.083387Z","steps":["trace[1741451282] 'agreement among raft nodes before linearized reading' (duration: 347.356329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.083422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.760445Z","time spent":"322.973536ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T10:04:59.08343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.735937Z","time spent":"347.486862ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T10:04:59.083439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.77002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T10:04:59.083506Z","caller":"traceutil/trace.go:171","msg":"trace[1423478438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25560; }","duration":"354.873522ms","start":"2026-10-02T10:04:58.728623Z","end":"2026-10-02T10:04:59.083497Z","steps":["trace[1423478438] 'agreement among raft nodes before linearized reading' (duration: 354.707459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:59.083532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:04:58.728607Z","time spent":"354.918553ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-02T10:05:03.587932Z","caller":"traceutil/trace.go:171","msg":"trace[1458260282] linearizableReadLoop","detail":"{readStateIndex:26474; appliedIndex:26473; }","duration":"153.903929ms","start":"2026-10-02T10:05:03.433962Z","end":"2026-10-02T10:05:03.587866Z","steps":["trace[1458260282] 'read index received' (duration: 153.682685ms)","trace[1458260282] 'applied index is now lower than readState.Index' (duration: 220.194µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:03.588008Z","caller":"traceutil/trace.go:171","msg":"trace[1497234059] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"163.238036ms","start":"2026-10-02T10:05:03.424733Z","end":"2026-10-02T10:05:03.587971Z","steps":["trace[1497234059] 'process raft request' (duration: 162.92123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:03.588102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.120553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-02T10:05:03.588181Z","caller":"traceutil/trace.go:171","msg":"trace[811043319] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25644; }","duration":"154.239326ms","start":"2026-10-02T10:05:03.433922Z","end":"2026-10-02T10:05:03.588161Z","steps":["trace[811043319] 'agreement among raft nodes before linearized reading' (duration: 153.94964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:03.79712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.658428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:05:03.797235Z","caller":"traceutil/trace.go:171","msg":"trace[343431905] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"202.785587ms","start":"2026-10-02T10:05:03.594426Z","end":"2026-10-02T10:05:03.797212Z","steps":["trace[343431905] 'process raft request' (duration: 41.923415ms)","trace[343431905] 'compare' (duration: 160.367362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:04.180356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.789825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:05:04.180582Z","caller":"traceutil/trace.go:171","msg":"trace[168493339] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"585.106758ms","start":"2026-10-02T10:05:03.595443Z","end":"2026-10-02T10:05:04.180549Z","steps":["trace[168493339] 'process raft request' (duration: 351.041749ms)","trace[168493339] 'compare' (duration: 233.451577ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:04.18078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:03.595419Z","time spent":"585.248321ms","remote":"127.0.0.1:57476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:04.181Z","caller":"traceutil/trace.go:171","msg":"trace[157149458] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"585.21281ms","start":"2026-10-02T10:05:03.595723Z","end":"2026-10-02T10:05:04.180936Z","steps":["trace[157149458] 'process raft request' (duration: 584.761762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:04.18133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:03.595716Z","time spent":"585.517396ms","remote":"127.0.0.1:57374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":940,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:04.181397Z","caller":"traceutil/trace.go:171","msg":"trace[1727789796] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"585.502636ms","start":"2026-10-02T10:05:03.595856Z","end":"2026-10-02T10:05:04.181359Z","steps":["trace[1727789796] 'process raft request' (duration: 584.947015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:04.181582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:03.59585Z","time spent":"585.642919ms","remote":"127.0.0.1:57476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:09.183915Z","caller":"traceutil/trace.go:171","msg":"trace[554722375] linearizableReadLoop","detail":"{readStateIndex:26532; appliedIndex:26531; }","duration":"208.646208ms","start":"2026-10-02T10:05:08.975235Z","end":"2026-10-02T10:05:09.183881Z","steps":["trace[554722375] 'read index received' (duration: 208.438904ms)","trace[554722375] 'applied index is now lower than readState.Index' (duration: 206.184µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:09.183934Z","caller":"traceutil/trace.go:171","msg":"trace[405675251] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"260.246986ms","start":"2026-10-02T10:05:08.923664Z","end":"2026-10-02T10:05:09.183911Z","steps":["trace[405675251] 'process raft request' (duration: 260.032792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.184161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.901383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-02T10:05:09.184218Z","caller":"traceutil/trace.go:171","msg":"trace[1122123814] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25701; }","duration":"209.004825ms","start":"2026-10-02T10:05:08.975196Z","end":"2026-10-02T10:05:09.1842Z","steps":["trace[1122123814] 'agreement among raft nodes before linearized reading' (duration: 208.826911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.784084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:05:09.343169Z","caller":"traceutil/trace.go:171","msg":"trace[1463191619] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26532; }","duration":"159.167779ms","start":"2026-10-02T10:05:09.18399Z","end":"2026-10-02T10:05:09.343157Z","steps":["trace[1463191619] 'read index received' (duration: 11.125061ms)","trace[1463191619] 'applied index is now lower than readState.Index' (duration: 148.041928ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:09.343184Z","caller":"traceutil/trace.go:171","msg":"trace[1289622462] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"222.9243ms","start":"2026-10-02T10:05:09.120223Z","end":"2026-10-02T10:05:09.343148Z","steps":["trace[1289622462] 'process raft request' (duration: 74.942963ms)","trace[1289622462] 'compare' (duration: 147.60745ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:09.34321Z","caller":"traceutil/trace.go:171","msg":"trace[1564114584] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"180.916822ms","start":"2026-10-02T10:05:09.162268Z","end":"2026-10-02T10:05:09.343185Z","steps":["trace[1564114584] 'process raft request' (duration: 180.859511ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:09.343242Z","caller":"traceutil/trace.go:171","msg":"trace[2117824356] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"216.316063ms","start":"2026-10-02T10:05:09.126904Z","end":"2026-10-02T10:05:09.34322Z","steps":["trace[2117824356] 'process raft request' (duration: 216.190481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.44149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T10:05:09.343307Z","caller":"traceutil/trace.go:171","msg":"trace[625789518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25704; }","duration":"192.494992ms","start":"2026-10-02T10:05:09.150807Z","end":"2026-10-02T10:05:09.343302Z","steps":["trace[625789518] 'agreement among raft nodes before linearized reading' (duration: 192.41439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.304314ms","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-10-02T10:05:09.343354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.348638ms","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-10-02T10:05:09.343396Z","caller":"traceutil/trace.go:171","msg":"trace[1720872269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25704; }","duration":"176.361605ms","start":"2026-10-02T10:05:09.167024Z","end":"2026-10-02T10:05:09.343386Z","steps":["trace[1720872269] 'agreement among raft nodes before linearized reading' (duration: 176.279014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.384265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T10:05:09.343422Z","caller":"traceutil/trace.go:171","msg":"trace[931883863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25704; }","duration":"177.442436ms","start":"2026-10-02T10:05:09.165974Z","end":"2026-10-02T10:05:09.343416Z","steps":["trace[931883863] 'agreement among raft nodes before linearized reading' (duration: 177.342094ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:09.343425Z","caller":"traceutil/trace.go:171","msg":"trace[1739977372] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25704; }","duration":"183.458581ms","start":"2026-10-02T10:05:09.159946Z","end":"2026-10-02T10:05:09.343404Z","steps":["trace[1739977372] 'agreement among raft nodes before linearized reading' (duration: 183.227266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.322246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-02T10:05:09.343509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.204235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:05:09.343541Z","caller":"traceutil/trace.go:171","msg":"trace[1969610483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25704; }","duration":"194.458019ms","start":"2026-10-02T10:05:09.149068Z","end":"2026-10-02T10:05:09.343526Z","steps":["trace[1969610483] 'agreement among raft nodes before linearized reading' (duration: 194.293246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.390321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-02T10:05:09.343533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.9629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"warn","ts":"2026-10-02T10:05:09.343346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.57502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:09.34359Z","caller":"traceutil/trace.go:171","msg":"trace[2143137093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25704; }","duration":"200.465563ms","start":"2026-10-02T10:05:09.143111Z","end":"2026-10-02T10:05:09.343577Z","steps":["trace[2143137093] 'agreement among raft nodes before linearized reading' (duration: 200.31538ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:09.343579Z","caller":"traceutil/trace.go:171","msg":"trace[1508364825] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25704; }","duration":"347.298038ms","start":"2026-10-02T10:05:08.996253Z","end":"2026-10-02T10:05:09.343551Z","steps":["trace[1508364825] 'agreement among raft nodes before linearized reading' (duration: 347.151525ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:09.34362Z","caller":"traceutil/trace.go:171","msg":"trace[1604498176] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25704; }","duration":"155.089002ms","start":"2026-10-02T10:05:09.18851Z","end":"2026-10-02T10:05:09.343599Z","steps":["trace[1604498176] 'agreement among raft nodes before linearized reading' (duration: 154.837908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:08.996233Z","time spent":"347.41822ms","remote":"127.0.0.1:57654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-10-02T10:05:09.343629Z","caller":"traceutil/trace.go:171","msg":"trace[1388755223] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25704; }","duration":"263.921927ms","start":"2026-10-02T10:05:09.079687Z","end":"2026-10-02T10:05:09.343609Z","steps":["trace[1388755223] 'agreement among raft nodes before linearized reading' (duration: 263.56703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:09.343479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.473021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-02T10:05:09.345063Z","caller":"traceutil/trace.go:171","msg":"trace[236854690] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25704; }","duration":"155.072461ms","start":"2026-10-02T10:05:09.189975Z","end":"2026-10-02T10:05:09.345048Z","steps":["trace[236854690] 'agreement among raft nodes before linearized reading' (duration: 153.440061ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:11.341805Z","caller":"traceutil/trace.go:171","msg":"trace[1090518425] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"152.566484ms","start":"2026-10-02T10:05:11.189213Z","end":"2026-10-02T10:05:11.34178Z","steps":["trace[1090518425] 'process raft request' (duration: 152.36271ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:12.872095Z","caller":"traceutil/trace.go:171","msg":"trace[987838362] linearizableReadLoop","detail":"{readStateIndex:26570; appliedIndex:26569; }","duration":"119.499166ms","start":"2026-10-02T10:05:12.752578Z","end":"2026-10-02T10:05:12.872077Z","steps":["trace[987838362] 'read index received' (duration: 119.313183ms)","trace[987838362] 'applied index is now lower than readState.Index' (duration: 185.143µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:12.872159Z","caller":"traceutil/trace.go:171","msg":"trace[375706894] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"188.328731ms","start":"2026-10-02T10:05:12.683802Z","end":"2026-10-02T10:05:12.87213Z","steps":["trace[375706894] 'process raft request' (duration: 188.151608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:12.872242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.642999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:12.872286Z","caller":"traceutil/trace.go:171","msg":"trace[790198220] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25738; }","duration":"119.69877ms","start":"2026-10-02T10:05:12.752571Z","end":"2026-10-02T10:05:12.87227Z","steps":["trace[790198220] 'agreement among raft nodes before linearized reading' (duration: 119.618519ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:17.031023Z","caller":"traceutil/trace.go:171","msg":"trace[1774231395] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"147.639501ms","start":"2026-10-02T10:05:16.883357Z","end":"2026-10-02T10:05:17.030997Z","steps":["trace[1774231395] 'process raft request' (duration: 147.470938ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:17.033252Z","caller":"traceutil/trace.go:171","msg":"trace[1294074648] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"100.610628ms","start":"2026-10-02T10:05:16.932629Z","end":"2026-10-02T10:05:17.033239Z","steps":["trace[1294074648] 'process raft request' (duration: 100.545127ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:21.61194Z","caller":"traceutil/trace.go:171","msg":"trace[1604097119] linearizableReadLoop","detail":"{readStateIndex:26649; appliedIndex:26645; }","duration":"118.69441ms","start":"2026-10-02T10:05:21.493216Z","end":"2026-10-02T10:05:21.61191Z","steps":["trace[1604097119] 'read index received' (duration: 9.920527ms)","trace[1604097119] 'applied index is now lower than readState.Index' (duration: 108.772643ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:21.612031Z","caller":"traceutil/trace.go:171","msg":"trace[1187820611] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"164.384878ms","start":"2026-10-02T10:05:21.447607Z","end":"2026-10-02T10:05:21.611992Z","steps":["trace[1187820611] 'process raft request' (duration: 164.026622ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:21.612057Z","caller":"traceutil/trace.go:171","msg":"trace[195712583] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"150.516525ms","start":"2026-10-02T10:05:21.461513Z","end":"2026-10-02T10:05:21.612029Z","steps":["trace[195712583] 'process raft request' (duration: 150.353891ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:21.612072Z","caller":"traceutil/trace.go:171","msg":"trace[321710089] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"152.583203ms","start":"2026-10-02T10:05:21.459435Z","end":"2026-10-02T10:05:21.612018Z","steps":["trace[321710089] 'process raft request' (duration: 152.38817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:21.612197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.943985ms","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-10-02T10:05:21.612244Z","caller":"traceutil/trace.go:171","msg":"trace[1615586515] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25816; }","duration":"119.054297ms","start":"2026-10-02T10:05:21.493174Z","end":"2026-10-02T10:05:21.612228Z","steps":["trace[1615586515] 'agreement among raft nodes before linearized reading' (duration: 118.878924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:21.61225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.559793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T10:05:21.6123Z","caller":"traceutil/trace.go:171","msg":"trace[8973113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25816; }","duration":"113.656776ms","start":"2026-10-02T10:05:21.498629Z","end":"2026-10-02T10:05:21.612286Z","steps":["trace[8973113] 'agreement among raft nodes before linearized reading' (duration: 113.467862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:21.612338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.745015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T10:05:21.61234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.638346ms","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-10-02T10:05:21.612372Z","caller":"traceutil/trace.go:171","msg":"trace[115817406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25816; }","duration":"106.806106ms","start":"2026-10-02T10:05:21.505553Z","end":"2026-10-02T10:05:21.612359Z","steps":["trace[115817406] 'agreement among raft nodes before linearized reading' (duration: 106.694314ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:21.612375Z","caller":"traceutil/trace.go:171","msg":"trace[1217876859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25816; }","duration":"103.737528ms","start":"2026-10-02T10:05:21.508626Z","end":"2026-10-02T10:05:21.612364Z","steps":["trace[1217876859] 'agreement among raft nodes before linearized reading' (duration: 103.579805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:21.612398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.526745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T10:05:21.612476Z","caller":"traceutil/trace.go:171","msg":"trace[231581219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25816; }","duration":"111.630927ms","start":"2026-10-02T10:05:21.500825Z","end":"2026-10-02T10:05:21.612456Z","steps":["trace[231581219] 'agreement among raft nodes before linearized reading' (duration: 111.432633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:21.612573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.241912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T10:05:21.612624Z","caller":"traceutil/trace.go:171","msg":"trace[264060115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25816; }","duration":"100.309443ms","start":"2026-10-02T10:05:21.512303Z","end":"2026-10-02T10:05:21.612612Z","steps":["trace[264060115] 'agreement among raft nodes before linearized reading' (duration: 100.131479ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:22.668Z","caller":"traceutil/trace.go:171","msg":"trace[2001388757] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"159.345692ms","start":"2026-10-02T10:05:22.508626Z","end":"2026-10-02T10:05:22.667972Z","steps":["trace[2001388757] 'process raft request' (duration: 156.180252ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:23.110207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22355} {"level":"info","ts":"2026-10-02T10:05:23.226194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22355,"took":"111.580506ms","hash":2286019165,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":45715456,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-02T10:05:23.226259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2286019165,"revision":22355,"compact-revision":20123} {"level":"info","ts":"2026-10-02T10:05:24.718824Z","caller":"traceutil/trace.go:171","msg":"trace[1635984098] linearizableReadLoop","detail":"{readStateIndex:26688; appliedIndex:26687; }","duration":"288.188097ms","start":"2026-10-02T10:05:24.430618Z","end":"2026-10-02T10:05:24.718806Z","steps":["trace[1635984098] 'read index received' (duration: 287.951962ms)","trace[1635984098] 'applied index is now lower than readState.Index' (duration: 235.595µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:24.719155Z","caller":"traceutil/trace.go:171","msg":"trace[131035291] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"290.97811ms","start":"2026-10-02T10:05:24.42817Z","end":"2026-10-02T10:05:24.719148Z","steps":["trace[131035291] 'process raft request' (duration: 290.46197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:24.719388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.766347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-10-02T10:05:24.71941Z","caller":"traceutil/trace.go:171","msg":"trace[899500899] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25853; }","duration":"288.832408ms","start":"2026-10-02T10:05:24.430573Z","end":"2026-10-02T10:05:24.719405Z","steps":["trace[899500899] 'agreement among raft nodes before linearized reading' (duration: 288.743436ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:24.780484Z","caller":"traceutil/trace.go:171","msg":"trace[1606870818] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"345.722648ms","start":"2026-10-02T10:05:24.43454Z","end":"2026-10-02T10:05:24.780263Z","steps":["trace[1606870818] 'process raft request' (duration: 345.101736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:24.78063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:24.434523Z","time spent":"346.029235ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:24.780836Z","caller":"traceutil/trace.go:171","msg":"trace[448109812] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"342.468725ms","start":"2026-10-02T10:05:24.438354Z","end":"2026-10-02T10:05:24.780823Z","steps":["trace[448109812] 'process raft request' (duration: 341.418605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:24.780911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:24.43834Z","time spent":"342.526267ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:24.781045Z","caller":"traceutil/trace.go:171","msg":"trace[1951498794] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"244.899315ms","start":"2026-10-02T10:05:24.536136Z","end":"2026-10-02T10:05:24.781036Z","steps":["trace[1951498794] 'process raft request' (duration: 243.672332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:24.781461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.512961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:05:24.781507Z","caller":"traceutil/trace.go:171","msg":"trace[345083316] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25856; }","duration":"183.564912ms","start":"2026-10-02T10:05:24.597929Z","end":"2026-10-02T10:05:24.781494Z","steps":["trace[345083316] 'agreement among raft nodes before linearized reading' (duration: 183.373288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:24.781675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.831703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:24.781709Z","caller":"traceutil/trace.go:171","msg":"trace[566373220] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25856; }","duration":"235.898855ms","start":"2026-10-02T10:05:24.545801Z","end":"2026-10-02T10:05:24.7817Z","steps":["trace[566373220] 'agreement among raft nodes before linearized reading' (duration: 235.846294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:24.781971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.231759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-02T10:05:24.782004Z","caller":"traceutil/trace.go:171","msg":"trace[533781232] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25856; }","duration":"238.294119ms","start":"2026-10-02T10:05:24.543702Z","end":"2026-10-02T10:05:24.781997Z","steps":["trace[533781232] 'agreement among raft nodes before linearized reading' (duration: 238.156227ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:28.927829Z","caller":"traceutil/trace.go:171","msg":"trace[2077771710] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"132.167758ms","start":"2026-10-02T10:05:28.795633Z","end":"2026-10-02T10:05:28.9278Z","steps":["trace[2077771710] 'process raft request' (duration: 131.960204ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:28.943232Z","caller":"traceutil/trace.go:171","msg":"trace[1892341931] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"147.029979ms","start":"2026-10-02T10:05:28.796175Z","end":"2026-10-02T10:05:28.943205Z","steps":["trace[1892341931] 'process raft request' (duration: 146.879477ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:33.044557Z","caller":"traceutil/trace.go:171","msg":"trace[1389481705] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"106.171634ms","start":"2026-10-02T10:05:32.938362Z","end":"2026-10-02T10:05:33.044534Z","steps":["trace[1389481705] 'process raft request' (duration: 106.034991ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:33.165952Z","caller":"traceutil/trace.go:171","msg":"trace[261645338] transaction","detail":"{read_only:false; response_revision:25914; number_of_response:1; }","duration":"212.262446ms","start":"2026-10-02T10:05:32.953658Z","end":"2026-10-02T10:05:33.16592Z","steps":["trace[261645338] 'process raft request' (duration: 185.637141ms)","trace[261645338] 'compare' (duration: 26.37842ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:39.096095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835595930443596383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T10:05:39.113917Z","caller":"traceutil/trace.go:171","msg":"trace[1505217980] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"585.922954ms","start":"2026-10-02T10:05:38.527969Z","end":"2026-10-02T10:05:39.113892Z","steps":["trace[1505217980] 'process raft request' (duration: 585.70861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.114048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.527941Z","time spent":"586.039377ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:39.296229Z","caller":"traceutil/trace.go:171","msg":"trace[922167688] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"717.886058ms","start":"2026-10-02T10:05:38.578315Z","end":"2026-10-02T10:05:39.296201Z","steps":["trace[922167688] 'process raft request' (duration: 652.904005ms)","trace[922167688] 'compare' (duration: 64.80921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:39.296368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.578261Z","time spent":"718.036021ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:39.296446Z","caller":"traceutil/trace.go:171","msg":"trace[48497703] linearizableReadLoop","detail":"{readStateIndex:26796; appliedIndex:26792; }","duration":"700.602538ms","start":"2026-10-02T10:05:38.595826Z","end":"2026-10-02T10:05:39.296429Z","steps":["trace[48497703] 'read index received' (duration: 517.846643ms)","trace[48497703] 'applied index is now lower than readState.Index' (duration: 182.755315ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:39.296463Z","caller":"traceutil/trace.go:171","msg":"trace[812019565] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"498.21492ms","start":"2026-10-02T10:05:38.79822Z","end":"2026-10-02T10:05:39.296435Z","steps":["trace[812019565] 'process raft request' (duration: 497.930636ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:39.296484Z","caller":"traceutil/trace.go:171","msg":"trace[1166235322] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"108.484467ms","start":"2026-10-02T10:05:39.187982Z","end":"2026-10-02T10:05:39.296467Z","steps":["trace[1166235322] 'process raft request' (duration: 108.428916ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:39.296513Z","caller":"traceutil/trace.go:171","msg":"trace[336804274] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"233.858075ms","start":"2026-10-02T10:05:39.062621Z","end":"2026-10-02T10:05:39.296479Z","steps":["trace[336804274] 'process raft request' (duration: 233.738994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.296586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.753561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:39.296613Z","caller":"traceutil/trace.go:171","msg":"trace[2057035659] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25959; }","duration":"700.836433ms","start":"2026-10-02T10:05:38.595769Z","end":"2026-10-02T10:05:39.296605Z","steps":["trace[2057035659] 'agreement among raft nodes before linearized reading' (duration: 700.749981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.296597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.798196Z","time spent":"498.323482ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:05:39.29663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.595729Z","time spent":"700.896494ms","remote":"127.0.0.1:33252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:05:39.296622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.150255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:39.296673Z","caller":"traceutil/trace.go:171","msg":"trace[523549941] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25959; }","duration":"628.246056ms","start":"2026-10-02T10:05:38.668414Z","end":"2026-10-02T10:05:39.29666Z","steps":["trace[523549941] 'agreement among raft nodes before linearized reading' (duration: 628.155974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.296713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.668396Z","time spent":"628.307658ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:05:39.296676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.118514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-02T10:05:39.296795Z","caller":"traceutil/trace.go:171","msg":"trace[708822147] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25959; }","duration":"551.280807ms","start":"2026-10-02T10:05:38.745503Z","end":"2026-10-02T10:05:39.296784Z","steps":["trace[708822147] 'agreement among raft nodes before linearized reading' (duration: 551.073693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.29678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.020878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-02T10:05:39.296885Z","caller":"traceutil/trace.go:171","msg":"trace[339123794] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25959; }","duration":"457.194372ms","start":"2026-10-02T10:05:38.839664Z","end":"2026-10-02T10:05:39.296858Z","steps":["trace[339123794] 'agreement among raft nodes before linearized reading' (duration: 456.905096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.296822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.745464Z","time spent":"551.351938ms","remote":"127.0.0.1:57660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-02T10:05:39.296965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:38.839641Z","time spent":"457.307553ms","remote":"127.0.0.1:57380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-02T10:05:39.918806Z","caller":"traceutil/trace.go:171","msg":"trace[384851055] linearizableReadLoop","detail":"{readStateIndex:26798; appliedIndex:26797; }","duration":"317.117075ms","start":"2026-10-02T10:05:39.60167Z","end":"2026-10-02T10:05:39.918787Z","steps":["trace[384851055] 'read index received' (duration: 317.094055ms)","trace[384851055] 'applied index is now lower than readState.Index' (duration: 22.16µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:39.918908Z","caller":"traceutil/trace.go:171","msg":"trace[76277975] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"333.538377ms","start":"2026-10-02T10:05:39.585338Z","end":"2026-10-02T10:05:39.918877Z","steps":["trace[76277975] 'process raft request' (duration: 333.309312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.918971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.288598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:05:39.919005Z","caller":"traceutil/trace.go:171","msg":"trace[570273504] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25960; }","duration":"317.3741ms","start":"2026-10-02T10:05:39.601619Z","end":"2026-10-02T10:05:39.918993Z","steps":["trace[570273504] 'agreement among raft nodes before linearized reading' (duration: 317.271548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:39.919051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:39.585312Z","time spent":"333.660529ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:05:39.91906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:39.6016Z","time spent":"317.449621ms","remote":"127.0.0.1:55978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-10-02T10:05:40.086222Z","caller":"traceutil/trace.go:171","msg":"trace[176656309] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"335.400901ms","start":"2026-10-02T10:05:39.750795Z","end":"2026-10-02T10:05:40.086196Z","steps":["trace[176656309] 'process raft request' (duration: 335.194597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.086401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:39.750773Z","time spent":"335.523593ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:40.086465Z","caller":"traceutil/trace.go:171","msg":"trace[364919199] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"335.546424ms","start":"2026-10-02T10:05:39.750902Z","end":"2026-10-02T10:05:40.086448Z","steps":["trace[364919199] 'process raft request' (duration: 335.250169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.086577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:39.750866Z","time spent":"335.648457ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:40.086805Z","caller":"traceutil/trace.go:171","msg":"trace[1262590675] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"334.447853ms","start":"2026-10-02T10:05:39.752347Z","end":"2026-10-02T10:05:40.086795Z","steps":["trace[1262590675] 'process raft request' (duration: 334.037435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.086882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:39.752326Z","time spent":"334.501964ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:40.087077Z","caller":"traceutil/trace.go:171","msg":"trace[1595307692] linearizableReadLoop","detail":"{readStateIndex:26802; appliedIndex:26798; }","duration":"168.19902ms","start":"2026-10-02T10:05:39.918866Z","end":"2026-10-02T10:05:40.087065Z","steps":["trace[1595307692] 'read index received' (duration: 167.071108ms)","trace[1595307692] 'applied index is now lower than readState.Index' (duration: 1.127102ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:40.087222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.093708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T10:05:40.087254Z","caller":"traceutil/trace.go:171","msg":"trace[828214230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25964; }","duration":"314.15442ms","start":"2026-10-02T10:05:39.773088Z","end":"2026-10-02T10:05:40.087243Z","steps":["trace[828214230] 'agreement among raft nodes before linearized reading' (duration: 314.026027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.087265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.293643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-02T10:05:40.087287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:39.773076Z","time spent":"314.20443ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-02T10:05:40.087303Z","caller":"traceutil/trace.go:171","msg":"trace[1145540297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25964; }","duration":"254.348535ms","start":"2026-10-02T10:05:39.832944Z","end":"2026-10-02T10:05:40.087293Z","steps":["trace[1145540297] 'agreement among raft nodes before linearized reading' (duration: 254.234672ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:40.087409Z","caller":"traceutil/trace.go:171","msg":"trace[632972148] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"293.743492ms","start":"2026-10-02T10:05:39.793658Z","end":"2026-10-02T10:05:40.087401Z","steps":["trace[632972148] 'process raft request' (duration: 293.100679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.087537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.931965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T10:05:40.087565Z","caller":"traceutil/trace.go:171","msg":"trace[1165302961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25964; }","duration":"254.976457ms","start":"2026-10-02T10:05:39.83258Z","end":"2026-10-02T10:05:40.087556Z","steps":["trace[1165302961] 'agreement among raft nodes before linearized reading' (duration: 254.864924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.087652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.468734ms","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-10-02T10:05:40.087683Z","caller":"traceutil/trace.go:171","msg":"trace[1012353117] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25964; }","duration":"228.518505ms","start":"2026-10-02T10:05:39.859155Z","end":"2026-10-02T10:05:40.087674Z","steps":["trace[1012353117] 'agreement among raft nodes before linearized reading' (duration: 228.407003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.087774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.461293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T10:05:40.087802Z","caller":"traceutil/trace.go:171","msg":"trace[201591786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25964; }","duration":"258.509803ms","start":"2026-10-02T10:05:39.829284Z","end":"2026-10-02T10:05:40.087793Z","steps":["trace[201591786] 'agreement among raft nodes before linearized reading' (duration: 258.398851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:40.087916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.954042ms","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-10-02T10:05:40.087938Z","caller":"traceutil/trace.go:171","msg":"trace[1328397689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25964; }","duration":"258.976743ms","start":"2026-10-02T10:05:39.828954Z","end":"2026-10-02T10:05:40.087931Z","steps":["trace[1328397689] 'agreement among raft nodes before linearized reading' (duration: 258.906732ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:41.112174Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068ba.wal"} {"level":"info","ts":"2026-10-02T10:05:45.192994Z","caller":"traceutil/trace.go:171","msg":"trace[393025739] transaction","detail":"{read_only:false; response_revision:25999; number_of_response:1; }","duration":"170.595835ms","start":"2026-10-02T10:05:45.022376Z","end":"2026-10-02T10:05:45.192972Z","steps":["trace[393025739] 'process raft request' (duration: 170.420552ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:45.452655Z","caller":"traceutil/trace.go:171","msg":"trace[1060651064] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"130.106817ms","start":"2026-10-02T10:05:45.322517Z","end":"2026-10-02T10:05:45.452624Z","steps":["trace[1060651064] 'process raft request' (duration: 130.013196ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:45.452662Z","caller":"traceutil/trace.go:171","msg":"trace[1835823842] transaction","detail":"{read_only:false; response_revision:26001; number_of_response:1; }","duration":"137.141161ms","start":"2026-10-02T10:05:45.315489Z","end":"2026-10-02T10:05:45.45263Z","steps":["trace[1835823842] 'process raft request' (duration: 64.85133ms)","trace[1835823842] 'compare' (duration: 71.820363ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:46.396307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.066231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:05:46.396381Z","caller":"traceutil/trace.go:171","msg":"trace[993538854] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"288.143115ms","start":"2026-10-02T10:05:46.108227Z","end":"2026-10-02T10:05:46.39637Z","steps":["trace[993538854] 'process raft request' (duration: 73.949743ms)","trace[993538854] 'compare' (duration: 213.758335ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:46.433055Z","caller":"traceutil/trace.go:171","msg":"trace[1031965166] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"310.421908ms","start":"2026-10-02T10:05:46.122613Z","end":"2026-10-02T10:05:46.433035Z","steps":["trace[1031965166] 'process raft request' (duration: 310.280975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:46.433214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:46.122587Z","time spent":"310.574301ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:05:46.433264Z","caller":"traceutil/trace.go:171","msg":"trace[385005717] linearizableReadLoop","detail":"{readStateIndex:26845; appliedIndex:26842; }","duration":"296.137397ms","start":"2026-10-02T10:05:46.1371Z","end":"2026-10-02T10:05:46.433237Z","steps":["trace[385005717] 'read index received' (duration: 45.087335ms)","trace[385005717] 'applied index is now lower than readState.Index' (duration: 251.048942ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:05:46.433455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.32695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:46.433425Z","caller":"traceutil/trace.go:171","msg":"trace[1104641313] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"310.584401ms","start":"2026-10-02T10:05:46.122779Z","end":"2026-10-02T10:05:46.433363Z","steps":["trace[1104641313] 'process raft request' (duration: 310.212174ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.433511Z","caller":"traceutil/trace.go:171","msg":"trace[78028903] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:26006; }","duration":"296.434083ms","start":"2026-10-02T10:05:46.137062Z","end":"2026-10-02T10:05:46.433496Z","steps":["trace[78028903] 'agreement among raft nodes before linearized reading' (duration: 296.30787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:46.433631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:05:46.122749Z","time spent":"310.773685ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:05:46.434058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.65763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T10:05:46.434136Z","caller":"traceutil/trace.go:171","msg":"trace[1923156908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26006; }","duration":"274.762102ms","start":"2026-10-02T10:05:46.159352Z","end":"2026-10-02T10:05:46.434114Z","steps":["trace[1923156908] 'agreement among raft nodes before linearized reading' (duration: 274.554818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:46.434185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.900793ms","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-10-02T10:05:46.434328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.87483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-02T10:05:46.434349Z","caller":"traceutil/trace.go:171","msg":"trace[494660440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26006; }","duration":"247.121978ms","start":"2026-10-02T10:05:46.187199Z","end":"2026-10-02T10:05:46.434321Z","steps":["trace[494660440] 'agreement among raft nodes before linearized reading' (duration: 246.789701ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.43439Z","caller":"traceutil/trace.go:171","msg":"trace[2097758401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26006; }","duration":"250.968182ms","start":"2026-10-02T10:05:46.183403Z","end":"2026-10-02T10:05:46.434372Z","steps":["trace[2097758401] 'agreement among raft nodes before linearized reading' (duration: 250.838359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:46.434181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.666956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-02T10:05:46.43441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.283166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T10:05:46.434454Z","caller":"traceutil/trace.go:171","msg":"trace[2055247905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26006; }","duration":"252.353987ms","start":"2026-10-02T10:05:46.182088Z","end":"2026-10-02T10:05:46.434442Z","steps":["trace[2055247905] 'agreement among raft nodes before linearized reading' (duration: 252.220434ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.434456Z","caller":"traceutil/trace.go:171","msg":"trace[819654418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26006; }","duration":"250.981562ms","start":"2026-10-02T10:05:46.183457Z","end":"2026-10-02T10:05:46.434439Z","steps":["trace[819654418] 'agreement among raft nodes before linearized reading' (duration: 250.601805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:46.434206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.569054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T10:05:46.434514Z","caller":"traceutil/trace.go:171","msg":"trace[977426278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26006; }","duration":"249.89526ms","start":"2026-10-02T10:05:46.184604Z","end":"2026-10-02T10:05:46.434499Z","steps":["trace[977426278] 'agreement among raft nodes before linearized reading' (duration: 249.420911ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.563143Z","caller":"traceutil/trace.go:171","msg":"trace[51043747] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"123.820178ms","start":"2026-10-02T10:05:46.439297Z","end":"2026-10-02T10:05:46.563117Z","steps":["trace[51043747] 'process raft request' (duration: 123.677406ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.56315Z","caller":"traceutil/trace.go:171","msg":"trace[1584939933] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"123.88321ms","start":"2026-10-02T10:05:46.439223Z","end":"2026-10-02T10:05:46.563107Z","steps":["trace[1584939933] 'process raft request' (duration: 122.989043ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.563416Z","caller":"traceutil/trace.go:171","msg":"trace[1699693426] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"123.781438ms","start":"2026-10-02T10:05:46.439611Z","end":"2026-10-02T10:05:46.563392Z","steps":["trace[1699693426] 'process raft request' (duration: 123.460501ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:46.563446Z","caller":"traceutil/trace.go:171","msg":"trace[816626231] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"123.753627ms","start":"2026-10-02T10:05:46.439663Z","end":"2026-10-02T10:05:46.563416Z","steps":["trace[816626231] 'process raft request' (duration: 123.612584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:57.115829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.614736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:05:57.115907Z","caller":"traceutil/trace.go:171","msg":"trace[2089361072] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26093; }","duration":"170.743439ms","start":"2026-10-02T10:05:56.945144Z","end":"2026-10-02T10:05:57.115888Z","steps":["trace[2089361072] 'count revisions from in-memory index tree' (duration: 170.514445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:02.870153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.781786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:06:02.870305Z","caller":"traceutil/trace.go:171","msg":"trace[1623706063] linearizableReadLoop","detail":"{readStateIndex:26983; appliedIndex:26982; }","duration":"196.563399ms","start":"2026-10-02T10:06:02.673717Z","end":"2026-10-02T10:06:02.87028Z","steps":["trace[1623706063] 'read index received' (duration: 71.448036ms)","trace[1623706063] 'applied index is now lower than readState.Index' (duration: 125.107193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:06:02.87056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.574225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T10:06:02.870647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.473737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-02T10:06:02.870664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.182775ms","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:6"} {"level":"warn","ts":"2026-10-02T10:06:02.870658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.971935ms","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-10-02T10:06:02.87075Z","caller":"traceutil/trace.go:171","msg":"trace[857422605] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26139; }","duration":"179.081096ms","start":"2026-10-02T10:06:02.691629Z","end":"2026-10-02T10:06:02.87071Z","steps":["trace[857422605] 'agreement among raft nodes before linearized reading' (duration: 178.867002ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:02.870716Z","caller":"traceutil/trace.go:171","msg":"trace[1363466805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26139; }","duration":"187.557208ms","start":"2026-10-02T10:06:02.683138Z","end":"2026-10-02T10:06:02.870696Z","steps":["trace[1363466805] 'agreement among raft nodes before linearized reading' (duration: 187.409465ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:02.870745Z","caller":"traceutil/trace.go:171","msg":"trace[1369639112] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26139; }","duration":"134.263247ms","start":"2026-10-02T10:06:02.736439Z","end":"2026-10-02T10:06:02.870702Z","steps":["trace[1369639112] 'agreement among raft nodes before linearized reading' (duration: 134.181445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:02.870563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.831214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T10:06:02.870934Z","caller":"traceutil/trace.go:171","msg":"trace[1491713558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26139; }","duration":"191.728947ms","start":"2026-10-02T10:06:02.678938Z","end":"2026-10-02T10:06:02.870667Z","steps":["trace[1491713558] 'agreement among raft nodes before linearized reading' (duration: 191.501093ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:02.871038Z","caller":"traceutil/trace.go:171","msg":"trace[1541446486] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"258.088476ms","start":"2026-10-02T10:06:02.612905Z","end":"2026-10-02T10:06:02.870993Z","steps":["trace[1541446486] 'process raft request' (duration: 132.353891ms)","trace[1541446486] 'compare' (duration: 124.422899ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:06:02.87107Z","caller":"traceutil/trace.go:171","msg":"trace[1027711186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26139; }","duration":"197.289673ms","start":"2026-10-02T10:06:02.673674Z","end":"2026-10-02T10:06:02.870963Z","steps":["trace[1027711186] 'agreement among raft nodes before linearized reading' (duration: 196.721582ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:03.211835Z","caller":"traceutil/trace.go:171","msg":"trace[290521226] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"336.763848ms","start":"2026-10-02T10:06:02.875048Z","end":"2026-10-02T10:06:03.211812Z","steps":["trace[290521226] 'process raft request' (duration: 336.542493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.211982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:06:02.875019Z","time spent":"336.885881ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:06:03.241295Z","caller":"traceutil/trace.go:171","msg":"trace[920992844] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"365.844549ms","start":"2026-10-02T10:06:02.875424Z","end":"2026-10-02T10:06:03.241269Z","steps":["trace[920992844] 'process raft request' (duration: 365.671636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.241442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:06:02.875401Z","time spent":"365.963801ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:06:03.241613Z","caller":"traceutil/trace.go:171","msg":"trace[1066810569] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"366.067663ms","start":"2026-10-02T10:06:02.875504Z","end":"2026-10-02T10:06:03.241572Z","steps":["trace[1066810569] 'process raft request' (duration: 365.725376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.241805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:06:02.875439Z","time spent":"366.256726ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:06:03.242389Z","caller":"traceutil/trace.go:171","msg":"trace[503002976] linearizableReadLoop","detail":"{readStateIndex:26987; appliedIndex:26983; }","duration":"180.648797ms","start":"2026-10-02T10:06:03.061716Z","end":"2026-10-02T10:06:03.242365Z","steps":["trace[503002976] 'read index received' (duration: 149.927094ms)","trace[503002976] 'applied index is now lower than readState.Index' (duration: 30.720443ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:06:03.242513Z","caller":"traceutil/trace.go:171","msg":"trace[888378857] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"366.93633ms","start":"2026-10-02T10:06:02.875563Z","end":"2026-10-02T10:06:03.242499Z","steps":["trace[888378857] 'process raft request' (duration: 365.943531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.242605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:06:02.875537Z","time spent":"367.005632ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:06:03.242626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.033071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:06:03.242687Z","caller":"traceutil/trace.go:171","msg":"trace[50167795] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26143; }","duration":"174.098252ms","start":"2026-10-02T10:06:03.068571Z","end":"2026-10-02T10:06:03.24267Z","steps":["trace[50167795] 'agreement among raft nodes before linearized reading' (duration: 174.00564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.242705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.975543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:06:03.242796Z","caller":"traceutil/trace.go:171","msg":"trace[475037439] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26143; }","duration":"181.108075ms","start":"2026-10-02T10:06:03.061669Z","end":"2026-10-02T10:06:03.242777Z","steps":["trace[475037439] 'agreement among raft nodes before linearized reading' (duration: 180.942112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.471161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.30603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:06:03.471242Z","caller":"traceutil/trace.go:171","msg":"trace[1422739605] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26143; }","duration":"151.443253ms","start":"2026-10-02T10:06:03.319779Z","end":"2026-10-02T10:06:03.471223Z","steps":["trace[1422739605] 'count revisions from in-memory index tree' (duration: 151.203128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.471303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.317303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:06:03.471383Z","caller":"traceutil/trace.go:171","msg":"trace[21261320] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26143; }","duration":"167.448726ms","start":"2026-10-02T10:06:03.303915Z","end":"2026-10-02T10:06:03.471364Z","steps":["trace[21261320] 'count revisions from in-memory index tree' (duration: 167.231022ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:03.827649Z","caller":"traceutil/trace.go:171","msg":"trace[1955198660] linearizableReadLoop","detail":"{readStateIndex:26990; appliedIndex:26989; }","duration":"214.470457ms","start":"2026-10-02T10:06:03.61315Z","end":"2026-10-02T10:06:03.82762Z","steps":["trace[1955198660] 'read index received' (duration: 214.168162ms)","trace[1955198660] 'applied index is now lower than readState.Index' (duration: 300.805µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:06:03.827824Z","caller":"traceutil/trace.go:171","msg":"trace[562020425] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"231.320158ms","start":"2026-10-02T10:06:03.596488Z","end":"2026-10-02T10:06:03.827808Z","steps":["trace[562020425] 'process raft request' (duration: 230.930251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.828093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.933376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-02T10:06:03.828148Z","caller":"traceutil/trace.go:171","msg":"trace[656815301] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26146; }","duration":"215.033878ms","start":"2026-10-02T10:06:03.613095Z","end":"2026-10-02T10:06:03.828129Z","steps":["trace[656815301] 'agreement among raft nodes before linearized reading' (duration: 214.853585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:03.828214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.181244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:06:03.828298Z","caller":"traceutil/trace.go:171","msg":"trace[1490515808] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26146; }","duration":"135.330437ms","start":"2026-10-02T10:06:03.692946Z","end":"2026-10-02T10:06:03.828277Z","steps":["trace[1490515808] 'agreement among raft nodes before linearized reading' (duration: 135.147774ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:04.215249Z","caller":"traceutil/trace.go:171","msg":"trace[1156133111] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"180.823459ms","start":"2026-10-02T10:06:04.0344Z","end":"2026-10-02T10:06:04.215224Z","steps":["trace[1156133111] 'process raft request' (duration: 180.656217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:04.755334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.056568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:06:04.755585Z","caller":"traceutil/trace.go:171","msg":"trace[684960859] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26992; }","duration":"211.082193ms","start":"2026-10-02T10:06:04.544486Z","end":"2026-10-02T10:06:04.755569Z","steps":["trace[684960859] 'read index received' (duration: 91.642858ms)","trace[684960859] 'applied index is now lower than readState.Index' (duration: 119.436215ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:06:04.755609Z","caller":"traceutil/trace.go:171","msg":"trace[1839539783] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"242.396057ms","start":"2026-10-02T10:06:04.513191Z","end":"2026-10-02T10:06:04.755587Z","steps":["trace[1839539783] 'process raft request' (duration: 122.989423ms)","trace[1839539783] 'compare' (duration: 118.763273ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:06:04.75569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.220976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:06:04.755638Z","caller":"traceutil/trace.go:171","msg":"trace[2096704793] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"222.968879ms","start":"2026-10-02T10:06:04.532639Z","end":"2026-10-02T10:06:04.755608Z","steps":["trace[2096704793] 'process raft request' (duration: 222.800256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:06:04.755884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.097441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-02T10:06:04.755901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.766952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-10-02T10:06:04.755903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.651279ms","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-10-02T10:06:04.755986Z","caller":"traceutil/trace.go:171","msg":"trace[709174520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26150; }","duration":"178.768491ms","start":"2026-10-02T10:06:04.577199Z","end":"2026-10-02T10:06:04.755967Z","steps":["trace[709174520] 'agreement among raft nodes before linearized reading' (duration: 178.551927ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:04.755974Z","caller":"traceutil/trace.go:171","msg":"trace[222071744] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26150; }","duration":"138.888994ms","start":"2026-10-02T10:06:04.617066Z","end":"2026-10-02T10:06:04.755955Z","steps":["trace[222071744] 'agreement among raft nodes before linearized reading' (duration: 138.694971ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:04.755922Z","caller":"traceutil/trace.go:171","msg":"trace[362320505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26150; }","duration":"165.196684ms","start":"2026-10-02T10:06:04.590715Z","end":"2026-10-02T10:06:04.755912Z","steps":["trace[362320505] 'agreement among raft nodes before linearized reading' (duration: 165.048191ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:06:04.755741Z","caller":"traceutil/trace.go:171","msg":"trace[1179083573] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26150; }","duration":"211.290487ms","start":"2026-10-02T10:06:04.544421Z","end":"2026-10-02T10:06:04.755711Z","steps":["trace[1179083573] 'agreement among raft nodes before linearized reading' (duration: 211.235166ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:10:23.116138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25837} {"level":"info","ts":"2026-10-02T10:10:23.290965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25837,"took":"168.277572ms","hash":2763902643,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":36495360,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-02T10:10:23.291023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2763902643,"revision":25837,"compact-revision":22355} {"level":"info","ts":"2026-10-02T10:11:12.929323Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T10:11:12.936353Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-02T10:11:12.936471Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-02T10:15:23.125539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28715} {"level":"info","ts":"2026-10-02T10:15:23.280091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28715,"took":"147.859435ms","hash":646202252,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":31301632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:15:23.280179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":646202252,"revision":28715,"compact-revision":25837} {"level":"info","ts":"2026-10-02T10:20:23.133301Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30940} {"level":"info","ts":"2026-10-02T10:20:23.292778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30940,"took":"155.150302ms","hash":1093558339,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":30621696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:20:23.292899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1093558339,"revision":30940,"compact-revision":28715} {"level":"info","ts":"2026-10-02T10:25:23.141803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33194} {"level":"info","ts":"2026-10-02T10:25:23.309153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33194,"took":"162.775088ms","hash":1790888924,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":30760960,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:25:23.309232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1790888924,"revision":33194,"compact-revision":30940} {"level":"info","ts":"2026-10-02T10:30:23.149924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35457} {"level":"info","ts":"2026-10-02T10:30:23.297201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35457,"took":"142.749478ms","hash":3743690597,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T10:30:23.297275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3743690597,"revision":35457,"compact-revision":33194} {"level":"info","ts":"2026-10-02T10:32:44.8513Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T10:32:44.85702Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-02T10:32:44.857162Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-02T10:35:23.156653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37720} {"level":"info","ts":"2026-10-02T10:35:23.293133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37720,"took":"128.546899ms","hash":136078415,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":33009664,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T10:35:23.293209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":136078415,"revision":37720,"compact-revision":35457}