{"level":"warn","ts":"2026-07-24T10:07:15.516829Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T10:07:15.516985Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.12: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.19.213.12:2380","--initial-cluster=instance=https://199.19.213.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.12: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-07-24T10:07:15.517085Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T10:07:15.517102Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-07-24T10:07:15.517128Z","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-07-24T10:07:15.517847Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"]} {"level":"info","ts":"2026-07-24T10:07:15.517929Z","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.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.12: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-07-24T10:07:15.521075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.870642ms"} {"level":"info","ts":"2026-07-24T10:07:15.526218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"790561176553eafe","cluster-id":"e32c72c573d8545f"} {"level":"info","ts":"2026-07-24T10:07:15.526303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T10:07:15.526328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 0"} {"level":"info","ts":"2026-07-24T10:07:15.526344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 790561176553eafe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T10:07:15.526355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 1"} {"level":"info","ts":"2026-07-24T10:07:15.526396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"warn","ts":"2026-07-24T10:07:15.531081Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T10:07:15.53262Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T10:07:15.533811Z","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-07-24T10:07:15.536195Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"790561176553eafe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T10:07:15.536374Z","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-07-24T10:07:15.536473Z","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-07-24T10:07:15.536489Z","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-07-24T10:07:15.537221Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"790561176553eafe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T10:07:15.537822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"info","ts":"2026-07-24T10:07:15.538007Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","added-peer-id":"790561176553eafe","added-peer-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-07-24T10:07:15.543535Z","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-07-24T10:07:15.543669Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-07-24T10:07:15.54372Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-07-24T10:07:15.543845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"790561176553eafe","initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T10:07:15.543891Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T10:07:16.526651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T10:07:16.526837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T10:07:16.526878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgPreVoteResp from 790561176553eafe at term 1"} {"level":"info","ts":"2026-07-24T10:07:16.526904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became candidate at term 2"} {"level":"info","ts":"2026-07-24T10:07:16.526917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgVoteResp from 790561176553eafe at term 2"} {"level":"info","ts":"2026-07-24T10:07:16.526935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became leader at term 2"} {"level":"info","ts":"2026-07-24T10:07:16.526957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 790561176553eafe elected leader 790561176553eafe at term 2"} {"level":"info","ts":"2026-07-24T10:07:16.528027Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:07:16.529122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T10:07:16.529133Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"790561176553eafe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.12:2379]}","request-path":"/0/members/790561176553eafe/attributes","cluster-id":"e32c72c573d8545f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T10:07:16.529306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T10:07:16.529488Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T10:07:16.529561Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T10:07:16.531203Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:07:16.531341Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:07:16.531402Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:07:16.533123Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T10:07:16.533307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.12:2379"} {"level":"info","ts":"2026-07-24T10:07:36.246923Z","caller":"traceutil/trace.go:171","msg":"trace[979406257] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"112.992448ms","start":"2026-07-24T10:07:36.133891Z","end":"2026-07-24T10:07:36.246883Z","steps":["trace[979406257] 'process raft request' (duration: 112.754423ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:36.796645Z","caller":"traceutil/trace.go:171","msg":"trace[686469672] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"121.945663ms","start":"2026-07-24T10:08:36.674662Z","end":"2026-07-24T10:08:36.796608Z","steps":["trace[686469672] 'process raft request' (duration: 121.106793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:36.804896Z","caller":"traceutil/trace.go:171","msg":"trace[2118843322] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"127.440804ms","start":"2026-07-24T10:08:36.677412Z","end":"2026-07-24T10:08:36.804853Z","steps":["trace[2118843322] 'process raft request' (duration: 127.086286ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:36.804948Z","caller":"traceutil/trace.go:171","msg":"trace[1209579120] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"124.457003ms","start":"2026-07-24T10:08:36.680454Z","end":"2026-07-24T10:08:36.804911Z","steps":["trace[1209579120] 'process raft request' (duration: 124.182876ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:36.805026Z","caller":"traceutil/trace.go:171","msg":"trace[92648884] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"119.326111ms","start":"2026-07-24T10:08:36.68565Z","end":"2026-07-24T10:08:36.804976Z","steps":["trace[92648884] 'process raft request' (duration: 119.106916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:36.805057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.152334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:36.805133Z","caller":"traceutil/trace.go:171","msg":"trace[423767280] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:614; }","duration":"121.273538ms","start":"2026-07-24T10:08:36.683849Z","end":"2026-07-24T10:08:36.805123Z","steps":["trace[423767280] 'agreement among raft nodes before linearized reading' (duration: 121.138354ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:36.804941Z","caller":"traceutil/trace.go:171","msg":"trace[1127854444] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:639; }","duration":"121.037712ms","start":"2026-07-24T10:08:36.683878Z","end":"2026-07-24T10:08:36.804916Z","steps":["trace[1127854444] 'read index received' (duration: 111.960126ms)","trace[1127854444] 'applied index is now lower than readState.Index' (duration: 9.076686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:08:37.075545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.768723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:08:37.075786Z","caller":"traceutil/trace.go:171","msg":"trace[1428321008] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"182.961567ms","start":"2026-07-24T10:08:36.892776Z","end":"2026-07-24T10:08:37.075738Z","steps":["trace[1428321008] 'process raft request' (duration: 25.382415ms)","trace[1428321008] 'compare' (duration: 156.613758ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:08:37.076654Z","caller":"traceutil/trace.go:171","msg":"trace[335780855] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"181.690676ms","start":"2026-07-24T10:08:36.894399Z","end":"2026-07-24T10:08:37.07609Z","steps":["trace[335780855] 'process raft request' (duration: 181.338358ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:37.077122Z","caller":"traceutil/trace.go:171","msg":"trace[898078645] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"176.111884ms","start":"2026-07-24T10:08:36.90095Z","end":"2026-07-24T10:08:37.077061Z","steps":["trace[898078645] 'process raft request' (duration: 175.019338ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:37.079615Z","caller":"traceutil/trace.go:171","msg":"trace[559867006] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"175.721524ms","start":"2026-07-24T10:08:36.90386Z","end":"2026-07-24T10:08:37.079582Z","steps":["trace[559867006] 'process raft request' (duration: 172.973549ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:37.082608Z","caller":"traceutil/trace.go:171","msg":"trace[312914324] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"101.603299ms","start":"2026-07-24T10:08:36.980963Z","end":"2026-07-24T10:08:37.082567Z","steps":["trace[312914324] 'process raft request' (duration: 101.340223ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:43.532182Z","caller":"traceutil/trace.go:171","msg":"trace[1380152119] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"195.976227ms","start":"2026-07-24T10:08:43.336172Z","end":"2026-07-24T10:08:43.532149Z","steps":["trace[1380152119] 'process raft request' (duration: 195.72236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:44.014242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.611561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T10:08:44.014302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.570975ms","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-07-24T10:08:44.01434Z","caller":"traceutil/trace.go:171","msg":"trace[2043068837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"357.645487ms","start":"2026-07-24T10:08:43.656677Z","end":"2026-07-24T10:08:44.014323Z","steps":["trace[2043068837] 'range keys from in-memory index tree' (duration: 357.089323ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:44.014342Z","caller":"traceutil/trace.go:171","msg":"trace[76030538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"256.805126ms","start":"2026-07-24T10:08:43.757515Z","end":"2026-07-24T10:08:44.01432Z","steps":["trace[76030538] 'range keys from in-memory index tree' (duration: 256.435327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:44.014376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.909954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m7smr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-24T10:08:44.014561Z","caller":"traceutil/trace.go:171","msg":"trace[1746849669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m7smr; range_end:; response_count:1; response_revision:694; }","duration":"416.16836ms","start":"2026-07-24T10:08:43.598362Z","end":"2026-07-24T10:08:44.01453Z","steps":["trace[1746849669] 'range keys from in-memory index tree' (duration: 415.701289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:44.014374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:43.656653Z","time spent":"357.713278ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T10:08:44.014614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:43.598334Z","time spent":"416.271963ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-m7smr\" "} {"level":"info","ts":"2026-07-24T10:08:44.173256Z","caller":"traceutil/trace.go:171","msg":"trace[25103398] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"178.816997ms","start":"2026-07-24T10:08:43.994408Z","end":"2026-07-24T10:08:44.173225Z","steps":["trace[25103398] 'read index received' (duration: 177.869945ms)","trace[25103398] 'applied index is now lower than readState.Index' (duration: 946.082µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:08:44.173357Z","caller":"traceutil/trace.go:171","msg":"trace[2092426046] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"192.476403ms","start":"2026-07-24T10:08:43.980817Z","end":"2026-07-24T10:08:44.173294Z","steps":["trace[2092426046] 'process raft request' (duration: 191.626963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:44.173706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.27852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T10:08:44.1739Z","caller":"traceutil/trace.go:171","msg":"trace[1426629240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:695; }","duration":"179.454374ms","start":"2026-07-24T10:08:43.994343Z","end":"2026-07-24T10:08:44.173797Z","steps":["trace[1426629240] 'agreement among raft nodes before linearized reading' (duration: 178.993842ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:44.184517Z","caller":"traceutil/trace.go:171","msg":"trace[1222498392] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"164.576308ms","start":"2026-07-24T10:08:44.019919Z","end":"2026-07-24T10:08:44.184496Z","steps":["trace[1222498392] 'process raft request' (duration: 164.410005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:44.184556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.875171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:44.184594Z","caller":"traceutil/trace.go:171","msg":"trace[1385181515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"168.961593ms","start":"2026-07-24T10:08:44.01562Z","end":"2026-07-24T10:08:44.184582Z","steps":["trace[1385181515] 'agreement among raft nodes before linearized reading' (duration: 168.873321ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:44.184957Z","caller":"traceutil/trace.go:171","msg":"trace[1660178833] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"155.935064ms","start":"2026-07-24T10:08:44.029011Z","end":"2026-07-24T10:08:44.184946Z","steps":["trace[1660178833] 'process raft request' (duration: 155.427391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:00.517442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.271095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-24T10:09:00.517538Z","caller":"traceutil/trace.go:171","msg":"trace[985762996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"246.429328ms","start":"2026-07-24T10:09:00.271087Z","end":"2026-07-24T10:09:00.517516Z","steps":["trace[985762996] 'range keys from in-memory index tree' (duration: 246.06651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:00.51765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.633424ms","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-07-24T10:09:00.517733Z","caller":"traceutil/trace.go:171","msg":"trace[774919509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"233.762787ms","start":"2026-07-24T10:09:00.283953Z","end":"2026-07-24T10:09:00.517716Z","steps":["trace[774919509] 'range keys from in-memory index tree' (duration: 233.475541ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:49.236571Z","caller":"traceutil/trace.go:171","msg":"trace[1071511452] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"214.800682ms","start":"2026-07-24T10:09:49.02172Z","end":"2026-07-24T10:09:49.23652Z","steps":["trace[1071511452] 'process raft request' (duration: 214.597967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:14.350278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.433335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"warn","ts":"2026-07-24T10:10:14.350318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.192499ms","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":"warn","ts":"2026-07-24T10:10:14.350326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.849041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a94d4583-5ff2-44e6-902a-a5c22199f1f5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-24T10:10:14.350378Z","caller":"traceutil/trace.go:171","msg":"trace[1955798205] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1912; }","duration":"290.607198ms","start":"2026-07-24T10:10:14.059746Z","end":"2026-07-24T10:10:14.350353Z","steps":["trace[1955798205] 'range keys from in-memory index tree' (duration: 290.155419ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:14.350397Z","caller":"traceutil/trace.go:171","msg":"trace[1150543862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1912; }","duration":"272.298191ms","start":"2026-07-24T10:10:14.078078Z","end":"2026-07-24T10:10:14.350376Z","steps":["trace[1150543862] 'range keys from in-memory index tree' (duration: 272.007184ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:14.350409Z","caller":"traceutil/trace.go:171","msg":"trace[343648010] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a94d4583-5ff2-44e6-902a-a5c22199f1f5; range_end:; response_count:1; response_revision:1912; }","duration":"271.971574ms","start":"2026-07-24T10:10:14.078415Z","end":"2026-07-24T10:10:14.350387Z","steps":["trace[343648010] 'range keys from in-memory index tree' (duration: 271.438652ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:12:21.900825Z","caller":"traceutil/trace.go:171","msg":"trace[685380250] linearizableReadLoop","detail":"{readStateIndex:2821; appliedIndex:2820; }","duration":"215.131235ms","start":"2026-07-24T10:12:21.68567Z","end":"2026-07-24T10:12:21.900801Z","steps":["trace[685380250] 'read index received' (duration: 214.930311ms)","trace[685380250] 'applied index is now lower than readState.Index' (duration: 200.204µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:12:21.900876Z","caller":"traceutil/trace.go:171","msg":"trace[1641244433] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"232.990638ms","start":"2026-07-24T10:12:21.667858Z","end":"2026-07-24T10:12:21.900848Z","steps":["trace[1641244433] 'process raft request' (duration: 232.799334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:12:21.901049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.361261ms","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-07-24T10:12:21.901093Z","caller":"traceutil/trace.go:171","msg":"trace[1414182723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2711; }","duration":"215.464592ms","start":"2026-07-24T10:12:21.685618Z","end":"2026-07-24T10:12:21.901083Z","steps":["trace[1414182723] 'agreement among raft nodes before linearized reading' (duration: 215.301799ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:34.763224Z","caller":"traceutil/trace.go:171","msg":"trace[607741666] transaction","detail":"{read_only:false; response_revision:3563; number_of_response:1; }","duration":"103.897784ms","start":"2026-07-24T10:14:34.659289Z","end":"2026-07-24T10:14:34.763187Z","steps":["trace[607741666] 'process raft request' (duration: 103.562207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:28.704652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.918434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T10:16:28.704748Z","caller":"traceutil/trace.go:171","msg":"trace[531240581] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4911; }","duration":"148.075597ms","start":"2026-07-24T10:16:28.556652Z","end":"2026-07-24T10:16:28.704727Z","steps":["trace[531240581] 'count revisions from in-memory index tree' (duration: 147.822811ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:42.421245Z","caller":"traceutil/trace.go:171","msg":"trace[856991486] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"124.811762ms","start":"2026-07-24T10:16:42.296413Z","end":"2026-07-24T10:16:42.421224Z","steps":["trace[856991486] 'process raft request' (duration: 124.766481ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:42.42126Z","caller":"traceutil/trace.go:171","msg":"trace[1101570384] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"125.270993ms","start":"2026-07-24T10:16:42.295962Z","end":"2026-07-24T10:16:42.421233Z","steps":["trace[1101570384] 'process raft request' (duration: 82.455784ms)","trace[1101570384] 'compare' (duration: 42.596894ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:16:42.671818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.515011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:16:42.671952Z","caller":"traceutil/trace.go:171","msg":"trace[379744016] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"160.028111ms","start":"2026-07-24T10:16:42.511911Z","end":"2026-07-24T10:16:42.671939Z","steps":["trace[379744016] 'process raft request' (duration: 159.98439ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:42.672143Z","caller":"traceutil/trace.go:171","msg":"trace[674093546] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"160.344148ms","start":"2026-07-24T10:16:42.511791Z","end":"2026-07-24T10:16:42.672135Z","steps":["trace[674093546] 'compare' (duration: 150.413179ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:49.890233Z","caller":"traceutil/trace.go:171","msg":"trace[1617277222] linearizableReadLoop","detail":"{readStateIndex:5563; appliedIndex:5562; }","duration":"133.576116ms","start":"2026-07-24T10:16:49.756582Z","end":"2026-07-24T10:16:49.890159Z","steps":["trace[1617277222] 'read index received' (duration: 122.523721ms)","trace[1617277222] 'applied index is now lower than readState.Index' (duration: 11.050445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:16:49.890292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.690499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:16:49.890371Z","caller":"traceutil/trace.go:171","msg":"trace[1395320498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5358; }","duration":"133.790441ms","start":"2026-07-24T10:16:49.756557Z","end":"2026-07-24T10:16:49.890348Z","steps":["trace[1395320498] 'agreement among raft nodes before linearized reading' (duration: 133.680179ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:50.3171Z","caller":"traceutil/trace.go:171","msg":"trace[1588114320] linearizableReadLoop","detail":"{readStateIndex:5567; appliedIndex:5566; }","duration":"184.135245ms","start":"2026-07-24T10:16:50.13294Z","end":"2026-07-24T10:16:50.317075Z","steps":["trace[1588114320] 'read index received' (duration: 182.193571ms)","trace[1588114320] 'applied index is now lower than readState.Index' (duration: 1.940594ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:16:50.317207Z","caller":"traceutil/trace.go:171","msg":"trace[1198970204] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"188.516192ms","start":"2026-07-24T10:16:50.128645Z","end":"2026-07-24T10:16:50.317161Z","steps":["trace[1198970204] 'process raft request' (duration: 186.518868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:50.317307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.345309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-24T10:16:50.317346Z","caller":"traceutil/trace.go:171","msg":"trace[1151743604] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5362; }","duration":"184.427771ms","start":"2026-07-24T10:16:50.132907Z","end":"2026-07-24T10:16:50.317335Z","steps":["trace[1151743604] 'agreement among raft nodes before linearized reading' (duration: 184.256728ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:52.441104Z","caller":"traceutil/trace.go:171","msg":"trace[266777416] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"226.43093ms","start":"2026-07-24T10:16:52.214648Z","end":"2026-07-24T10:16:52.441079Z","steps":["trace[266777416] 'process raft request' (duration: 225.51631ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:17:16.555816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2684} {"level":"info","ts":"2026-07-24T10:17:16.628105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2684,"took":"71.003452ms","hash":3499941840,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":31383552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T10:17:16.6282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3499941840,"revision":2684,"compact-revision":-1} {"level":"info","ts":"2026-07-24T10:17:37.698723Z","caller":"traceutil/trace.go:171","msg":"trace[1495900682] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"113.260644ms","start":"2026-07-24T10:17:37.585432Z","end":"2026-07-24T10:17:37.698693Z","steps":["trace[1495900682] 'process raft request' (duration: 112.992908ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:22:16.562888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5632} {"level":"info","ts":"2026-07-24T10:22:16.651724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5632,"took":"86.914038ms","hash":3692325081,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":25133056,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-24T10:22:16.651788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3692325081,"revision":5632,"compact-revision":2684} {"level":"warn","ts":"2026-07-24T10:22:47.945034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.674189ms","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-07-24T10:22:47.945113Z","caller":"traceutil/trace.go:171","msg":"trace[1449134041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8156; }","duration":"161.795062ms","start":"2026-07-24T10:22:47.783304Z","end":"2026-07-24T10:22:47.945099Z","steps":["trace[1449134041] 'range keys from in-memory index tree' (duration: 161.540886ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:22:48.801014Z","caller":"traceutil/trace.go:171","msg":"trace[176168587] linearizableReadLoop","detail":"{readStateIndex:8454; appliedIndex:8453; }","duration":"114.850041ms","start":"2026-07-24T10:22:48.686135Z","end":"2026-07-24T10:22:48.800985Z","steps":["trace[176168587] 'read index received' (duration: 26.149754ms)","trace[176168587] 'applied index is now lower than readState.Index' (duration: 88.698796ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:22:48.801181Z","caller":"traceutil/trace.go:171","msg":"trace[241416275] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"128.98243ms","start":"2026-07-24T10:22:48.672184Z","end":"2026-07-24T10:22:48.801167Z","steps":["trace[241416275] 'process raft request' (duration: 40.15434ms)","trace[241416275] 'compare' (duration: 88.517673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:22:48.801444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.276432ms","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":"info","ts":"2026-07-24T10:22:48.801528Z","caller":"traceutil/trace.go:171","msg":"trace[1059328728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8160; }","duration":"115.500896ms","start":"2026-07-24T10:22:48.686007Z","end":"2026-07-24T10:22:48.801508Z","steps":["trace[1059328728] 'agreement among raft nodes before linearized reading' (duration: 115.343002ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:22:49.182213Z","caller":"traceutil/trace.go:171","msg":"trace[728318511] transaction","detail":"{read_only:false; response_revision:8161; number_of_response:1; }","duration":"251.549102ms","start":"2026-07-24T10:22:48.930629Z","end":"2026-07-24T10:22:49.182178Z","steps":["trace[728318511] 'process raft request' (duration: 157.347384ms)","trace[728318511] 'compare' (duration: 93.825829ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:23:15.682054Z","caller":"traceutil/trace.go:171","msg":"trace[1813842364] linearizableReadLoop","detail":"{readStateIndex:8656; appliedIndex:8655; }","duration":"100.989157ms","start":"2026-07-24T10:23:15.581036Z","end":"2026-07-24T10:23:15.682025Z","steps":["trace[1813842364] 'read index received' (duration: 100.934116ms)","trace[1813842364] 'applied index is now lower than readState.Index' (duration: 54.011µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:23:15.682157Z","caller":"traceutil/trace.go:171","msg":"trace[822579619] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"220.597903ms","start":"2026-07-24T10:23:15.461508Z","end":"2026-07-24T10:23:15.682106Z","steps":["trace[822579619] 'process raft request' (duration: 220.358437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:23:15.682361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.304543ms","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-07-24T10:23:15.682453Z","caller":"traceutil/trace.go:171","msg":"trace[1529742206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8355; }","duration":"101.442867ms","start":"2026-07-24T10:23:15.580988Z","end":"2026-07-24T10:23:15.682431Z","steps":["trace[1529742206] 'agreement among raft nodes before linearized reading' (duration: 101.192991ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:23:15.705956Z","caller":"traceutil/trace.go:171","msg":"trace[768019659] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"109.102005ms","start":"2026-07-24T10:23:15.596834Z","end":"2026-07-24T10:23:15.705936Z","steps":["trace[768019659] 'process raft request' (duration: 108.474281ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:23:15.840559Z","caller":"traceutil/trace.go:171","msg":"trace[76886949] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"153.835747ms","start":"2026-07-24T10:23:15.68669Z","end":"2026-07-24T10:23:15.840525Z","steps":["trace[76886949] 'process raft request' (duration: 139.861261ms)","trace[76886949] 'compare' (duration: 13.530107ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:23:15.840745Z","caller":"traceutil/trace.go:171","msg":"trace[1724777886] transaction","detail":"{read_only:false; response_revision:8358; number_of_response:1; }","duration":"129.680396ms","start":"2026-07-24T10:23:15.71103Z","end":"2026-07-24T10:23:15.84071Z","steps":["trace[1724777886] 'process raft request' (duration: 129.420651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:23:15.840941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.074445ms","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-07-24T10:23:15.841173Z","caller":"traceutil/trace.go:171","msg":"trace[2061283912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8356; }","duration":"191.77592ms","start":"2026-07-24T10:23:15.649211Z","end":"2026-07-24T10:23:15.840987Z","steps":["trace[2061283912] 'agreement among raft nodes before linearized reading' (duration: 56.873509ms)","trace[2061283912] 'range keys from in-memory index tree' (duration: 134.051002ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:23:29.162099Z","caller":"traceutil/trace.go:171","msg":"trace[2047532238] transaction","detail":"{read_only:false; response_revision:8470; number_of_response:1; }","duration":"141.086004ms","start":"2026-07-24T10:23:29.020976Z","end":"2026-07-24T10:23:29.162062Z","steps":["trace[2047532238] 'process raft request' (duration: 140.912989ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:23:29.469943Z","caller":"traceutil/trace.go:171","msg":"trace[387206156] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"157.482241ms","start":"2026-07-24T10:23:29.31242Z","end":"2026-07-24T10:23:29.469902Z","steps":["trace[387206156] 'process raft request' (duration: 157.306135ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:23:30.421387Z","caller":"traceutil/trace.go:171","msg":"trace[1592775239] linearizableReadLoop","detail":"{readStateIndex:8785; appliedIndex:8784; }","duration":"153.885681ms","start":"2026-07-24T10:23:30.267472Z","end":"2026-07-24T10:23:30.421357Z","steps":["trace[1592775239] 'read index received' (duration: 153.652803ms)","trace[1592775239] 'applied index is now lower than readState.Index' (duration: 231.848µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:23:30.421441Z","caller":"traceutil/trace.go:171","msg":"trace[1845621103] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"298.844275ms","start":"2026-07-24T10:23:30.122554Z","end":"2026-07-24T10:23:30.421399Z","steps":["trace[1845621103] 'process raft request' (duration: 298.531965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:23:30.421724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.228807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2gj86\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-24T10:23:30.421773Z","caller":"traceutil/trace.go:171","msg":"trace[96847119] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2gj86; range_end:; response_count:1; response_revision:8479; }","duration":"137.362991ms","start":"2026-07-24T10:23:30.284398Z","end":"2026-07-24T10:23:30.421761Z","steps":["trace[96847119] 'agreement among raft nodes before linearized reading' (duration: 137.120023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:23:30.421734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.142149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T10:23:30.421845Z","caller":"traceutil/trace.go:171","msg":"trace[198182294] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8479; }","duration":"154.409338ms","start":"2026-07-24T10:23:30.267413Z","end":"2026-07-24T10:23:30.421822Z","steps":["trace[198182294] 'agreement among raft nodes before linearized reading' (duration: 154.028976ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:23:31.0635Z","caller":"traceutil/trace.go:171","msg":"trace[1834989740] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"151.74627ms","start":"2026-07-24T10:23:30.911721Z","end":"2026-07-24T10:23:31.063468Z","steps":["trace[1834989740] 'process raft request' (duration: 151.500622ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:24:37.832715Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e0.wal"} {"level":"info","ts":"2026-07-24T10:24:37.833516Z","caller":"traceutil/trace.go:171","msg":"trace[1299879411] linearizableReadLoop","detail":"{readStateIndex:9439; appliedIndex:9438; }","duration":"300.82381ms","start":"2026-07-24T10:24:37.532671Z","end":"2026-07-24T10:24:37.833495Z","steps":["trace[1299879411] 'read index received' (duration: 300.180666ms)","trace[1299879411] 'applied index is now lower than readState.Index' (duration: 622.534µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:24:37.833601Z","caller":"traceutil/trace.go:171","msg":"trace[1479428450] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"431.685813ms","start":"2026-07-24T10:24:37.401896Z","end":"2026-07-24T10:24:37.833582Z","steps":["trace[1479428450] 'process raft request' (duration: 431.256434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:24:37.833697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.026426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-07-24T10:24:37.83373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:24:37.401867Z","time spent":"431.780505ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:24:37.83375Z","caller":"traceutil/trace.go:171","msg":"trace[1116527282] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9113; }","duration":"301.124858ms","start":"2026-07-24T10:24:37.532612Z","end":"2026-07-24T10:24:37.833736Z","steps":["trace[1116527282] 'agreement among raft nodes before linearized reading' (duration: 301.016666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:24:37.83378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:24:37.532589Z","time spent":"301.180249ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T10:24:37.833924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.608347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-07-24T10:24:37.834047Z","caller":"traceutil/trace.go:171","msg":"trace[1170049342] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9113; }","duration":"199.73579ms","start":"2026-07-24T10:24:37.634264Z","end":"2026-07-24T10:24:37.834Z","steps":["trace[1170049342] 'agreement among raft nodes before linearized reading' (duration: 199.413713ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:25:40.572545Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T10:25:40.580268Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T10:25:40.580391Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T10:27:16.569486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7813} {"level":"info","ts":"2026-07-24T10:27:16.635152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7813,"took":"63.210873ms","hash":2232778549,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":29024256,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-24T10:27:16.635232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2232778549,"revision":7813,"compact-revision":5632} {"level":"info","ts":"2026-07-24T10:28:01.460362Z","caller":"traceutil/trace.go:171","msg":"trace[213846549] transaction","detail":"{read_only:false; response_revision:10950; number_of_response:1; }","duration":"128.829408ms","start":"2026-07-24T10:28:01.331489Z","end":"2026-07-24T10:28:01.460318Z","steps":["trace[213846549] 'process raft request' (duration: 128.614044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:28:01.460431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.171169ms","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-07-24T10:28:01.460516Z","caller":"traceutil/trace.go:171","msg":"trace[1125426612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10950; }","duration":"135.288662ms","start":"2026-07-24T10:28:01.325205Z","end":"2026-07-24T10:28:01.460494Z","steps":["trace[1125426612] 'agreement among raft nodes before linearized reading' (duration: 135.075747ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:32:16.575611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10479} {"level":"info","ts":"2026-07-24T10:32:16.658755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10479,"took":"80.763436ms","hash":1064157282,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":26673152,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-24T10:32:16.658828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1064157282,"revision":10479,"compact-revision":7813} {"level":"info","ts":"2026-07-24T10:34:27.514399Z","caller":"traceutil/trace.go:171","msg":"trace[1812693692] transaction","detail":"{read_only:false; response_revision:13537; number_of_response:1; }","duration":"117.196526ms","start":"2026-07-24T10:34:27.397103Z","end":"2026-07-24T10:34:27.514299Z","steps":["trace[1812693692] 'process raft request' (duration: 116.623113ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:27.515763Z","caller":"traceutil/trace.go:171","msg":"trace[1919345515] transaction","detail":"{read_only:false; response_revision:13538; number_of_response:1; }","duration":"117.380591ms","start":"2026-07-24T10:34:27.398156Z","end":"2026-07-24T10:34:27.515536Z","steps":["trace[1919345515] 'process raft request' (duration: 116.669856ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:35.125464Z","caller":"traceutil/trace.go:171","msg":"trace[224663562] transaction","detail":"{read_only:false; response_revision:13587; number_of_response:1; }","duration":"132.478524ms","start":"2026-07-24T10:34:34.99295Z","end":"2026-07-24T10:34:35.125428Z","steps":["trace[224663562] 'process raft request' (duration: 132.323241ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:35.14165Z","caller":"traceutil/trace.go:171","msg":"trace[959801919] transaction","detail":"{read_only:false; response_revision:13588; number_of_response:1; }","duration":"133.700859ms","start":"2026-07-24T10:34:35.007927Z","end":"2026-07-24T10:34:35.141628Z","steps":["trace[959801919] 'process raft request' (duration: 133.447064ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:35.181905Z","caller":"traceutil/trace.go:171","msg":"trace[319873982] linearizableReadLoop","detail":"{readStateIndex:14061; appliedIndex:14059; }","duration":"154.559468ms","start":"2026-07-24T10:34:35.027315Z","end":"2026-07-24T10:34:35.181874Z","steps":["trace[319873982] 'read index received' (duration: 98.141768ms)","trace[319873982] 'applied index is now lower than readState.Index' (duration: 56.41617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:34:35.182174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.843155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-24T10:34:35.18225Z","caller":"traceutil/trace.go:171","msg":"trace[1111467016] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13588; }","duration":"154.925587ms","start":"2026-07-24T10:34:35.027304Z","end":"2026-07-24T10:34:35.182229Z","steps":["trace[1111467016] 'agreement among raft nodes before linearized reading' (duration: 154.726762ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:36.210091Z","caller":"traceutil/trace.go:171","msg":"trace[1232161363] transaction","detail":"{read_only:false; response_revision:13595; number_of_response:1; }","duration":"185.711284ms","start":"2026-07-24T10:34:36.024354Z","end":"2026-07-24T10:34:36.210066Z","steps":["trace[1232161363] 'process raft request' (duration: 185.55708ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:36.210271Z","caller":"traceutil/trace.go:171","msg":"trace[380571874] linearizableReadLoop","detail":"{readStateIndex:14068; appliedIndex:14068; }","duration":"103.326892ms","start":"2026-07-24T10:34:36.10691Z","end":"2026-07-24T10:34:36.210237Z","steps":["trace[380571874] 'read index received' (duration: 103.315542ms)","trace[380571874] 'applied index is now lower than readState.Index' (duration: 8.89µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:34:36.210576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.540477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-24T10:34:36.210618Z","caller":"traceutil/trace.go:171","msg":"trace[634639245] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13595; }","duration":"103.757631ms","start":"2026-07-24T10:34:36.106848Z","end":"2026-07-24T10:34:36.210606Z","steps":["trace[634639245] 'agreement among raft nodes before linearized reading' (duration: 103.473505ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:34:44.398531Z","caller":"traceutil/trace.go:171","msg":"trace[1672550787] transaction","detail":"{read_only:false; response_revision:13748; number_of_response:1; }","duration":"116.126543ms","start":"2026-07-24T10:34:44.282327Z","end":"2026-07-24T10:34:44.398453Z","steps":["trace[1672550787] 'process raft request' (duration: 115.886318ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:36:17.902071Z","caller":"traceutil/trace.go:171","msg":"trace[1206062257] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"102.638727ms","start":"2026-07-24T10:36:17.799393Z","end":"2026-07-24T10:36:17.902032Z","steps":["trace[1206062257] 'process raft request' (duration: 102.368131ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:36:18.055405Z","caller":"traceutil/trace.go:171","msg":"trace[1218001844] linearizableReadLoop","detail":"{readStateIndex:15314; appliedIndex:15313; }","duration":"135.06829ms","start":"2026-07-24T10:36:17.92031Z","end":"2026-07-24T10:36:18.055378Z","steps":["trace[1218001844] 'read index received' (duration: 70.04264ms)","trace[1218001844] 'applied index is now lower than readState.Index' (duration: 65.02416ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:36:18.055546Z","caller":"traceutil/trace.go:171","msg":"trace[173185716] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"139.647329ms","start":"2026-07-24T10:36:17.915862Z","end":"2026-07-24T10:36:18.055509Z","steps":["trace[173185716] 'process raft request' (duration: 74.550268ms)","trace[173185716] 'compare' (duration: 64.724113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:36:18.055683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.350546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-07-24T10:36:18.055695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.932744ms","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-07-24T10:36:18.055825Z","caller":"traceutil/trace.go:171","msg":"trace[1643960873] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14808; }","duration":"134.054876ms","start":"2026-07-24T10:36:17.921727Z","end":"2026-07-24T10:36:18.055782Z","steps":["trace[1643960873] 'agreement among raft nodes before linearized reading' (duration: 133.887953ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:36:18.055732Z","caller":"traceutil/trace.go:171","msg":"trace[1717207771] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14808; }","duration":"135.434458ms","start":"2026-07-24T10:36:17.920285Z","end":"2026-07-24T10:36:18.055719Z","steps":["trace[1717207771] 'agreement among raft nodes before linearized reading' (duration: 135.232394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:36:18.056117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.588354ms","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-07-24T10:36:18.056184Z","caller":"traceutil/trace.go:171","msg":"trace[1082878734] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14808; }","duration":"126.708017ms","start":"2026-07-24T10:36:17.929461Z","end":"2026-07-24T10:36:18.056169Z","steps":["trace[1082878734] 'agreement among raft nodes before linearized reading' (duration: 126.511552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:36:19.207015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.37216ms","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-07-24T10:36:19.207134Z","caller":"traceutil/trace.go:171","msg":"trace[618141287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14813; }","duration":"243.495323ms","start":"2026-07-24T10:36:18.963618Z","end":"2026-07-24T10:36:19.207113Z","steps":["trace[618141287] 'range keys from in-memory index tree' (duration: 243.207087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:36:19.207012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.215904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T10:36:19.207258Z","caller":"traceutil/trace.go:171","msg":"trace[975741970] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:14813; }","duration":"266.508139ms","start":"2026-07-24T10:36:18.940719Z","end":"2026-07-24T10:36:19.207227Z","steps":["trace[975741970] 'count revisions from in-memory index tree' (duration: 266.166343ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:37:16.583239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12585} {"level":"info","ts":"2026-07-24T10:37:16.659745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12585,"took":"72.005373ms","hash":3103857763,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":30502912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T10:37:16.659804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3103857763,"revision":12585,"compact-revision":10479} {"level":"info","ts":"2026-07-24T10:42:02.040032Z","caller":"traceutil/trace.go:171","msg":"trace[1138078757] transaction","detail":"{read_only:false; response_revision:17550; number_of_response:1; }","duration":"140.343551ms","start":"2026-07-24T10:42:01.899664Z","end":"2026-07-24T10:42:02.040007Z","steps":["trace[1138078757] 'process raft request' (duration: 140.169777ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:42:02.066469Z","caller":"traceutil/trace.go:171","msg":"trace[600557220] transaction","detail":"{read_only:false; response_revision:17552; number_of_response:1; }","duration":"116.948393ms","start":"2026-07-24T10:42:01.94949Z","end":"2026-07-24T10:42:02.066439Z","steps":["trace[600557220] 'process raft request' (duration: 116.894701ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:42:02.066538Z","caller":"traceutil/trace.go:171","msg":"trace[118970345] transaction","detail":"{read_only:false; response_revision:17551; number_of_response:1; }","duration":"165.057529ms","start":"2026-07-24T10:42:01.901411Z","end":"2026-07-24T10:42:02.066469Z","steps":["trace[118970345] 'process raft request' (duration: 164.790453ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:42:07.618125Z","caller":"traceutil/trace.go:171","msg":"trace[1039557743] transaction","detail":"{read_only:false; response_revision:17602; number_of_response:1; }","duration":"384.609516ms","start":"2026-07-24T10:42:07.23348Z","end":"2026-07-24T10:42:07.618089Z","steps":["trace[1039557743] 'process raft request' (duration: 384.375831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:42:07.618353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:42:07.233443Z","time spent":"384.79567ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:42:07.669236Z","caller":"traceutil/trace.go:171","msg":"trace[85984593] linearizableReadLoop","detail":"{readStateIndex:18196; appliedIndex:18194; }","duration":"185.775568ms","start":"2026-07-24T10:42:07.483436Z","end":"2026-07-24T10:42:07.669211Z","steps":["trace[85984593] 'read index received' (duration: 134.362889ms)","trace[85984593] 'applied index is now lower than readState.Index' (duration: 51.411839ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:42:07.669418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.968772ms","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-07-24T10:42:07.669451Z","caller":"traceutil/trace.go:171","msg":"trace[1828973211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17603; }","duration":"186.088205ms","start":"2026-07-24T10:42:07.48335Z","end":"2026-07-24T10:42:07.669438Z","steps":["trace[1828973211] 'agreement among raft nodes before linearized reading' (duration: 185.960622ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:42:07.669462Z","caller":"traceutil/trace.go:171","msg":"trace[284041924] transaction","detail":"{read_only:false; response_revision:17603; number_of_response:1; }","duration":"426.330121ms","start":"2026-07-24T10:42:07.24305Z","end":"2026-07-24T10:42:07.66938Z","steps":["trace[284041924] 'process raft request' (duration: 422.764432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:42:07.669739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:42:07.243029Z","time spent":"426.569387ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20866,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:42:16.589153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15193} {"level":"info","ts":"2026-07-24T10:42:16.691883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15193,"took":"99.053855ms","hash":717325836,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T10:42:16.69194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":717325836,"revision":15193,"compact-revision":12585} {"level":"info","ts":"2026-07-24T10:45:17.669309Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T10:45:17.676893Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T10:45:17.677006Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-24T10:47:16.598692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17672} {"level":"info","ts":"2026-07-24T10:47:16.748516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17672,"took":"145.74655ms","hash":744828587,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":31559680,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T10:47:16.74861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":744828587,"revision":17672,"compact-revision":15193} {"level":"info","ts":"2026-07-24T10:48:29.798773Z","caller":"traceutil/trace.go:171","msg":"trace[2046312225] transaction","detail":"{read_only:false; response_revision:20708; number_of_response:1; }","duration":"127.540277ms","start":"2026-07-24T10:48:29.6712Z","end":"2026-07-24T10:48:29.79874Z","steps":["trace[2046312225] 'process raft request' (duration: 79.499815ms)","trace[2046312225] 'compare' (duration: 47.90603ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:48:50.294463Z","caller":"traceutil/trace.go:171","msg":"trace[522343745] transaction","detail":"{read_only:false; response_revision:20811; number_of_response:1; }","duration":"126.226381ms","start":"2026-07-24T10:48:50.168211Z","end":"2026-07-24T10:48:50.294437Z","steps":["trace[522343745] 'process raft request' (duration: 126.095479ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:48:50.296812Z","caller":"traceutil/trace.go:171","msg":"trace[314183671] transaction","detail":"{read_only:false; response_revision:20812; number_of_response:1; }","duration":"126.374875ms","start":"2026-07-24T10:48:50.170418Z","end":"2026-07-24T10:48:50.296793Z","steps":["trace[314183671] 'process raft request' (duration: 126.034087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:48:56.121101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.568686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:48:56.121217Z","caller":"traceutil/trace.go:171","msg":"trace[939742477] linearizableReadLoop","detail":"{readStateIndex:21539; appliedIndex:21538; }","duration":"202.736856ms","start":"2026-07-24T10:48:55.918464Z","end":"2026-07-24T10:48:56.1212Z","steps":["trace[939742477] 'read index received' (duration: 57.958125ms)","trace[939742477] 'applied index is now lower than readState.Index' (duration: 144.777521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:48:56.121348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.89075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-24T10:48:56.121389Z","caller":"traceutil/trace.go:171","msg":"trace[847347283] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20841; }","duration":"202.988101ms","start":"2026-07-24T10:48:55.918386Z","end":"2026-07-24T10:48:56.121374Z","steps":["trace[847347283] 'agreement among raft nodes before linearized reading' (duration: 202.860579ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:48:56.121579Z","caller":"traceutil/trace.go:171","msg":"trace[920300557] transaction","detail":"{read_only:false; response_revision:20841; number_of_response:1; }","duration":"334.379187ms","start":"2026-07-24T10:48:55.786947Z","end":"2026-07-24T10:48:56.121326Z","steps":["trace[920300557] 'process raft request' (duration: 189.497175ms)","trace[920300557] 'compare' (duration: 144.443914ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:48:56.121829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:48:55.78693Z","time spent":"334.757536ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:48:56.418408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.510634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:48:56.418535Z","caller":"traceutil/trace.go:171","msg":"trace[1981587738] linearizableReadLoop","detail":"{readStateIndex:21540; appliedIndex:21539; }","duration":"288.807415ms","start":"2026-07-24T10:48:56.129712Z","end":"2026-07-24T10:48:56.41852Z","steps":["trace[1981587738] 'read index received' (duration: 125.010955ms)","trace[1981587738] 'applied index is now lower than readState.Index' (duration: 163.79509ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:48:56.418602Z","caller":"traceutil/trace.go:171","msg":"trace[855629727] transaction","detail":"{read_only:false; response_revision:20842; number_of_response:1; }","duration":"292.179983ms","start":"2026-07-24T10:48:56.126411Z","end":"2026-07-24T10:48:56.418591Z","steps":["trace[855629727] 'process raft request' (duration: 128.403294ms)","trace[855629727] 'compare' (duration: 163.373361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:48:56.418701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.964169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:48:56.418788Z","caller":"traceutil/trace.go:171","msg":"trace[2117673903] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:20842; }","duration":"289.090161ms","start":"2026-07-24T10:48:56.129671Z","end":"2026-07-24T10:48:56.418761Z","steps":["trace[2117673903] 'agreement among raft nodes before linearized reading' (duration: 288.952138ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:48:56.539689Z","caller":"traceutil/trace.go:171","msg":"trace[574195091] linearizableReadLoop","detail":"{readStateIndex:21541; appliedIndex:21540; }","duration":"121.098596ms","start":"2026-07-24T10:48:56.418561Z","end":"2026-07-24T10:48:56.53966Z","steps":["trace[574195091] 'read index received' (duration: 110.503359ms)","trace[574195091] 'applied index is now lower than readState.Index' (duration: 10.593967ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:48:56.539719Z","caller":"traceutil/trace.go:171","msg":"trace[1038388702] transaction","detail":"{read_only:false; response_revision:20843; number_of_response:1; }","duration":"224.461959ms","start":"2026-07-24T10:48:56.315216Z","end":"2026-07-24T10:48:56.539678Z","steps":["trace[1038388702] 'process raft request' (duration: 213.874382ms)","trace[1038388702] 'compare' (duration: 10.447634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:48:56.539785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.805162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T10:48:56.539857Z","caller":"traceutil/trace.go:171","msg":"trace[339461123] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:20843; }","duration":"125.928825ms","start":"2026-07-24T10:48:56.413911Z","end":"2026-07-24T10:48:56.53984Z","steps":["trace[339461123] 'agreement among raft nodes before linearized reading' (duration: 125.790422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:48:56.539996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.240714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-24T10:48:56.540001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.858134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T10:48:56.540052Z","caller":"traceutil/trace.go:171","msg":"trace[1730593855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20843; }","duration":"221.359246ms","start":"2026-07-24T10:48:56.318676Z","end":"2026-07-24T10:48:56.540036Z","steps":["trace[1730593855] 'agreement among raft nodes before linearized reading' (duration: 221.157112ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:48:56.540086Z","caller":"traceutil/trace.go:171","msg":"trace[168528738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20843; }","duration":"219.004938ms","start":"2026-07-24T10:48:56.321061Z","end":"2026-07-24T10:48:56.540066Z","steps":["trace[168528738] 'agreement among raft nodes before linearized reading' (duration: 218.719902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:48:56.54044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.643302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-24T10:48:56.540756Z","caller":"traceutil/trace.go:171","msg":"trace[1617288445] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:20843; }","duration":"110.961728ms","start":"2026-07-24T10:48:56.429764Z","end":"2026-07-24T10:48:56.540726Z","steps":["trace[1617288445] 'agreement among raft nodes before linearized reading' (duration: 110.133781ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:52:16.60432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20202} {"level":"info","ts":"2026-07-24T10:52:16.711913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20202,"took":"103.426792ms","hash":4030547107,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T10:52:16.711975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4030547107,"revision":20202,"compact-revision":17672} {"level":"info","ts":"2026-07-24T10:52:23.707535Z","caller":"traceutil/trace.go:171","msg":"trace[1322780999] transaction","detail":"{read_only:false; response_revision:23026; number_of_response:1; }","duration":"105.759362ms","start":"2026-07-24T10:52:23.601748Z","end":"2026-07-24T10:52:23.707507Z","steps":["trace[1322780999] 'process raft request' (duration: 105.475956ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:52:23.86156Z","caller":"traceutil/trace.go:171","msg":"trace[1941089977] transaction","detail":"{read_only:false; response_revision:23028; number_of_response:1; }","duration":"145.393098ms","start":"2026-07-24T10:52:23.716107Z","end":"2026-07-24T10:52:23.8615Z","steps":["trace[1941089977] 'process raft request' (duration: 145.148752ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:52:23.861781Z","caller":"traceutil/trace.go:171","msg":"trace[1384474023] linearizableReadLoop","detail":"{readStateIndex:23796; appliedIndex:23794; }","duration":"140.379367ms","start":"2026-07-24T10:52:23.721363Z","end":"2026-07-24T10:52:23.861742Z","steps":["trace[1384474023] 'read index received' (duration: 34.00352ms)","trace[1384474023] 'applied index is now lower than readState.Index' (duration: 106.372767ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:52:23.861889Z","caller":"traceutil/trace.go:171","msg":"trace[345987050] transaction","detail":"{read_only:false; response_revision:23029; number_of_response:1; }","duration":"132.613185ms","start":"2026-07-24T10:52:23.729239Z","end":"2026-07-24T10:52:23.861852Z","steps":["trace[345987050] 'process raft request' (duration: 132.177896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:52:23.862118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.716215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/capo-validating-webhook-configuration\" ","response":"range_response_count:1 size:10501"} {"level":"info","ts":"2026-07-24T10:52:23.862177Z","caller":"traceutil/trace.go:171","msg":"trace[344286178] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/capo-validating-webhook-configuration; range_end:; response_count:1; response_revision:23029; }","duration":"140.819808ms","start":"2026-07-24T10:52:23.721338Z","end":"2026-07-24T10:52:23.862158Z","steps":["trace[344286178] 'agreement among raft nodes before linearized reading' (duration: 140.563011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:52:23.862157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.581164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusteridentities.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:5815"} {"level":"warn","ts":"2026-07-24T10:52:23.862222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.113769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3641"} {"level":"info","ts":"2026-07-24T10:52:23.862246Z","caller":"traceutil/trace.go:171","msg":"trace[881398277] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusteridentities.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23029; }","duration":"136.694926ms","start":"2026-07-24T10:52:23.725529Z","end":"2026-07-24T10:52:23.862224Z","steps":["trace[881398277] 'agreement among raft nodes before linearized reading' (duration: 136.450271ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:52:23.862273Z","caller":"traceutil/trace.go:171","msg":"trace[369098379] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:23029; }","duration":"124.187411ms","start":"2026-07-24T10:52:23.738067Z","end":"2026-07-24T10:52:23.862254Z","steps":["trace[369098379] 'agreement among raft nodes before linearized reading' (duration: 124.014147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:52:23.862472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.096409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T10:52:23.862524Z","caller":"traceutil/trace.go:171","msg":"trace[552090488] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23029; }","duration":"129.157039ms","start":"2026-07-24T10:52:23.733348Z","end":"2026-07-24T10:52:23.862505Z","steps":["trace[552090488] 'agreement among raft nodes before linearized reading' (duration: 129.061507ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:52:30.522393Z","caller":"traceutil/trace.go:171","msg":"trace[343652876] transaction","detail":"{read_only:false; response_revision:23203; number_of_response:1; }","duration":"189.617973ms","start":"2026-07-24T10:52:30.332744Z","end":"2026-07-24T10:52:30.522362Z","steps":["trace[343652876] 'process raft request' (duration: 169.686004ms)","trace[343652876] 'compare' (duration: 19.652003ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:52:30.528932Z","caller":"traceutil/trace.go:171","msg":"trace[2111770439] transaction","detail":"{read_only:false; response_revision:23204; number_of_response:1; }","duration":"114.625838ms","start":"2026-07-24T10:52:30.414281Z","end":"2026-07-24T10:52:30.528907Z","steps":["trace[2111770439] 'process raft request' (duration: 114.499246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:54:05.186238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.047318ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T10:54:41.185668Z","caller":"traceutil/trace.go:171","msg":"trace[1820906211] linearizableReadLoop","detail":"{readStateIndex:25355; appliedIndex:25354; }","duration":"129.563089ms","start":"2026-07-24T10:54:41.056074Z","end":"2026-07-24T10:54:41.185637Z","steps":["trace[1820906211] 'read index received' (duration: 129.319524ms)","trace[1820906211] 'applied index is now lower than readState.Index' (duration: 242.635µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:54:41.186073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.46737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T10:54:41.186159Z","caller":"traceutil/trace.go:171","msg":"trace[1709911030] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24552; }","duration":"126.599593ms","start":"2026-07-24T10:54:41.059546Z","end":"2026-07-24T10:54:41.186146Z","steps":["trace[1709911030] 'agreement among raft nodes before linearized reading' (duration: 126.45606ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:54:41.186131Z","caller":"traceutil/trace.go:171","msg":"trace[921089970] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"193.949529ms","start":"2026-07-24T10:54:40.992148Z","end":"2026-07-24T10:54:41.186097Z","steps":["trace[921089970] 'process raft request' (duration: 193.358656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:54:41.186308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.181573ms","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-07-24T10:54:41.186373Z","caller":"traceutil/trace.go:171","msg":"trace[26946115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24552; }","duration":"130.328256ms","start":"2026-07-24T10:54:41.056029Z","end":"2026-07-24T10:54:41.186358Z","steps":["trace[26946115] 'agreement among raft nodes before linearized reading' (duration: 130.080501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:49.261155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.14795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:55:49.261401Z","caller":"traceutil/trace.go:171","msg":"trace[364353207] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"229.114318ms","start":"2026-07-24T10:55:49.032268Z","end":"2026-07-24T10:55:49.261382Z","steps":["trace[364353207] 'process raft request' (duration: 229.060097ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:49.261386Z","caller":"traceutil/trace.go:171","msg":"trace[1252938922] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"281.581046ms","start":"2026-07-24T10:55:48.979765Z","end":"2026-07-24T10:55:49.261346Z","steps":["trace[1252938922] 'process raft request' (duration: 130.096548ms)","trace[1252938922] 'compare' (duration: 150.977937ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:49.261778Z","caller":"traceutil/trace.go:171","msg":"trace[210253051] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"262.789385ms","start":"2026-07-24T10:55:48.998935Z","end":"2026-07-24T10:55:49.261724Z","steps":["trace[210253051] 'process raft request' (duration: 262.333484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:49.511444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.262925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-24T10:55:49.511515Z","caller":"traceutil/trace.go:171","msg":"trace[584924798] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25285; }","duration":"136.366687ms","start":"2026-07-24T10:55:49.375132Z","end":"2026-07-24T10:55:49.511498Z","steps":["trace[584924798] 'range keys from in-memory index tree' (duration: 136.140533ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:49.74557Z","caller":"traceutil/trace.go:171","msg":"trace[1890100705] linearizableReadLoop","detail":"{readStateIndex:26109; appliedIndex:26108; }","duration":"174.973511ms","start":"2026-07-24T10:55:49.570556Z","end":"2026-07-24T10:55:49.74553Z","steps":["trace[1890100705] 'read index received' (duration: 164.750918ms)","trace[1890100705] 'applied index is now lower than readState.Index' (duration: 10.220923ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:49.745949Z","caller":"traceutil/trace.go:171","msg":"trace[808559827] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"226.4431ms","start":"2026-07-24T10:55:49.519484Z","end":"2026-07-24T10:55:49.745927Z","steps":["trace[808559827] 'process raft request' (duration: 215.877698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:49.746125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.310216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T10:55:49.746203Z","caller":"traceutil/trace.go:171","msg":"trace[1486049905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25287; }","duration":"142.438129ms","start":"2026-07-24T10:55:49.603747Z","end":"2026-07-24T10:55:49.746185Z","steps":["trace[1486049905] 'agreement among raft nodes before linearized reading' (duration: 142.237815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:49.746311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.75295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T10:55:49.746336Z","caller":"traceutil/trace.go:171","msg":"trace[784803450] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25287; }","duration":"175.856381ms","start":"2026-07-24T10:55:49.570473Z","end":"2026-07-24T10:55:49.74633Z","steps":["trace[784803450] 'agreement among raft nodes before linearized reading' (duration: 175.80919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:49.746463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.597082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:55:49.746507Z","caller":"traceutil/trace.go:171","msg":"trace[1342425513] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25287; }","duration":"131.686374ms","start":"2026-07-24T10:55:49.614813Z","end":"2026-07-24T10:55:49.746499Z","steps":["trace[1342425513] 'agreement among raft nodes before linearized reading' (duration: 131.629763ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:49.877106Z","caller":"traceutil/trace.go:171","msg":"trace[831339793] linearizableReadLoop","detail":"{readStateIndex:26110; appliedIndex:26109; }","duration":"118.942286ms","start":"2026-07-24T10:55:49.758122Z","end":"2026-07-24T10:55:49.877064Z","steps":["trace[831339793] 'read index received' (duration: 118.463385ms)","trace[831339793] 'applied index is now lower than readState.Index' (duration: 476.891µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:49.877205Z","caller":"traceutil/trace.go:171","msg":"trace[836710486] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"126.649324ms","start":"2026-07-24T10:55:49.75052Z","end":"2026-07-24T10:55:49.877169Z","steps":["trace[836710486] 'process raft request' (duration: 126.202204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:49.877662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.507389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:55:49.877726Z","caller":"traceutil/trace.go:171","msg":"trace[1005496819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25288; }","duration":"119.646142ms","start":"2026-07-24T10:55:49.758062Z","end":"2026-07-24T10:55:49.877709Z","steps":["trace[1005496819] 'agreement among raft nodes before linearized reading' (duration: 119.208241ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:54.061428Z","caller":"traceutil/trace.go:171","msg":"trace[1495191451] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"111.698007ms","start":"2026-07-24T10:55:53.949659Z","end":"2026-07-24T10:55:54.061357Z","steps":["trace[1495191451] 'process raft request' (duration: 111.40597ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:54.471767Z","caller":"traceutil/trace.go:171","msg":"trace[402936926] transaction","detail":"{read_only:false; response_revision:25331; number_of_response:1; }","duration":"212.707108ms","start":"2026-07-24T10:55:54.259034Z","end":"2026-07-24T10:55:54.471741Z","steps":["trace[402936926] 'process raft request' (duration: 212.457603ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:54.515952Z","caller":"traceutil/trace.go:171","msg":"trace[964597264] linearizableReadLoop","detail":"{readStateIndex:26156; appliedIndex:26154; }","duration":"236.923599ms","start":"2026-07-24T10:55:54.278999Z","end":"2026-07-24T10:55:54.515923Z","steps":["trace[964597264] 'read index received' (duration: 192.549657ms)","trace[964597264] 'applied index is now lower than readState.Index' (duration: 44.373292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:55:54.516187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.184394ms","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-07-24T10:55:54.516227Z","caller":"traceutil/trace.go:171","msg":"trace[1210318550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25332; }","duration":"237.335438ms","start":"2026-07-24T10:55:54.278882Z","end":"2026-07-24T10:55:54.516218Z","steps":["trace[1210318550] 'agreement among raft nodes before linearized reading' (duration: 237.135353ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:54.516263Z","caller":"traceutil/trace.go:171","msg":"trace[332085996] transaction","detail":"{read_only:false; response_revision:25332; number_of_response:1; }","duration":"248.232407ms","start":"2026-07-24T10:55:54.267985Z","end":"2026-07-24T10:55:54.516217Z","steps":["trace[332085996] 'process raft request' (duration: 247.795217ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:54.71633Z","caller":"traceutil/trace.go:171","msg":"trace[1562346739] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"195.816199ms","start":"2026-07-24T10:55:54.520485Z","end":"2026-07-24T10:55:54.716302Z","steps":["trace[1562346739] 'process raft request' (duration: 137.600703ms)","trace[1562346739] 'compare' (duration: 57.775546ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:54.988308Z","caller":"traceutil/trace.go:171","msg":"trace[78298209] linearizableReadLoop","detail":"{readStateIndex:26160; appliedIndex:26159; }","duration":"179.036362ms","start":"2026-07-24T10:55:54.809252Z","end":"2026-07-24T10:55:54.988288Z","steps":["trace[78298209] 'read index received' (duration: 178.777926ms)","trace[78298209] 'applied index is now lower than readState.Index' (duration: 257.736µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:54.988367Z","caller":"traceutil/trace.go:171","msg":"trace[500597782] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"195.886221ms","start":"2026-07-24T10:55:54.792443Z","end":"2026-07-24T10:55:54.988329Z","steps":["trace[500597782] 'process raft request' (duration: 195.646806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:54.988437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.169675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:55:54.988466Z","caller":"traceutil/trace.go:171","msg":"trace[40334338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25336; }","duration":"179.265527ms","start":"2026-07-24T10:55:54.809194Z","end":"2026-07-24T10:55:54.98846Z","steps":["trace[40334338] 'agreement among raft nodes before linearized reading' (duration: 179.201545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:55.170942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.35147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:55:55.171297Z","caller":"traceutil/trace.go:171","msg":"trace[993440199] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"178.013218ms","start":"2026-07-24T10:55:54.993243Z","end":"2026-07-24T10:55:55.171256Z","steps":["trace[993440199] 'process raft request' (duration: 39.26981ms)","trace[993440199] 'compare' (duration: 138.257368ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:55:55.304636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.593576ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-07-24T10:55:55.69931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.146386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:55:55.699834Z","caller":"traceutil/trace.go:171","msg":"trace[115033290] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25339; }","duration":"280.29352ms","start":"2026-07-24T10:55:55.419087Z","end":"2026-07-24T10:55:55.69938Z","steps":["trace[115033290] 'count revisions from in-memory index tree' (duration: 280.046124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:55.699736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.908056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:55:55.700164Z","caller":"traceutil/trace.go:171","msg":"trace[283168988] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"166.61057ms","start":"2026-07-24T10:55:55.53353Z","end":"2026-07-24T10:55:55.700141Z","steps":["trace[283168988] 'process raft request' (duration: 62.156892ms)","trace[283168988] 'compare' (duration: 103.441806ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:55.892008Z","caller":"traceutil/trace.go:171","msg":"trace[1816188848] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"257.666534ms","start":"2026-07-24T10:55:55.634299Z","end":"2026-07-24T10:55:55.891966Z","steps":["trace[1816188848] 'process raft request' (duration: 218.310182ms)","trace[1816188848] 'compare' (duration: 39.083826ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:55:55.89222Z","caller":"traceutil/trace.go:171","msg":"trace[1627046025] linearizableReadLoop","detail":"{readStateIndex:26166; appliedIndex:26165; }","duration":"129.216021ms","start":"2026-07-24T10:55:55.762977Z","end":"2026-07-24T10:55:55.892193Z","steps":["trace[1627046025] 'read index received' (duration: 89.614523ms)","trace[1627046025] 'applied index is now lower than readState.Index' (duration: 39.599838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:55:55.892476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.489037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:55:55.892533Z","caller":"traceutil/trace.go:171","msg":"trace[387959600] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25341; }","duration":"129.603759ms","start":"2026-07-24T10:55:55.762905Z","end":"2026-07-24T10:55:55.892509Z","steps":["trace[387959600] 'agreement among raft nodes before linearized reading' (duration: 129.425685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:55:55.892795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.130433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-24T10:55:55.892959Z","caller":"traceutil/trace.go:171","msg":"trace[1664684663] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25341; }","duration":"110.301337ms","start":"2026-07-24T10:55:55.782567Z","end":"2026-07-24T10:55:55.892869Z","steps":["trace[1664684663] 'agreement among raft nodes before linearized reading' (duration: 109.856737ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:55:56.02885Z","caller":"traceutil/trace.go:171","msg":"trace[869326759] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"132.094083ms","start":"2026-07-24T10:55:55.896725Z","end":"2026-07-24T10:55:56.028819Z","steps":["trace[869326759] 'process raft request' (duration: 109.973499ms)","trace[869326759] 'compare' (duration: 21.979901ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:06.25801Z","caller":"traceutil/trace.go:171","msg":"trace[1000136926] linearizableReadLoop","detail":"{readStateIndex:26242; appliedIndex:26241; }","duration":"146.541851ms","start":"2026-07-24T10:56:06.111439Z","end":"2026-07-24T10:56:06.257981Z","steps":["trace[1000136926] 'read index received' (duration: 146.287345ms)","trace[1000136926] 'applied index is now lower than readState.Index' (duration: 253.686µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:06.25805Z","caller":"traceutil/trace.go:171","msg":"trace[918681931] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"146.906509ms","start":"2026-07-24T10:56:06.111117Z","end":"2026-07-24T10:56:06.258023Z","steps":["trace[918681931] 'process raft request' (duration: 146.704174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:06.258422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.949819ms","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-07-24T10:56:06.258539Z","caller":"traceutil/trace.go:171","msg":"trace[162363414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25415; }","duration":"147.096772ms","start":"2026-07-24T10:56:06.111419Z","end":"2026-07-24T10:56:06.258516Z","steps":["trace[162363414] 'agreement among raft nodes before linearized reading' (duration: 146.703764ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:10.456006Z","caller":"traceutil/trace.go:171","msg":"trace[332514785] linearizableReadLoop","detail":"{readStateIndex:26332; appliedIndex:26331; }","duration":"143.047673ms","start":"2026-07-24T10:56:10.312933Z","end":"2026-07-24T10:56:10.455981Z","steps":["trace[332514785] 'read index received' (duration: 142.850199ms)","trace[332514785] 'applied index is now lower than readState.Index' (duration: 196.614µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:10.456153Z","caller":"traceutil/trace.go:171","msg":"trace[367003956] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"185.310499ms","start":"2026-07-24T10:56:10.270797Z","end":"2026-07-24T10:56:10.456108Z","steps":["trace[367003956] 'process raft request' (duration: 185.040583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.456205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.251167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T10:56:10.456328Z","caller":"traceutil/trace.go:171","msg":"trace[1030589627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25504; }","duration":"143.38758ms","start":"2026-07-24T10:56:10.312926Z","end":"2026-07-24T10:56:10.456314Z","steps":["trace[1030589627] 'agreement among raft nodes before linearized reading' (duration: 143.173715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.45633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.430609ms","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-07-24T10:56:10.456399Z","caller":"traceutil/trace.go:171","msg":"trace[1380746425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25504; }","duration":"142.536331ms","start":"2026-07-24T10:56:10.313843Z","end":"2026-07-24T10:56:10.456379Z","steps":["trace[1380746425] 'agreement among raft nodes before linearized reading' (duration: 142.313177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.456423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.300261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-24T10:56:10.456503Z","caller":"traceutil/trace.go:171","msg":"trace[1731458303] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc; range_end:; response_count:1; response_revision:25504; }","duration":"118.433535ms","start":"2026-07-24T10:56:10.338046Z","end":"2026-07-24T10:56:10.456479Z","steps":["trace[1731458303] 'agreement among raft nodes before linearized reading' (duration: 118.179828ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:10.610074Z","caller":"traceutil/trace.go:171","msg":"trace[1720578062] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"149.186138ms","start":"2026-07-24T10:56:10.460861Z","end":"2026-07-24T10:56:10.610047Z","steps":["trace[1720578062] 'process raft request' (duration: 108.833154ms)","trace[1720578062] 'compare' (duration: 40.233451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:10.973828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.667955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:10.974058Z","caller":"traceutil/trace.go:171","msg":"trace[978091370] linearizableReadLoop","detail":"{readStateIndex:26334; appliedIndex:26332; }","duration":"474.3243ms","start":"2026-07-24T10:56:10.499717Z","end":"2026-07-24T10:56:10.974041Z","steps":["trace[978091370] 'read index received' (duration: 69.999504ms)","trace[978091370] 'applied index is now lower than readState.Index' (duration: 404.324006ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:10.974113Z","caller":"traceutil/trace.go:171","msg":"trace[2086658717] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"512.220689ms","start":"2026-07-24T10:56:10.461851Z","end":"2026-07-24T10:56:10.974072Z","steps":["trace[2086658717] 'process raft request' (duration: 248.219367ms)","trace[2086658717] 'compare' (duration: 263.557233ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:10.974194Z","caller":"traceutil/trace.go:171","msg":"trace[461841137] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"428.24931ms","start":"2026-07-24T10:56:10.5459Z","end":"2026-07-24T10:56:10.974149Z","steps":["trace[461841137] 'process raft request' (duration: 428.042045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.974274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.46183Z","time spent":"512.353633ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:10.974297Z","caller":"traceutil/trace.go:171","msg":"trace[932830748] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"184.303395ms","start":"2026-07-24T10:56:10.789962Z","end":"2026-07-24T10:56:10.974266Z","steps":["trace[932830748] 'process raft request' (duration: 184.04045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.974356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.626056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:10.974618Z","caller":"traceutil/trace.go:171","msg":"trace[842901301] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25508; }","duration":"474.723208ms","start":"2026-07-24T10:56:10.499667Z","end":"2026-07-24T10:56:10.97439Z","steps":["trace[842901301] 'agreement among raft nodes before linearized reading' (duration: 474.453312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.974658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.499646Z","time spent":"475.000134ms","remote":"127.0.0.1:58006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T10:56:10.974679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.545884Z","time spent":"428.441255ms","remote":"127.0.0.1:40236","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-07-24T10:56:10.979838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.676871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:10.979882Z","caller":"traceutil/trace.go:171","msg":"trace[1971639866] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25509; }","duration":"434.772633ms","start":"2026-07-24T10:56:10.545096Z","end":"2026-07-24T10:56:10.979869Z","steps":["trace[1971639866] 'agreement among raft nodes before linearized reading' (duration: 434.688211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.979907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.545069Z","time spent":"434.830204ms","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T10:56:10.979928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.397502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:10.979992Z","caller":"traceutil/trace.go:171","msg":"trace[278031026] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25509; }","duration":"259.530644ms","start":"2026-07-24T10:56:10.72045Z","end":"2026-07-24T10:56:10.97998Z","steps":["trace[278031026] 'agreement among raft nodes before linearized reading' (duration: 259.396641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.980017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.563394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c534841a4ae316\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-07-24T10:56:10.980097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.94255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-24T10:56:10.980133Z","caller":"traceutil/trace.go:171","msg":"trace[1233122392] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c534841a4ae316; range_end:; response_count:1; response_revision:25509; }","duration":"106.721477ms","start":"2026-07-24T10:56:10.873376Z","end":"2026-07-24T10:56:10.980097Z","steps":["trace[1233122392] 'agreement among raft nodes before linearized reading' (duration: 106.450201ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:10.98017Z","caller":"traceutil/trace.go:171","msg":"trace[747026511] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc; range_end:; response_count:1; response_revision:25509; }","duration":"342.059122ms","start":"2026-07-24T10:56:10.638094Z","end":"2026-07-24T10:56:10.980153Z","steps":["trace[747026511] 'agreement among raft nodes before linearized reading' (duration: 341.831948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:10.980216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.638063Z","time spent":"342.143595ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc\" "} {"level":"warn","ts":"2026-07-24T10:56:10.980243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.428188ms","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-07-24T10:56:10.980276Z","caller":"traceutil/trace.go:171","msg":"trace[2018440958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25509; }","duration":"189.49556ms","start":"2026-07-24T10:56:10.790771Z","end":"2026-07-24T10:56:10.980267Z","steps":["trace[2018440958] 'agreement among raft nodes before linearized reading' (duration: 189.382887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.267417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.330427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:11.267513Z","caller":"traceutil/trace.go:171","msg":"trace[1064391131] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"285.361031ms","start":"2026-07-24T10:56:10.982135Z","end":"2026-07-24T10:56:11.267496Z","steps":["trace[1064391131] 'process raft request' (duration: 179.86038ms)","trace[1064391131] 'compare' (duration: 105.121063ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:11.364715Z","caller":"traceutil/trace.go:171","msg":"trace[288818605] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"376.537848ms","start":"2026-07-24T10:56:10.988156Z","end":"2026-07-24T10:56:11.364694Z","steps":["trace[288818605] 'process raft request' (duration: 376.465977ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:11.364793Z","caller":"traceutil/trace.go:171","msg":"trace[1395516714] linearizableReadLoop","detail":"{readStateIndex:26342; appliedIndex:26338; }","duration":"158.559003ms","start":"2026-07-24T10:56:11.206225Z","end":"2026-07-24T10:56:11.364784Z","steps":["trace[1395516714] 'read index received' (duration: 158.144464ms)","trace[1395516714] 'applied index is now lower than readState.Index' (duration: 413.189µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:11.364854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.988145Z","time spent":"376.62953ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:11.364862Z","caller":"traceutil/trace.go:171","msg":"trace[906076971] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"327.701738ms","start":"2026-07-24T10:56:11.037152Z","end":"2026-07-24T10:56:11.364854Z","steps":["trace[906076971] 'process raft request' (duration: 327.514504ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:11.364823Z","caller":"traceutil/trace.go:171","msg":"trace[2108638146] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"381.62332ms","start":"2026-07-24T10:56:10.983168Z","end":"2026-07-24T10:56:11.364791Z","steps":["trace[2108638146] 'process raft request' (duration: 381.280462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.364933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:11.037097Z","time spent":"327.79146ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:11.364723Z","caller":"traceutil/trace.go:171","msg":"trace[1652044753] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"381.291681ms","start":"2026-07-24T10:56:10.983403Z","end":"2026-07-24T10:56:11.364695Z","steps":["trace[1652044753] 'process raft request' (duration: 381.182119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.364995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.983158Z","time spent":"381.748932ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:56:11.364998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.737438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:11.365088Z","caller":"traceutil/trace.go:171","msg":"trace[1320810915] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25514; }","duration":"158.891051ms","start":"2026-07-24T10:56:11.206181Z","end":"2026-07-24T10:56:11.365072Z","steps":["trace[1320810915] 'agreement among raft nodes before linearized reading' (duration: 158.741867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.365089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.396573ms","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-07-24T10:56:11.365351Z","caller":"traceutil/trace.go:171","msg":"trace[1902828447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25514; }","duration":"102.69736ms","start":"2026-07-24T10:56:11.262632Z","end":"2026-07-24T10:56:11.365329Z","steps":["trace[1902828447] 'agreement among raft nodes before linearized reading' (duration: 102.259561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.365116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:10.983397Z","time spent":"381.597959ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:11.595355Z","caller":"traceutil/trace.go:171","msg":"trace[1156041272] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"227.121105ms","start":"2026-07-24T10:56:11.36821Z","end":"2026-07-24T10:56:11.595331Z","steps":["trace[1156041272] 'process raft request' (duration: 226.957061ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:11.607897Z","caller":"traceutil/trace.go:171","msg":"trace[486128779] linearizableReadLoop","detail":"{readStateIndex:26344; appliedIndex:26342; }","duration":"221.759758ms","start":"2026-07-24T10:56:11.386121Z","end":"2026-07-24T10:56:11.607881Z","steps":["trace[486128779] 'read index received' (duration: 209.021749ms)","trace[486128779] 'applied index is now lower than readState.Index' (duration: 12.737059ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:11.607992Z","caller":"traceutil/trace.go:171","msg":"trace[1944942163] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"238.613278ms","start":"2026-07-24T10:56:11.369344Z","end":"2026-07-24T10:56:11.607957Z","steps":["trace[1944942163] 'process raft request' (duration: 238.456514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.608086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.872054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T10:56:11.608126Z","caller":"traceutil/trace.go:171","msg":"trace[1310689409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25516; }","duration":"197.967676ms","start":"2026-07-24T10:56:11.410147Z","end":"2026-07-24T10:56:11.608115Z","steps":["trace[1310689409] 'agreement among raft nodes before linearized reading' (duration: 197.827553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.608128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.989013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-24T10:56:11.608181Z","caller":"traceutil/trace.go:171","msg":"trace[1662656702] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc; range_end:; response_count:1; response_revision:25516; }","duration":"222.075346ms","start":"2026-07-24T10:56:11.386091Z","end":"2026-07-24T10:56:11.608167Z","steps":["trace[1662656702] 'agreement among raft nodes before linearized reading' (duration: 221.871651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.608231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.188015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-24T10:56:11.608312Z","caller":"traceutil/trace.go:171","msg":"trace[1736981963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97165e7b-6ff4-4188-92ba-b9c10d0e4ecc; range_end:; response_count:1; response_revision:25516; }","duration":"147.331037ms","start":"2026-07-24T10:56:11.460966Z","end":"2026-07-24T10:56:11.608297Z","steps":["trace[1736981963] 'agreement among raft nodes before linearized reading' (duration: 147.039521ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:11.867408Z","caller":"traceutil/trace.go:171","msg":"trace[30470256] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"255.21545ms","start":"2026-07-24T10:56:11.612143Z","end":"2026-07-24T10:56:11.867358Z","steps":["trace[30470256] 'process raft request' (duration: 214.877997ms)","trace[30470256] 'compare' (duration: 39.604498ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:11.86749Z","caller":"traceutil/trace.go:171","msg":"trace[759150459] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"102.923075ms","start":"2026-07-24T10:56:11.76452Z","end":"2026-07-24T10:56:11.867443Z","steps":["trace[759150459] 'process raft request' (duration: 102.647239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:11.867743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.336962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:11.867808Z","caller":"traceutil/trace.go:171","msg":"trace[1101594926] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25518; }","duration":"249.441204ms","start":"2026-07-24T10:56:11.61835Z","end":"2026-07-24T10:56:11.867791Z","steps":["trace[1101594926] 'agreement among raft nodes before linearized reading' (duration: 249.126527ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:11.867438Z","caller":"traceutil/trace.go:171","msg":"trace[2091985493] linearizableReadLoop","detail":"{readStateIndex:26345; appliedIndex:26344; }","duration":"248.961064ms","start":"2026-07-24T10:56:11.618377Z","end":"2026-07-24T10:56:11.867338Z","steps":["trace[2091985493] 'read index received' (duration: 208.775394ms)","trace[2091985493] 'applied index is now lower than readState.Index' (duration: 40.1831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:11.868502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.45863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:11.868539Z","caller":"traceutil/trace.go:171","msg":"trace[725817018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25518; }","duration":"110.618004ms","start":"2026-07-24T10:56:11.757911Z","end":"2026-07-24T10:56:11.868529Z","steps":["trace[725817018] 'agreement among raft nodes before linearized reading' (duration: 110.421418ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:12.273645Z","caller":"traceutil/trace.go:171","msg":"trace[1002750775] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"134.023266ms","start":"2026-07-24T10:56:12.139597Z","end":"2026-07-24T10:56:12.27362Z","steps":["trace[1002750775] 'process raft request' (duration: 133.860083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:12.530308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.752682ms","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-07-24T10:56:12.530419Z","caller":"traceutil/trace.go:171","msg":"trace[1704893725] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:25519; }","duration":"108.916016ms","start":"2026-07-24T10:56:12.421475Z","end":"2026-07-24T10:56:12.530391Z","steps":["trace[1704893725] 'range keys from in-memory index tree' (duration: 108.553038ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:12.870857Z","caller":"traceutil/trace.go:171","msg":"trace[1869398456] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"242.663525ms","start":"2026-07-24T10:56:12.628165Z","end":"2026-07-24T10:56:12.870828Z","steps":["trace[1869398456] 'process raft request' (duration: 228.870212ms)","trace[1869398456] 'compare' (duration: 13.483536ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:13.307746Z","caller":"traceutil/trace.go:171","msg":"trace[1056703139] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"327.420992ms","start":"2026-07-24T10:56:12.980293Z","end":"2026-07-24T10:56:13.307714Z","steps":["trace[1056703139] 'process raft request' (duration: 317.935925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:13.307916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:12.980262Z","time spent":"327.564786ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:13.307944Z","caller":"traceutil/trace.go:171","msg":"trace[1163421337] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"283.168462ms","start":"2026-07-24T10:56:13.024738Z","end":"2026-07-24T10:56:13.307906Z","steps":["trace[1163421337] 'process raft request' (duration: 282.876026ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:13.593329Z","caller":"traceutil/trace.go:171","msg":"trace[1898106989] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"221.727737ms","start":"2026-07-24T10:56:13.371573Z","end":"2026-07-24T10:56:13.5933Z","steps":["trace[1898106989] 'process raft request' (duration: 220.152953ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:13.595864Z","caller":"traceutil/trace.go:171","msg":"trace[1145940414] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"219.875195ms","start":"2026-07-24T10:56:13.375955Z","end":"2026-07-24T10:56:13.59583Z","steps":["trace[1145940414] 'process raft request' (duration: 219.565709ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:13.595923Z","caller":"traceutil/trace.go:171","msg":"trace[476261196] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"219.101779ms","start":"2026-07-24T10:56:13.376795Z","end":"2026-07-24T10:56:13.595897Z","steps":["trace[476261196] 'process raft request' (duration: 219.018377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:15.483333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.430155ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T10:56:15.48368Z","caller":"traceutil/trace.go:171","msg":"trace[1337136108] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"168.099652ms","start":"2026-07-24T10:56:15.315558Z","end":"2026-07-24T10:56:15.483658Z","steps":["trace[1337136108] 'process raft request' (duration: 167.914008ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:15.483665Z","caller":"traceutil/trace.go:171","msg":"trace[1265094633] linearizableReadLoop","detail":"{readStateIndex:26367; appliedIndex:26366; }","duration":"170.250319ms","start":"2026-07-24T10:56:15.313384Z","end":"2026-07-24T10:56:15.483634Z","steps":["trace[1265094633] 'read index received' (duration: 91.632µs)","trace[1265094633] 'applied index is now lower than readState.Index' (duration: 170.155337ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:15.484223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.825861ms","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-07-24T10:56:15.4843Z","caller":"traceutil/trace.go:171","msg":"trace[918293796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25539; }","duration":"170.962614ms","start":"2026-07-24T10:56:15.313316Z","end":"2026-07-24T10:56:15.484278Z","steps":["trace[918293796] 'agreement among raft nodes before linearized reading' (duration: 170.523355ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:18.679606Z","caller":"traceutil/trace.go:171","msg":"trace[45975483] linearizableReadLoop","detail":"{readStateIndex:26409; appliedIndex:26408; }","duration":"155.243482ms","start":"2026-07-24T10:56:18.524339Z","end":"2026-07-24T10:56:18.679583Z","steps":["trace[45975483] 'read index received' (duration: 155.076988ms)","trace[45975483] 'applied index is now lower than readState.Index' (duration: 165.274µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:18.679768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.410385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T10:56:18.679797Z","caller":"traceutil/trace.go:171","msg":"trace[1902221898] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25579; }","duration":"155.512927ms","start":"2026-07-24T10:56:18.524276Z","end":"2026-07-24T10:56:18.679789Z","steps":["trace[1902221898] 'agreement among raft nodes before linearized reading' (duration: 155.392794ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:18.679783Z","caller":"traceutil/trace.go:171","msg":"trace[117148979] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"193.650541ms","start":"2026-07-24T10:56:18.4861Z","end":"2026-07-24T10:56:18.67975Z","steps":["trace[117148979] 'process raft request' (duration: 193.284973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:18.899927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.75956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:18.90016Z","caller":"traceutil/trace.go:171","msg":"trace[1121428300] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"324.774824ms","start":"2026-07-24T10:56:18.575359Z","end":"2026-07-24T10:56:18.900134Z","steps":["trace[1121428300] 'process raft request' (duration: 143.685067ms)","trace[1121428300] 'compare' (duration: 180.541194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:18.900245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:18.575325Z","time spent":"324.886257ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:19.154437Z","caller":"traceutil/trace.go:171","msg":"trace[686555991] linearizableReadLoop","detail":"{readStateIndex:26411; appliedIndex:26410; }","duration":"182.820515ms","start":"2026-07-24T10:56:18.971591Z","end":"2026-07-24T10:56:19.154412Z","steps":["trace[686555991] 'read index received' (duration: 182.658671ms)","trace[686555991] 'applied index is now lower than readState.Index' (duration: 156.464µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:19.154821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.193533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T10:56:19.154947Z","caller":"traceutil/trace.go:171","msg":"trace[2093132845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25581; }","duration":"183.396558ms","start":"2026-07-24T10:56:18.971517Z","end":"2026-07-24T10:56:19.154913Z","steps":["trace[2093132845] 'agreement among raft nodes before linearized reading' (duration: 183.036899ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.154982Z","caller":"traceutil/trace.go:171","msg":"trace[386232638] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"235.033117ms","start":"2026-07-24T10:56:18.919477Z","end":"2026-07-24T10:56:19.15451Z","steps":["trace[386232638] 'process raft request' (duration: 234.737851ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.22718Z","caller":"traceutil/trace.go:171","msg":"trace[89174083] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"103.109968ms","start":"2026-07-24T10:56:19.124033Z","end":"2026-07-24T10:56:19.227143Z","steps":["trace[89174083] 'process raft request' (duration: 103.015696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:19.227311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.747507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-07-24T10:56:19.227404Z","caller":"traceutil/trace.go:171","msg":"trace[1452321741] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25583; }","duration":"121.899119ms","start":"2026-07-24T10:56:19.105485Z","end":"2026-07-24T10:56:19.227384Z","steps":["trace[1452321741] 'agreement among raft nodes before linearized reading' (duration: 121.616203ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.227416Z","caller":"traceutil/trace.go:171","msg":"trace[1570344067] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"220.377767ms","start":"2026-07-24T10:56:19.007023Z","end":"2026-07-24T10:56:19.227401Z","steps":["trace[1570344067] 'process raft request' (duration: 219.850065ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.275025Z","caller":"traceutil/trace.go:171","msg":"trace[1185704123] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"113.287681ms","start":"2026-07-24T10:56:19.161712Z","end":"2026-07-24T10:56:19.275Z","steps":["trace[1185704123] 'process raft request' (duration: 113.194109ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.275091Z","caller":"traceutil/trace.go:171","msg":"trace[1142733647] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"115.068511ms","start":"2026-07-24T10:56:19.160001Z","end":"2026-07-24T10:56:19.27507Z","steps":["trace[1142733647] 'process raft request' (duration: 114.763514ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.275242Z","caller":"traceutil/trace.go:171","msg":"trace[1879068554] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"113.384654ms","start":"2026-07-24T10:56:19.161842Z","end":"2026-07-24T10:56:19.275227Z","steps":["trace[1879068554] 'process raft request' (duration: 113.118217ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.275474Z","caller":"traceutil/trace.go:171","msg":"trace[252923400] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"113.176059ms","start":"2026-07-24T10:56:19.162287Z","end":"2026-07-24T10:56:19.275464Z","steps":["trace[252923400] 'process raft request' (duration: 112.889833ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:19.275578Z","caller":"traceutil/trace.go:171","msg":"trace[113555949] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"113.058898ms","start":"2026-07-24T10:56:19.162507Z","end":"2026-07-24T10:56:19.275566Z","steps":["trace[113555949] 'process raft request' (duration: 112.881694ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:29.054463Z","caller":"traceutil/trace.go:171","msg":"trace[2002692711] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"121.112333ms","start":"2026-07-24T10:56:28.933326Z","end":"2026-07-24T10:56:29.054438Z","steps":["trace[2002692711] 'process raft request' (duration: 120.939579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:29.569789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.605701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-07-24T10:56:29.569854Z","caller":"traceutil/trace.go:171","msg":"trace[608460213] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25667; }","duration":"324.681382ms","start":"2026-07-24T10:56:29.245156Z","end":"2026-07-24T10:56:29.569838Z","steps":["trace[608460213] 'range keys from in-memory index tree' (duration: 324.365054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:29.56988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.245102Z","time spent":"324.771754ms","remote":"127.0.0.1:41840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3260,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-24T10:56:29.569913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.518634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:29.57Z","caller":"traceutil/trace.go:171","msg":"trace[2012714884] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"303.578319ms","start":"2026-07-24T10:56:29.266405Z","end":"2026-07-24T10:56:29.569983Z","steps":["trace[2012714884] 'process raft request' (duration: 59.900532ms)","trace[2012714884] 'compare' (duration: 243.218077ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:29.570036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.266377Z","time spent":"303.64176ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:56:30.245258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.598981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:30.24538Z","caller":"traceutil/trace.go:171","msg":"trace[433462761] linearizableReadLoop","detail":"{readStateIndex:26501; appliedIndex:26499; }","duration":"919.725917ms","start":"2026-07-24T10:56:29.325635Z","end":"2026-07-24T10:56:30.245361Z","steps":["trace[433462761] 'read index received' (duration: 677.705µs)","trace[433462761] 'applied index is now lower than readState.Index' (duration: 919.046832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:30.245804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.137006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T10:56:30.245869Z","caller":"traceutil/trace.go:171","msg":"trace[190915849] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"974.319852ms","start":"2026-07-24T10:56:29.271511Z","end":"2026-07-24T10:56:30.245831Z","steps":["trace[190915849] 'process raft request' (duration: 753.025015ms)","trace[190915849] 'compare' (duration: 220.461658ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:30.245915Z","caller":"traceutil/trace.go:171","msg":"trace[555197657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25669; }","duration":"920.310599ms","start":"2026-07-24T10:56:29.325579Z","end":"2026-07-24T10:56:30.24589Z","steps":["trace[555197657] 'agreement among raft nodes before linearized reading' (duration: 919.994043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.245977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.325551Z","time spent":"920.414951ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T10:56:30.246034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.271492Z","time spent":"974.442754ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:30.411743Z","caller":"traceutil/trace.go:171","msg":"trace[1004008002] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"838.941977ms","start":"2026-07-24T10:56:29.572776Z","end":"2026-07-24T10:56:30.411718Z","steps":["trace[1004008002] 'process raft request' (duration: 838.827784ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:30.411761Z","caller":"traceutil/trace.go:171","msg":"trace[2109825727] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"878.684987ms","start":"2026-07-24T10:56:29.533038Z","end":"2026-07-24T10:56:30.411723Z","steps":["trace[2109825727] 'process raft request' (duration: 877.90156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.411923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.572754Z","time spent":"839.06975ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:56:30.411963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.533002Z","time spent":"878.856371ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:30.412083Z","caller":"traceutil/trace.go:171","msg":"trace[2026145162] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"762.84883ms","start":"2026-07-24T10:56:29.649204Z","end":"2026-07-24T10:56:30.412053Z","steps":["trace[2026145162] 'process raft request' (duration: 762.452951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.412246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.649174Z","time spent":"762.983322ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:30.412431Z","caller":"traceutil/trace.go:171","msg":"trace[796253035] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"748.750561ms","start":"2026-07-24T10:56:29.663665Z","end":"2026-07-24T10:56:30.412416Z","steps":["trace[796253035] 'process raft request' (duration: 748.24436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.412508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.663572Z","time spent":"748.895964ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:30.412528Z","caller":"traceutil/trace.go:171","msg":"trace[761754177] linearizableReadLoop","detail":"{readStateIndex:26506; appliedIndex:26501; }","duration":"167.075169ms","start":"2026-07-24T10:56:30.245407Z","end":"2026-07-24T10:56:30.412482Z","steps":["trace[761754177] 'read index received' (duration: 165.701139ms)","trace[761754177] 'applied index is now lower than readState.Index' (duration: 1.37315ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:30.412625Z","caller":"traceutil/trace.go:171","msg":"trace[746599597] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"160.578417ms","start":"2026-07-24T10:56:30.25201Z","end":"2026-07-24T10:56:30.412589Z","steps":["trace[746599597] 'process raft request' (duration: 160.422964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.412746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.979627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:30.412791Z","caller":"traceutil/trace.go:171","msg":"trace[1963952352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25675; }","duration":"655.077709ms","start":"2026-07-24T10:56:29.757702Z","end":"2026-07-24T10:56:30.41278Z","steps":["trace[1963952352] 'agreement among raft nodes before linearized reading' (duration: 655.002078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.412827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.757674Z","time spent":"655.1476ms","remote":"127.0.0.1:40018","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-07-24T10:56:30.412834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.78679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-07-24T10:56:30.412844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.562844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-07-24T10:56:30.412894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.328361ms","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-07-24T10:56:30.412865Z","caller":"traceutil/trace.go:171","msg":"trace[1651957186] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"477.363256ms","start":"2026-07-24T10:56:29.93545Z","end":"2026-07-24T10:56:30.412814Z","steps":["trace[1651957186] 'process raft request' (duration: 476.832695ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:30.412971Z","caller":"traceutil/trace.go:171","msg":"trace[1503217186] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25675; }","duration":"742.434692ms","start":"2026-07-24T10:56:29.670525Z","end":"2026-07-24T10:56:30.412959Z","steps":["trace[1503217186] 'agreement among raft nodes before linearized reading' (duration: 742.249588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.412988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.148886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-07-24T10:56:30.412998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.670512Z","time spent":"742.479224ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T10:56:30.413023Z","caller":"traceutil/trace.go:171","msg":"trace[1496518907] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25675; }","duration":"233.220889ms","start":"2026-07-24T10:56:30.179794Z","end":"2026-07-24T10:56:30.413015Z","steps":["trace[1496518907] 'agreement among raft nodes before linearized reading' (duration: 233.117866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.413018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.944152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-07-24T10:56:30.41305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.09189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-07-24T10:56:30.413071Z","caller":"traceutil/trace.go:171","msg":"trace[1571248577] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0; range_end:; response_count:1; response_revision:25675; }","duration":"968.078704ms","start":"2026-07-24T10:56:29.444974Z","end":"2026-07-24T10:56:30.413052Z","steps":["trace[1571248577] 'agreement among raft nodes before linearized reading' (duration: 967.87401ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:30.413092Z","caller":"traceutil/trace.go:171","msg":"trace[118161690] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25675; }","duration":"235.179642ms","start":"2026-07-24T10:56:30.177903Z","end":"2026-07-24T10:56:30.413082Z","steps":["trace[118161690] 'agreement among raft nodes before linearized reading' (duration: 235.06714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.413104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.935421Z","time spent":"477.57205ms","remote":"127.0.0.1:40236","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-07-24T10:56:30.413113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.444944Z","time spent":"968.160216ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0\" "} {"level":"warn","ts":"2026-07-24T10:56:30.413278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.935738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:30.413318Z","caller":"traceutil/trace.go:171","msg":"trace[1911661746] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25675; }","duration":"294.0391ms","start":"2026-07-24T10:56:30.119267Z","end":"2026-07-24T10:56:30.413306Z","steps":["trace[1911661746] 'agreement among raft nodes before linearized reading' (duration: 293.963378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.413372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.922867ms","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-07-24T10:56:30.41342Z","caller":"traceutil/trace.go:171","msg":"trace[1701804391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25675; }","duration":"865.99919ms","start":"2026-07-24T10:56:29.54741Z","end":"2026-07-24T10:56:30.413409Z","steps":["trace[1701804391] 'agreement among raft nodes before linearized reading' (duration: 865.889317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.413456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.547397Z","time spent":"866.051681ms","remote":"127.0.0.1:40236","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-07-24T10:56:30.413516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.399635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T10:56:30.413564Z","caller":"traceutil/trace.go:171","msg":"trace[513839786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25675; }","duration":"455.491067ms","start":"2026-07-24T10:56:29.958062Z","end":"2026-07-24T10:56:30.413553Z","steps":["trace[513839786] 'agreement among raft nodes before linearized reading' (duration: 455.378655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.413453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.215938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-24T10:56:30.413903Z","caller":"traceutil/trace.go:171","msg":"trace[562823268] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25675; }","duration":"737.74247ms","start":"2026-07-24T10:56:29.67613Z","end":"2026-07-24T10:56:30.413872Z","steps":["trace[562823268] 'agreement among raft nodes before linearized reading' (duration: 737.057174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.41397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.676115Z","time spent":"737.839593ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-24T10:56:30.412939Z","caller":"traceutil/trace.go:171","msg":"trace[1062572127] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:25675; }","duration":"516.717297ms","start":"2026-07-24T10:56:29.896201Z","end":"2026-07-24T10:56:30.412918Z","steps":["trace[1062572127] 'agreement among raft nodes before linearized reading' (duration: 516.38081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.414027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.896178Z","time spent":"517.833633ms","remote":"127.0.0.1:40036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.19.213.12\" "} {"level":"warn","ts":"2026-07-24T10:56:30.413594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.958006Z","time spent":"455.5826ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T10:56:30.412888Z","caller":"traceutil/trace.go:171","msg":"trace[414368965] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25675; }","duration":"233.889943ms","start":"2026-07-24T10:56:30.178984Z","end":"2026-07-24T10:56:30.412874Z","steps":["trace[414368965] 'agreement among raft nodes before linearized reading' (duration: 233.725569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.413582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.024403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T10:56:30.414331Z","caller":"traceutil/trace.go:171","msg":"trace[1093049836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25675; }","duration":"925.76729ms","start":"2026-07-24T10:56:29.488545Z","end":"2026-07-24T10:56:30.414312Z","steps":["trace[1093049836] 'agreement among raft nodes before linearized reading' (duration: 924.932832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.41438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.488462Z","time spent":"925.901192ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T10:56:30.413713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.296637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-24T10:56:30.414602Z","caller":"traceutil/trace.go:171","msg":"trace[973202135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25675; }","duration":"731.267257ms","start":"2026-07-24T10:56:29.683319Z","end":"2026-07-24T10:56:30.414586Z","steps":["trace[973202135] 'agreement among raft nodes before linearized reading' (duration: 730.257306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:30.414692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:29.683293Z","time spent":"731.38222ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T10:56:55.588095Z","caller":"traceutil/trace.go:171","msg":"trace[978240197] linearizableReadLoop","detail":"{readStateIndex:26712; appliedIndex:26711; }","duration":"127.456201ms","start":"2026-07-24T10:56:55.460618Z","end":"2026-07-24T10:56:55.588074Z","steps":["trace[978240197] 'read index received' (duration: 127.285687ms)","trace[978240197] 'applied index is now lower than readState.Index' (duration: 169.374µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:55.588279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.637045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-07-24T10:56:55.588304Z","caller":"traceutil/trace.go:171","msg":"trace[702000855] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25874; }","duration":"127.710537ms","start":"2026-07-24T10:56:55.460586Z","end":"2026-07-24T10:56:55.588296Z","steps":["trace[702000855] 'agreement among raft nodes before linearized reading' (duration: 127.572084ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:55.588354Z","caller":"traceutil/trace.go:171","msg":"trace[995503793] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"134.310022ms","start":"2026-07-24T10:56:55.453829Z","end":"2026-07-24T10:56:55.588139Z","steps":["trace[995503793] 'process raft request' (duration: 134.071607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:55.878813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.399975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:55.878933Z","caller":"traceutil/trace.go:171","msg":"trace[260158014] linearizableReadLoop","detail":"{readStateIndex:26713; appliedIndex:26712; }","duration":"122.357891ms","start":"2026-07-24T10:56:55.756556Z","end":"2026-07-24T10:56:55.878914Z","steps":["trace[260158014] 'read index received' (duration: 53.382µs)","trace[260158014] 'applied index is now lower than readState.Index' (duration: 122.302739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:55.879031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.484843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:55.879069Z","caller":"traceutil/trace.go:171","msg":"trace[1215939120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25875; }","duration":"122.572505ms","start":"2026-07-24T10:56:55.756488Z","end":"2026-07-24T10:56:55.87906Z","steps":["trace[1215939120] 'agreement among raft nodes before linearized reading' (duration: 122.476053ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:55.879337Z","caller":"traceutil/trace.go:171","msg":"trace[1035827279] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"214.184751ms","start":"2026-07-24T10:56:55.66512Z","end":"2026-07-24T10:56:55.879304Z","steps":["trace[1035827279] 'process raft request' (duration: 84.188364ms)","trace[1035827279] 'compare' (duration: 128.973985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:56.193305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.225237ms","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-07-24T10:56:56.193437Z","caller":"traceutil/trace.go:171","msg":"trace[88846968] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25875; }","duration":"153.436651ms","start":"2026-07-24T10:56:56.039971Z","end":"2026-07-24T10:56:56.193408Z","steps":["trace[88846968] 'range keys from in-memory index tree' (duration: 153.119934ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.369797Z","caller":"traceutil/trace.go:171","msg":"trace[2077261482] linearizableReadLoop","detail":"{readStateIndex:26714; appliedIndex:26713; }","duration":"147.594763ms","start":"2026-07-24T10:56:56.222164Z","end":"2026-07-24T10:56:56.369759Z","steps":["trace[2077261482] 'read index received' (duration: 147.302737ms)","trace[2077261482] 'applied index is now lower than readState.Index' (duration: 290.836µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:56.369844Z","caller":"traceutil/trace.go:171","msg":"trace[172199406] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"153.642255ms","start":"2026-07-24T10:56:56.216162Z","end":"2026-07-24T10:56:56.369804Z","steps":["trace[172199406] 'process raft request' (duration: 153.327538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.3701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.898399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-24T10:56:56.370221Z","caller":"traceutil/trace.go:171","msg":"trace[1534354635] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25876; }","duration":"148.081813ms","start":"2026-07-24T10:56:56.222114Z","end":"2026-07-24T10:56:56.370196Z","steps":["trace[1534354635] 'agreement among raft nodes before linearized reading' (duration: 147.755836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.662576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.392661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-07-24T10:56:56.662698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.013884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-07-24T10:56:56.662747Z","caller":"traceutil/trace.go:171","msg":"trace[236894688] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25876; }","duration":"170.111496ms","start":"2026-07-24T10:56:56.492622Z","end":"2026-07-24T10:56:56.662733Z","steps":["trace[236894688] 'range keys from in-memory index tree' (duration: 169.857371ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.662692Z","caller":"traceutil/trace.go:171","msg":"trace[1935659633] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25876; }","duration":"169.538354ms","start":"2026-07-24T10:56:56.493126Z","end":"2026-07-24T10:56:56.662664Z","steps":["trace[1935659633] 'range keys from in-memory index tree' (duration: 169.197826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.662829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.717658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-07-24T10:56:56.662932Z","caller":"traceutil/trace.go:171","msg":"trace[486478178] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25876; }","duration":"169.854871ms","start":"2026-07-24T10:56:56.493051Z","end":"2026-07-24T10:56:56.662906Z","steps":["trace[486478178] 'range keys from in-memory index tree' (duration: 169.482473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.662817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.210364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:56.663075Z","caller":"traceutil/trace.go:171","msg":"trace[193425099] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25876; }","duration":"262.543611ms","start":"2026-07-24T10:56:56.400495Z","end":"2026-07-24T10:56:56.663039Z","steps":["trace[193425099] 'count revisions from in-memory index tree' (duration: 262.100561ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.663687Z","caller":"traceutil/trace.go:171","msg":"trace[1733283440] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"157.928969ms","start":"2026-07-24T10:56:56.505734Z","end":"2026-07-24T10:56:56.663663Z","steps":["trace[1733283440] 'process raft request' (duration: 157.744875ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.715704Z","caller":"traceutil/trace.go:171","msg":"trace[860037787] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"208.495677ms","start":"2026-07-24T10:56:56.507171Z","end":"2026-07-24T10:56:56.715667Z","steps":["trace[860037787] 'process raft request' (duration: 208.388205ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.715754Z","caller":"traceutil/trace.go:171","msg":"trace[1218749974] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"208.523298ms","start":"2026-07-24T10:56:56.507163Z","end":"2026-07-24T10:56:56.715687Z","steps":["trace[1218749974] 'process raft request' (duration: 208.131888ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.716023Z","caller":"traceutil/trace.go:171","msg":"trace[143378860] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"207.487874ms","start":"2026-07-24T10:56:56.508489Z","end":"2026-07-24T10:56:56.715977Z","steps":["trace[143378860] 'process raft request' (duration: 207.119036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.716227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.144904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T10:56:56.716335Z","caller":"traceutil/trace.go:171","msg":"trace[1636476670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25880; }","duration":"179.308757ms","start":"2026-07-24T10:56:56.537003Z","end":"2026-07-24T10:56:56.716312Z","steps":["trace[1636476670] 'agreement among raft nodes before linearized reading' (duration: 179.036732ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.716021Z","caller":"traceutil/trace.go:171","msg":"trace[844383963] linearizableReadLoop","detail":"{readStateIndex:26718; appliedIndex:26714; }","duration":"178.895689ms","start":"2026-07-24T10:56:56.537056Z","end":"2026-07-24T10:56:56.715951Z","steps":["trace[844383963] 'read index received' (duration: 126.537202ms)","trace[844383963] 'applied index is now lower than readState.Index' (duration: 52.355647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:56.716963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.019831ms","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-07-24T10:56:56.71702Z","caller":"traceutil/trace.go:171","msg":"trace[2041876983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25880; }","duration":"174.095933ms","start":"2026-07-24T10:56:56.542906Z","end":"2026-07-24T10:56:56.717002Z","steps":["trace[2041876983] 'agreement among raft nodes before linearized reading' (duration: 173.968731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.717051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.215737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:56.717119Z","caller":"traceutil/trace.go:171","msg":"trace[1191999443] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25880; }","duration":"164.318649ms","start":"2026-07-24T10:56:56.552782Z","end":"2026-07-24T10:56:56.717101Z","steps":["trace[1191999443] 'agreement among raft nodes before linearized reading' (duration: 164.213547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.717161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.129045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-24T10:56:56.717212Z","caller":"traceutil/trace.go:171","msg":"trace[1084175856] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25880; }","duration":"143.184686ms","start":"2026-07-24T10:56:56.574Z","end":"2026-07-24T10:56:56.717185Z","steps":["trace[1084175856] 'agreement among raft nodes before linearized reading' (duration: 143.101164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.717245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.951316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-24T10:56:56.71706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.755402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-24T10:56:56.717383Z","caller":"traceutil/trace.go:171","msg":"trace[1165154311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25880; }","duration":"156.11927ms","start":"2026-07-24T10:56:56.561248Z","end":"2026-07-24T10:56:56.717367Z","steps":["trace[1165154311] 'agreement among raft nodes before linearized reading' (duration: 155.697571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:56.71744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.382187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-07-24T10:56:56.717311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.315508ms","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-07-24T10:56:56.7175Z","caller":"traceutil/trace.go:171","msg":"trace[1954925688] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25880; }","duration":"147.47315ms","start":"2026-07-24T10:56:56.57001Z","end":"2026-07-24T10:56:56.717483Z","steps":["trace[1954925688] 'agreement among raft nodes before linearized reading' (duration: 147.346397ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.717514Z","caller":"traceutil/trace.go:171","msg":"trace[1562883783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25880; }","duration":"174.534133ms","start":"2026-07-24T10:56:56.542967Z","end":"2026-07-24T10:56:56.717501Z","steps":["trace[1562883783] 'agreement among raft nodes before linearized reading' (duration: 174.248977ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.717314Z","caller":"traceutil/trace.go:171","msg":"trace[233130101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25880; }","duration":"156.087359ms","start":"2026-07-24T10:56:56.561209Z","end":"2026-07-24T10:56:56.717297Z","steps":["trace[233130101] 'agreement among raft nodes before linearized reading' (duration: 155.824573ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:56.890076Z","caller":"traceutil/trace.go:171","msg":"trace[1712985601] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"168.439689ms","start":"2026-07-24T10:56:56.721606Z","end":"2026-07-24T10:56:56.890046Z","steps":["trace[1712985601] 'process raft request' (duration: 156.720462ms)","trace[1712985601] 'compare' (duration: 11.460952ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:57.181021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.685589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:57.181406Z","caller":"traceutil/trace.go:171","msg":"trace[2047901626] linearizableReadLoop","detail":"{readStateIndex:26725; appliedIndex:26718; }","duration":"456.388087ms","start":"2026-07-24T10:56:56.725Z","end":"2026-07-24T10:56:57.181388Z","steps":["trace[2047901626] 'read index received' (duration: 153.271407ms)","trace[2047901626] 'applied index is now lower than readState.Index' (duration: 303.11536ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:57.181507Z","caller":"traceutil/trace.go:171","msg":"trace[1792858866] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"459.304481ms","start":"2026-07-24T10:56:56.722193Z","end":"2026-07-24T10:56:57.181497Z","steps":["trace[1792858866] 'process raft request' (duration: 459.065706ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:57.18156Z","caller":"traceutil/trace.go:171","msg":"trace[1700131012] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"459.486805ms","start":"2026-07-24T10:56:56.722055Z","end":"2026-07-24T10:56:57.181542Z","steps":["trace[1700131012] 'process raft request' (duration: 356.131261ms)","trace[1700131012] 'compare' (duration: 102.29953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:57.181577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.722185Z","time spent":"459.357102ms","remote":"127.0.0.1:40236","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-07-24T10:56:57.181717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.722044Z","time spent":"459.568037ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:57.181805Z","caller":"traceutil/trace.go:171","msg":"trace[776146347] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"458.897562ms","start":"2026-07-24T10:56:56.722896Z","end":"2026-07-24T10:56:57.181794Z","steps":["trace[776146347] 'process raft request' (duration: 458.419442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.181866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.722885Z","time spent":"458.950743ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:57.181961Z","caller":"traceutil/trace.go:171","msg":"trace[842559032] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"459.029135ms","start":"2026-07-24T10:56:56.72292Z","end":"2026-07-24T10:56:57.181949Z","steps":["trace[842559032] 'process raft request' (duration: 458.431912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.182022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.72291Z","time spent":"459.081826ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:57.181474Z","caller":"traceutil/trace.go:171","msg":"trace[1611129868] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"459.234079ms","start":"2026-07-24T10:56:56.722183Z","end":"2026-07-24T10:56:57.181417Z","steps":["trace[1611129868] 'process raft request' (duration: 459.039145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.182198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.668584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:57.182261Z","caller":"traceutil/trace.go:171","msg":"trace[1210489300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25887; }","duration":"425.730515ms","start":"2026-07-24T10:56:56.75651Z","end":"2026-07-24T10:56:57.182241Z","steps":["trace[1210489300] 'agreement among raft nodes before linearized reading' (duration: 425.630743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.182292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.75643Z","time spent":"425.855768ms","remote":"127.0.0.1:40018","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-07-24T10:56:57.182255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.333328ms","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":"warn","ts":"2026-07-24T10:56:57.182308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.79906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:57.182348Z","caller":"traceutil/trace.go:171","msg":"trace[1029781946] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25887; }","duration":"138.867111ms","start":"2026-07-24T10:56:57.043469Z","end":"2026-07-24T10:56:57.182336Z","steps":["trace[1029781946] 'agreement among raft nodes before linearized reading' (duration: 138.80305ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:57.182351Z","caller":"traceutil/trace.go:171","msg":"trace[605623355] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25887; }","duration":"184.486181ms","start":"2026-07-24T10:56:56.997843Z","end":"2026-07-24T10:56:57.182329Z","steps":["trace[605623355] 'agreement among raft nodes before linearized reading' (duration: 184.310287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.18233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.466463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:57.182416Z","caller":"traceutil/trace.go:171","msg":"trace[244178194] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25887; }","duration":"238.608256ms","start":"2026-07-24T10:56:56.943786Z","end":"2026-07-24T10:56:57.182394Z","steps":["trace[244178194] 'agreement among raft nodes before linearized reading' (duration: 238.432623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.182362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.72216Z","time spent":"460.025936ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:57.182557Z","caller":"traceutil/trace.go:171","msg":"trace[597535202] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"460.397234ms","start":"2026-07-24T10:56:56.722134Z","end":"2026-07-24T10:56:57.182531Z","steps":["trace[597535202] 'process raft request' (duration: 458.992184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.182145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.151423ms","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-07-24T10:56:57.182686Z","caller":"traceutil/trace.go:171","msg":"trace[1142930977] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25887; }","duration":"457.714795ms","start":"2026-07-24T10:56:56.724959Z","end":"2026-07-24T10:56:57.182673Z","steps":["trace[1142930977] 'agreement among raft nodes before linearized reading' (duration: 457.131013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:57.182714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.722083Z","time spent":"460.546107ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:56:57.182731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:56.724951Z","time spent":"457.767686ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-07-24T10:56:57.46235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.591185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:56:57.462465Z","caller":"traceutil/trace.go:171","msg":"trace[1290603709] linearizableReadLoop","detail":"{readStateIndex:26726; appliedIndex:26725; }","duration":"271.721822ms","start":"2026-07-24T10:56:57.190727Z","end":"2026-07-24T10:56:57.462448Z","steps":["trace[1290603709] 'read index received' (duration: 127.836291ms)","trace[1290603709] 'applied index is now lower than readState.Index' (duration: 143.883901ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:57.462595Z","caller":"traceutil/trace.go:171","msg":"trace[1988468694] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"277.221383ms","start":"2026-07-24T10:56:57.185362Z","end":"2026-07-24T10:56:57.462584Z","steps":["trace[1988468694] 'process raft request' (duration: 133.27298ms)","trace[1988468694] 'compare' (duration: 143.263798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:56:57.462906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.156461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:57.462989Z","caller":"traceutil/trace.go:171","msg":"trace[908774744] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25888; }","duration":"272.290264ms","start":"2026-07-24T10:56:57.190678Z","end":"2026-07-24T10:56:57.462968Z","steps":["trace[908774744] 'agreement among raft nodes before linearized reading' (duration: 272.161201ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:57.997334Z","caller":"traceutil/trace.go:171","msg":"trace[376051680] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"104.519209ms","start":"2026-07-24T10:56:57.892778Z","end":"2026-07-24T10:56:57.997297Z","steps":["trace[376051680] 'process raft request' (duration: 104.296794ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:58.985415Z","caller":"traceutil/trace.go:171","msg":"trace[1589648925] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"316.647026ms","start":"2026-07-24T10:56:58.668749Z","end":"2026-07-24T10:56:58.985396Z","steps":["trace[1589648925] 'process raft request' (duration: 316.492843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:58.985541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:56:58.668738Z","time spent":"316.732248ms","remote":"127.0.0.1:40236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:56:59.006598Z","caller":"traceutil/trace.go:171","msg":"trace[2050946806] linearizableReadLoop","detail":"{readStateIndex:26731; appliedIndex:26727; }","duration":"249.585677ms","start":"2026-07-24T10:56:58.756985Z","end":"2026-07-24T10:56:59.006571Z","steps":["trace[2050946806] 'read index received' (duration: 228.24257ms)","trace[2050946806] 'applied index is now lower than readState.Index' (duration: 21.341687ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:56:59.006628Z","caller":"traceutil/trace.go:171","msg":"trace[1239421877] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"282.727903ms","start":"2026-07-24T10:56:58.723859Z","end":"2026-07-24T10:56:59.006587Z","steps":["trace[1239421877] 'process raft request' (duration: 282.59106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:59.006655Z","caller":"traceutil/trace.go:171","msg":"trace[1747922221] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"284.920911ms","start":"2026-07-24T10:56:58.721696Z","end":"2026-07-24T10:56:59.006617Z","steps":["trace[1747922221] 'process raft request' (duration: 284.595044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:59.006749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.748361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:56:59.006804Z","caller":"traceutil/trace.go:171","msg":"trace[1933408054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25893; }","duration":"249.857232ms","start":"2026-07-24T10:56:58.756935Z","end":"2026-07-24T10:56:59.006792Z","steps":["trace[1933408054] 'agreement among raft nodes before linearized reading' (duration: 249.74029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:56:59.006832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.671416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-24T10:56:59.006866Z","caller":"traceutil/trace.go:171","msg":"trace[1423853802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25893; }","duration":"111.742667ms","start":"2026-07-24T10:56:58.895112Z","end":"2026-07-24T10:56:59.006854Z","steps":["trace[1423853802] 'agreement among raft nodes before linearized reading' (duration: 111.593534ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:56:59.007113Z","caller":"traceutil/trace.go:171","msg":"trace[2094062786] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"282.722873ms","start":"2026-07-24T10:56:58.723909Z","end":"2026-07-24T10:56:59.006632Z","steps":["trace[2094062786] 'process raft request' (duration: 282.60639ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:00.75548Z","caller":"traceutil/trace.go:171","msg":"trace[460096683] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"157.513941ms","start":"2026-07-24T10:57:00.597936Z","end":"2026-07-24T10:57:00.75545Z","steps":["trace[460096683] 'process raft request' (duration: 157.106461ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.264062Z","caller":"traceutil/trace.go:171","msg":"trace[1924883526] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"252.023011ms","start":"2026-07-24T10:57:01.012008Z","end":"2026-07-24T10:57:01.264031Z","steps":["trace[1924883526] 'process raft request' (duration: 224.850025ms)","trace[1924883526] 'compare' (duration: 26.651084ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:01.264086Z","caller":"traceutil/trace.go:171","msg":"trace[1194821031] transaction","detail":"{read_only:false; response_revision:25909; number_of_response:1; }","duration":"135.700482ms","start":"2026-07-24T10:57:01.128361Z","end":"2026-07-24T10:57:01.264062Z","steps":["trace[1194821031] 'process raft request' (duration: 135.658701ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.264088Z","caller":"traceutil/trace.go:171","msg":"trace[466042250] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"251.386577ms","start":"2026-07-24T10:57:01.012644Z","end":"2026-07-24T10:57:01.264031Z","steps":["trace[466042250] 'process raft request' (duration: 251.228663ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.264262Z","caller":"traceutil/trace.go:171","msg":"trace[2109492583] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"248.735488ms","start":"2026-07-24T10:57:01.015516Z","end":"2026-07-24T10:57:01.264251Z","steps":["trace[2109492583] 'process raft request' (duration: 248.453592ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.264283Z","caller":"traceutil/trace.go:171","msg":"trace[1682927629] linearizableReadLoop","detail":"{readStateIndex:26747; appliedIndex:26744; }","duration":"186.842042ms","start":"2026-07-24T10:57:01.07743Z","end":"2026-07-24T10:57:01.264272Z","steps":["trace[1682927629] 'read index received' (duration: 159.436481ms)","trace[1682927629] 'applied index is now lower than readState.Index' (duration: 27.404441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:57:01.264477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.036286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-24T10:57:01.264497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.262796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:57:01.264518Z","caller":"traceutil/trace.go:171","msg":"trace[170211626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25909; }","duration":"187.131198ms","start":"2026-07-24T10:57:01.077376Z","end":"2026-07-24T10:57:01.264508Z","steps":["trace[170211626] 'agreement among raft nodes before linearized reading' (duration: 186.965034ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.26458Z","caller":"traceutil/trace.go:171","msg":"trace[528636316] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25909; }","duration":"153.40192ms","start":"2026-07-24T10:57:01.111133Z","end":"2026-07-24T10:57:01.264535Z","steps":["trace[528636316] 'agreement among raft nodes before linearized reading' (duration: 153.238866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:01.264806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.082047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-24T10:57:01.264886Z","caller":"traceutil/trace.go:171","msg":"trace[1358787554] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25909; }","duration":"134.25713ms","start":"2026-07-24T10:57:01.130612Z","end":"2026-07-24T10:57:01.264869Z","steps":["trace[1358787554] 'agreement among raft nodes before linearized reading' (duration: 133.974584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:01.526454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.556248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:57:01.526612Z","caller":"traceutil/trace.go:171","msg":"trace[677808000] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"256.496429ms","start":"2026-07-24T10:57:01.270087Z","end":"2026-07-24T10:57:01.526584Z","steps":["trace[677808000] 'process raft request' (duration: 141.883028ms)","trace[677808000] 'compare' (duration: 113.071917ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:01.569467Z","caller":"traceutil/trace.go:171","msg":"trace[1137715255] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"299.201484ms","start":"2026-07-24T10:57:01.270227Z","end":"2026-07-24T10:57:01.569429Z","steps":["trace[1137715255] 'process raft request' (duration: 298.916408ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.569521Z","caller":"traceutil/trace.go:171","msg":"trace[1552265810] linearizableReadLoop","detail":"{readStateIndex:26755; appliedIndex:26748; }","duration":"231.850908ms","start":"2026-07-24T10:57:01.337646Z","end":"2026-07-24T10:57:01.569497Z","steps":["trace[1552265810] 'read index received' (duration: 74.3972ms)","trace[1552265810] 'applied index is now lower than readState.Index' (duration: 157.452638ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:01.569679Z","caller":"traceutil/trace.go:171","msg":"trace[1328997728] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"299.309836ms","start":"2026-07-24T10:57:01.270316Z","end":"2026-07-24T10:57:01.569626Z","steps":["trace[1328997728] 'process raft request' (duration: 298.981899ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.569851Z","caller":"traceutil/trace.go:171","msg":"trace[907415807] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"299.522791ms","start":"2026-07-24T10:57:01.270316Z","end":"2026-07-24T10:57:01.569839Z","steps":["trace[907415807] 'process raft request' (duration: 299.0266ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.570037Z","caller":"traceutil/trace.go:171","msg":"trace[1828006061] transaction","detail":"{read_only:false; response_revision:25914; number_of_response:1; }","duration":"299.634944ms","start":"2026-07-24T10:57:01.270392Z","end":"2026-07-24T10:57:01.570027Z","steps":["trace[1828006061] 'process raft request' (duration: 298.99203ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.57012Z","caller":"traceutil/trace.go:171","msg":"trace[325236554] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"299.102041ms","start":"2026-07-24T10:57:01.271008Z","end":"2026-07-24T10:57:01.57011Z","steps":["trace[325236554] 'process raft request' (duration: 298.411026ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.570185Z","caller":"traceutil/trace.go:171","msg":"trace[2033783374] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"298.179921ms","start":"2026-07-24T10:57:01.271996Z","end":"2026-07-24T10:57:01.570176Z","steps":["trace[2033783374] 'process raft request' (duration: 297.459696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:01.570302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.642687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T10:57:01.570337Z","caller":"traceutil/trace.go:171","msg":"trace[6045165] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25916; }","duration":"232.741849ms","start":"2026-07-24T10:57:01.337581Z","end":"2026-07-24T10:57:01.570323Z","steps":["trace[6045165] 'agreement among raft nodes before linearized reading' (duration: 232.663547ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:01.578427Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006885.wal"} {"level":"info","ts":"2026-07-24T10:57:01.578871Z","caller":"traceutil/trace.go:171","msg":"trace[1539154073] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"103.242661ms","start":"2026-07-24T10:57:01.475613Z","end":"2026-07-24T10:57:01.578856Z","steps":["trace[1539154073] 'process raft request' (duration: 103.049047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:01.578927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.46993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T10:57:01.578967Z","caller":"traceutil/trace.go:171","msg":"trace[1862386474] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25917; }","duration":"199.568552ms","start":"2026-07-24T10:57:01.379385Z","end":"2026-07-24T10:57:01.578954Z","steps":["trace[1862386474] 'agreement among raft nodes before linearized reading' (duration: 199.406169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:07.3028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.36273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T10:57:07.302955Z","caller":"traceutil/trace.go:171","msg":"trace[1308250763] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"161.533928ms","start":"2026-07-24T10:57:07.141394Z","end":"2026-07-24T10:57:07.302928Z","steps":["trace[1308250763] 'process raft request' (duration: 33.928343ms)","trace[1308250763] 'compare' (duration: 126.997872ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:07.44085Z","caller":"traceutil/trace.go:171","msg":"trace[473236203] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"157.043251ms","start":"2026-07-24T10:57:07.283766Z","end":"2026-07-24T10:57:07.44081Z","steps":["trace[473236203] 'process raft request' (duration: 156.798605ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.453722Z","caller":"traceutil/trace.go:171","msg":"trace[1739410762] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"168.248355ms","start":"2026-07-24T10:57:07.285449Z","end":"2026-07-24T10:57:07.453697Z","steps":["trace[1739410762] 'process raft request' (duration: 168.205214ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.453961Z","caller":"traceutil/trace.go:171","msg":"trace[1373650286] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"168.928591ms","start":"2026-07-24T10:57:07.285001Z","end":"2026-07-24T10:57:07.45393Z","steps":["trace[1373650286] 'process raft request' (duration: 168.486702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:07.77988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.962015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-24T10:57:07.779969Z","caller":"traceutil/trace.go:171","msg":"trace[270214994] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25952; }","duration":"182.075429ms","start":"2026-07-24T10:57:07.597856Z","end":"2026-07-24T10:57:07.779931Z","steps":["trace[270214994] 'range keys from in-memory index tree' (duration: 181.730311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:07.779977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.426018ms","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-07-24T10:57:07.779878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.730284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-07-24T10:57:07.780091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.527662ms","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-07-24T10:57:07.780169Z","caller":"traceutil/trace.go:171","msg":"trace[628975819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25952; }","duration":"226.624014ms","start":"2026-07-24T10:57:07.553528Z","end":"2026-07-24T10:57:07.780152Z","steps":["trace[628975819] 'range keys from in-memory index tree' (duration: 226.371068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:07.780172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.142967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-24T10:57:07.78021Z","caller":"traceutil/trace.go:171","msg":"trace[1861503069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25952; }","duration":"186.211838ms","start":"2026-07-24T10:57:07.59399Z","end":"2026-07-24T10:57:07.780202Z","steps":["trace[1861503069] 'range keys from in-memory index tree' (duration: 186.024255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:07.780472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.669096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-24T10:57:07.780562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.688676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T10:57:07.780622Z","caller":"traceutil/trace.go:171","msg":"trace[61984122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25952; }","duration":"184.762647ms","start":"2026-07-24T10:57:07.595847Z","end":"2026-07-24T10:57:07.78061Z","steps":["trace[61984122] 'range keys from in-memory index tree' (duration: 184.562223ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.780501Z","caller":"traceutil/trace.go:171","msg":"trace[1865788325] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25952; }","duration":"184.047111ms","start":"2026-07-24T10:57:07.596084Z","end":"2026-07-24T10:57:07.780131Z","steps":["trace[1865788325] 'range keys from in-memory index tree' (duration: 183.54823ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.780621Z","caller":"traceutil/trace.go:171","msg":"trace[430433612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25952; }","duration":"185.858621ms","start":"2026-07-24T10:57:07.594722Z","end":"2026-07-24T10:57:07.78058Z","steps":["trace[430433612] 'range keys from in-memory index tree' (duration: 185.249598ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.780066Z","caller":"traceutil/trace.go:171","msg":"trace[1047086719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25952; }","duration":"183.54408ms","start":"2026-07-24T10:57:07.5965Z","end":"2026-07-24T10:57:07.780044Z","steps":["trace[1047086719] 'range keys from in-memory index tree' (duration: 183.197272ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.781014Z","caller":"traceutil/trace.go:171","msg":"trace[501217083] linearizableReadLoop","detail":"{readStateIndex:26793; appliedIndex:26792; }","duration":"143.638907ms","start":"2026-07-24T10:57:07.637361Z","end":"2026-07-24T10:57:07.781Z","steps":["trace[501217083] 'read index received' (duration: 50.583268ms)","trace[501217083] 'applied index is now lower than readState.Index' (duration: 93.052099ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:07.781133Z","caller":"traceutil/trace.go:171","msg":"trace[1491937372] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"168.556463ms","start":"2026-07-24T10:57:07.612532Z","end":"2026-07-24T10:57:07.781088Z","steps":["trace[1491937372] 'process raft request' (duration: 75.424572ms)","trace[1491937372] 'compare' (duration: 92.011485ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:57:07.781186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.833442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/senlin-api-6d59f464cd-79z59.18c533d76b49558b\" ","response":"range_response_count:1 size:1170"} {"level":"info","ts":"2026-07-24T10:57:07.781211Z","caller":"traceutil/trace.go:171","msg":"trace[266913475] range","detail":"{range_begin:/registry/events/openstack/senlin-api-6d59f464cd-79z59.18c533d76b49558b; range_end:; response_count:1; response_revision:25953; }","duration":"143.902413ms","start":"2026-07-24T10:57:07.637301Z","end":"2026-07-24T10:57:07.781203Z","steps":["trace[266913475] 'agreement among raft nodes before linearized reading' (duration: 143.796791ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.978461Z","caller":"traceutil/trace.go:171","msg":"trace[1965553693] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"193.187252ms","start":"2026-07-24T10:57:07.785251Z","end":"2026-07-24T10:57:07.978438Z","steps":["trace[1965553693] 'process raft request' (duration: 176.68248ms)","trace[1965553693] 'compare' (duration: 16.349129ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:07.978648Z","caller":"traceutil/trace.go:171","msg":"trace[97041910] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"193.338464ms","start":"2026-07-24T10:57:07.785303Z","end":"2026-07-24T10:57:07.978641Z","steps":["trace[97041910] 'process raft request' (duration: 193.100619ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.979082Z","caller":"traceutil/trace.go:171","msg":"trace[2146004771] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"193.012807ms","start":"2026-07-24T10:57:07.786048Z","end":"2026-07-24T10:57:07.979061Z","steps":["trace[2146004771] 'process raft request' (duration: 192.567588ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.979127Z","caller":"traceutil/trace.go:171","msg":"trace[1983328994] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"192.465465ms","start":"2026-07-24T10:57:07.786652Z","end":"2026-07-24T10:57:07.979117Z","steps":["trace[1983328994] 'process raft request' (duration: 192.314301ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.979203Z","caller":"traceutil/trace.go:171","msg":"trace[545194828] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"192.442124ms","start":"2026-07-24T10:57:07.786755Z","end":"2026-07-24T10:57:07.979197Z","steps":["trace[545194828] 'process raft request' (duration: 192.309921ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.979222Z","caller":"traceutil/trace.go:171","msg":"trace[1362341503] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"192.410804ms","start":"2026-07-24T10:57:07.786796Z","end":"2026-07-24T10:57:07.979207Z","steps":["trace[1362341503] 'process raft request' (duration: 192.308831ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:07.979203Z","caller":"traceutil/trace.go:171","msg":"trace[2008917929] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"192.395123ms","start":"2026-07-24T10:57:07.786787Z","end":"2026-07-24T10:57:07.979183Z","steps":["trace[2008917929] 'process raft request' (duration: 192.356802ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:08.294236Z","caller":"traceutil/trace.go:171","msg":"trace[677612341] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"481.304853ms","start":"2026-07-24T10:57:07.812901Z","end":"2026-07-24T10:57:08.294206Z","steps":["trace[677612341] 'process raft request' (duration: 469.7516ms)","trace[677612341] 'compare' (duration: 10.889079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:57:08.294409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:57:07.812879Z","time spent":"481.428115ms","remote":"127.0.0.1:40068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1152,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:57:08.311291Z","caller":"traceutil/trace.go:171","msg":"trace[661178799] linearizableReadLoop","detail":"{readStateIndex:26803; appliedIndex:26801; }","duration":"191.799961ms","start":"2026-07-24T10:57:08.119465Z","end":"2026-07-24T10:57:08.311265Z","steps":["trace[661178799] 'read index received' (duration: 163.350158ms)","trace[661178799] 'applied index is now lower than readState.Index' (duration: 28.448603ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:08.311376Z","caller":"traceutil/trace.go:171","msg":"trace[746373656] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"276.162289ms","start":"2026-07-24T10:57:08.035189Z","end":"2026-07-24T10:57:08.311352Z","steps":["trace[746373656] 'process raft request' (duration: 275.936305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:57:08.311598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.102529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T10:57:08.31165Z","caller":"traceutil/trace.go:171","msg":"trace[430829379] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25962; }","duration":"192.218891ms","start":"2026-07-24T10:57:08.11942Z","end":"2026-07-24T10:57:08.311639Z","steps":["trace[430829379] 'agreement among raft nodes before linearized reading' (duration: 191.972775ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:08.443606Z","caller":"traceutil/trace.go:171","msg":"trace[866656666] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"129.042416ms","start":"2026-07-24T10:57:08.314534Z","end":"2026-07-24T10:57:08.443576Z","steps":["trace[866656666] 'process raft request' (duration: 114.60192ms)","trace[866656666] 'compare' (duration: 14.197731ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:14.206242Z","caller":"traceutil/trace.go:171","msg":"trace[1917901702] transaction","detail":"{read_only:false; response_revision:26000; number_of_response:1; }","duration":"177.084339ms","start":"2026-07-24T10:57:14.029127Z","end":"2026-07-24T10:57:14.206211Z","steps":["trace[1917901702] 'process raft request' (duration: 150.462356ms)","trace[1917901702] 'compare' (duration: 26.433369ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:14.206391Z","caller":"traceutil/trace.go:171","msg":"trace[444670752] transaction","detail":"{read_only:false; response_revision:26001; number_of_response:1; }","duration":"177.086928ms","start":"2026-07-24T10:57:14.029288Z","end":"2026-07-24T10:57:14.206375Z","steps":["trace[444670752] 'process raft request' (duration: 176.877244ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:14.206647Z","caller":"traceutil/trace.go:171","msg":"trace[431603497] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"177.188981ms","start":"2026-07-24T10:57:14.029438Z","end":"2026-07-24T10:57:14.206627Z","steps":["trace[431603497] 'process raft request' (duration: 176.875534ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:14.206713Z","caller":"traceutil/trace.go:171","msg":"trace[325663738] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"176.385023ms","start":"2026-07-24T10:57:14.030314Z","end":"2026-07-24T10:57:14.2067Z","steps":["trace[325663738] 'process raft request' (duration: 176.344902ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:14.20676Z","caller":"traceutil/trace.go:171","msg":"trace[76412329] transaction","detail":"{read_only:false; response_revision:26003; number_of_response:1; }","duration":"176.756051ms","start":"2026-07-24T10:57:14.029957Z","end":"2026-07-24T10:57:14.206713Z","steps":["trace[76412329] 'process raft request' (duration: 176.603228ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:14.206858Z","caller":"traceutil/trace.go:171","msg":"trace[1836481211] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"176.724981ms","start":"2026-07-24T10:57:14.030094Z","end":"2026-07-24T10:57:14.206819Z","steps":["trace[1836481211] 'process raft request' (duration: 176.532147ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:16.611162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22615} {"level":"info","ts":"2026-07-24T10:57:16.725777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22615,"took":"107.202709ms","hash":2724383912,"current-db-size-bytes":59265024,"current-db-size":"59 MB","current-db-size-in-use-bytes":46690304,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-07-24T10:57:16.72584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2724383912,"revision":22615,"compact-revision":20202} {"level":"info","ts":"2026-07-24T10:57:32.338752Z","caller":"traceutil/trace.go:171","msg":"trace[1525461369] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"131.548972ms","start":"2026-07-24T10:57:32.207154Z","end":"2026-07-24T10:57:32.338703Z","steps":["trace[1525461369] 'process raft request' (duration: 131.259366ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.478296Z","caller":"traceutil/trace.go:171","msg":"trace[482552660] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"134.061376ms","start":"2026-07-24T10:57:32.344209Z","end":"2026-07-24T10:57:32.47827Z","steps":["trace[482552660] 'process raft request' (duration: 133.838831ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.47867Z","caller":"traceutil/trace.go:171","msg":"trace[977536415] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"134.285211ms","start":"2026-07-24T10:57:32.344346Z","end":"2026-07-24T10:57:32.478631Z","steps":["trace[977536415] 'process raft request' (duration: 133.875482ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.47874Z","caller":"traceutil/trace.go:171","msg":"trace[958935450] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"134.312741ms","start":"2026-07-24T10:57:32.344392Z","end":"2026-07-24T10:57:32.478705Z","steps":["trace[958935450] 'process raft request' (duration: 134.072886ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.478858Z","caller":"traceutil/trace.go:171","msg":"trace[1792493851] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"133.631557ms","start":"2026-07-24T10:57:32.345212Z","end":"2026-07-24T10:57:32.478844Z","steps":["trace[1792493851] 'process raft request' (duration: 133.466324ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.478749Z","caller":"traceutil/trace.go:171","msg":"trace[1207423382] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"133.75554ms","start":"2026-07-24T10:57:32.34497Z","end":"2026-07-24T10:57:32.478726Z","steps":["trace[1207423382] 'process raft request' (duration: 133.656948ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.478818Z","caller":"traceutil/trace.go:171","msg":"trace[1832988982] linearizableReadLoop","detail":"{readStateIndex:27141; appliedIndex:27135; }","duration":"131.992551ms","start":"2026-07-24T10:57:32.346811Z","end":"2026-07-24T10:57:32.478804Z","steps":["trace[1832988982] 'read index received' (duration: 3.177949ms)","trace[1832988982] 'applied index is now lower than readState.Index' (duration: 128.813262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:57:32.479003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.172694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T10:57:32.479048Z","caller":"traceutil/trace.go:171","msg":"trace[225711035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26293; }","duration":"132.262687ms","start":"2026-07-24T10:57:32.346773Z","end":"2026-07-24T10:57:32.479036Z","steps":["trace[225711035] 'agreement among raft nodes before linearized reading' (duration: 132.079123ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:57:32.617946Z","caller":"traceutil/trace.go:171","msg":"trace[1259848731] linearizableReadLoop","detail":"{readStateIndex:27142; appliedIndex:27141; }","duration":"137.017731ms","start":"2026-07-24T10:57:32.480901Z","end":"2026-07-24T10:57:32.617919Z","steps":["trace[1259848731] 'read index received' (duration: 122.812739ms)","trace[1259848731] 'applied index is now lower than readState.Index' (duration: 14.203892ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:57:32.618214Z","caller":"traceutil/trace.go:171","msg":"trace[680254973] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"140.460968ms","start":"2026-07-24T10:57:32.477523Z","end":"2026-07-24T10:57:32.617984Z","steps":["trace[680254973] 'process raft request' (duration: 126.142353ms)","trace[680254973] 'compare' (duration: 14.12561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:57:32.618532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.558334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18143"} {"level":"info","ts":"2026-07-24T10:57:32.618663Z","caller":"traceutil/trace.go:171","msg":"trace[584941343] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26294; }","duration":"137.793638ms","start":"2026-07-24T10:57:32.480841Z","end":"2026-07-24T10:57:32.618635Z","steps":["trace[584941343] 'agreement among raft nodes before linearized reading' (duration: 137.354438ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T11:02:16.619165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26022} {"level":"info","ts":"2026-07-24T11:02:16.777457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26022,"took":"153.346928ms","hash":1507685761,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":37306368,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-24T11:02:16.777542Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1507685761,"revision":26022,"compact-revision":22615} {"level":"info","ts":"2026-07-24T11:02:26.348607Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T11:02:26.354516Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-24T11:02:26.354661Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-24T11:07:16.637841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29011} {"level":"info","ts":"2026-07-24T11:07:16.778028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29011,"took":"137.122415ms","hash":362236234,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":31899648,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T11:07:16.77816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":362236234,"revision":29011,"compact-revision":26022} {"level":"info","ts":"2026-07-24T11:12:16.644348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31304} {"level":"info","ts":"2026-07-24T11:12:16.77556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31304,"took":"126.935547ms","hash":2214582589,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":31301632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T11:12:16.775622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2214582589,"revision":31304,"compact-revision":29011} {"level":"info","ts":"2026-07-24T11:17:16.651012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33609} {"level":"info","ts":"2026-07-24T11:17:16.814079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33609,"took":"159.498751ms","hash":825225590,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":32845824,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T11:17:16.81419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":825225590,"revision":33609,"compact-revision":31304} {"level":"info","ts":"2026-07-24T11:22:16.659605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35951} {"level":"info","ts":"2026-07-24T11:22:16.803951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35951,"took":"139.275257ms","hash":1511611760,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T11:22:16.804044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1511611760,"revision":35951,"compact-revision":33609} {"level":"info","ts":"2026-07-24T11:23:37.653027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T11:23:37.657573Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-24T11:23:37.657647Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-24T11:27:16.667159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38219} {"level":"info","ts":"2026-07-24T11:27:16.815164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38219,"took":"143.259094ms","hash":1158222921,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":30408704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T11:27:16.815247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1158222921,"revision":38219,"compact-revision":35951} {"level":"info","ts":"2026-07-24T11:32:16.673655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40546} {"level":"info","ts":"2026-07-24T11:32:16.801142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40546,"took":"123.892328ms","hash":3558853235,"current-db-size-bytes":61149184,"current-db-size":"61 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T11:32:16.80123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3558853235,"revision":40546,"compact-revision":38219}