{"level":"warn","ts":"2026-04-28T07:25:12.818245Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:25:12.81835Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-04-28T07:25:12.81843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:25:12.818447Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-28T07:25:12.818468Z","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-04-28T07:25:12.819331Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-04-28T07:25:12.819423Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-04-28T07:25:12.833644Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.95469ms"} {"level":"info","ts":"2026-04-28T07:25:12.845868Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-04-28T07:25:12.845981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T07:25:12.846012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-04-28T07:25:12.846034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T07:25:12.846058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-04-28T07:25:12.846104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-04-28T07:25:12.862912Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T07:25:12.866874Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T07:25:12.868445Z","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-04-28T07:25:12.872961Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T07:25:12.873423Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T07:25:12.874623Z","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-04-28T07:25:12.8748Z","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-04-28T07:25:12.874842Z","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-04-28T07:25:12.875698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-04-28T07:25:12.876092Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-28T07:25:12.87912Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-28T07:25:12.879138Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-28T07:25:12.879127Z","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-04-28T07:25:12.87968Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T07:25:12.879704Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T07:25:13.346929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T07:25:13.347052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T07:25:13.347078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-04-28T07:25:13.347097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-04-28T07:25:13.347108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-28T07:25:13.347124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-04-28T07:25:13.347137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-28T07:25:13.351908Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:25:13.355483Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:25:13.355496Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T07:25:13.355552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:25:13.356009Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T07:25:13.356117Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T07:25:13.356302Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:25:13.356426Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:25:13.356473Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:25:13.35957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T07:25:13.362107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"warn","ts":"2026-04-28T07:25:29.912355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.220311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:25:29.912508Z","caller":"traceutil/trace.go:171","msg":"trace[1929551315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"198.441323ms","start":"2026-04-28T07:25:29.714045Z","end":"2026-04-28T07:25:29.912486Z","steps":["trace[1929551315] 'range keys from in-memory index tree' (duration: 198.135531ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:30.188709Z","caller":"traceutil/trace.go:171","msg":"trace[1920412624] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"263.215849ms","start":"2026-04-28T07:25:29.925455Z","end":"2026-04-28T07:25:30.188671Z","steps":["trace[1920412624] 'process raft request' (duration: 263.007358ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:43.11176Z","caller":"traceutil/trace.go:171","msg":"trace[1402407582] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"110.529126ms","start":"2026-04-28T07:25:43.00122Z","end":"2026-04-28T07:25:43.111749Z","steps":["trace[1402407582] 'process raft request' (duration: 110.228241ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:43.111706Z","caller":"traceutil/trace.go:171","msg":"trace[1905748361] transaction","detail":"{read_only:false; number_of_response:1; response_revision:489; }","duration":"110.972939ms","start":"2026-04-28T07:25:43.000706Z","end":"2026-04-28T07:25:43.111679Z","steps":["trace[1905748361] 'process raft request' (duration: 89.666051ms)","trace[1905748361] 'compare' (duration: 21.001703ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:25:43.112672Z","caller":"traceutil/trace.go:171","msg":"trace[1171259881] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"110.3913ms","start":"2026-04-28T07:25:43.002258Z","end":"2026-04-28T07:25:43.11265Z","steps":["trace[1171259881] 'process raft request' (duration: 109.312497ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:43.114134Z","caller":"traceutil/trace.go:171","msg":"trace[1926306289] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"105.579913ms","start":"2026-04-28T07:25:43.008541Z","end":"2026-04-28T07:25:43.114121Z","steps":["trace[1926306289] 'process raft request' (duration: 104.0579ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:43.114326Z","caller":"traceutil/trace.go:171","msg":"trace[1338382704] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:511; }","duration":"105.534742ms","start":"2026-04-28T07:25:43.008777Z","end":"2026-04-28T07:25:43.114312Z","steps":["trace[1338382704] 'read index received' (duration: 81.652149ms)","trace[1338382704] 'applied index is now lower than readState.Index' (duration: 23.880072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:25:43.114464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.669658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-28T07:25:43.114503Z","caller":"traceutil/trace.go:171","msg":"trace[1166945725] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:496; }","duration":"105.71905ms","start":"2026-04-28T07:25:43.008772Z","end":"2026-04-28T07:25:43.114491Z","steps":["trace[1166945725] 'agreement among raft nodes before linearized reading' (duration: 105.591844ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:49.14846Z","caller":"traceutil/trace.go:171","msg":"trace[1092587125] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"218.60734ms","start":"2026-04-28T07:25:48.9298Z","end":"2026-04-28T07:25:49.148407Z","steps":["trace[1092587125] 'process raft request' (duration: 218.401101ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:25:49.486725Z","caller":"traceutil/trace.go:171","msg":"trace[1508634914] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"465.161858ms","start":"2026-04-28T07:25:49.021536Z","end":"2026-04-28T07:25:49.486698Z","steps":["trace[1508634914] 'process raft request' (duration: 464.617243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:25:49.487426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:25:49.0215Z","time spent":"465.377668ms","remote":"127.0.0.1:40024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:25:50.204608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.18252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:25:50.204737Z","caller":"traceutil/trace.go:171","msg":"trace[1933069203] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"660.071763ms","start":"2026-04-28T07:25:49.544651Z","end":"2026-04-28T07:25:50.204723Z","steps":["trace[1933069203] 'process raft request' (duration: 128.272765ms)","trace[1933069203] 'compare' (duration: 530.974811ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:25:50.204724Z","caller":"traceutil/trace.go:171","msg":"trace[309990074] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"492.164876ms","start":"2026-04-28T07:25:49.712544Z","end":"2026-04-28T07:25:50.204709Z","steps":["trace[309990074] 'read index received' (duration: 66.763µs)","trace[309990074] 'applied index is now lower than readState.Index' (duration: 492.097013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:25:50.204786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:25:49.544611Z","time spent":"660.154327ms","remote":"127.0.0.1:40422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:25:50.204928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.385325ms","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-04-28T07:25:50.204961Z","caller":"traceutil/trace.go:171","msg":"trace[314920722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"492.457019ms","start":"2026-04-28T07:25:49.712495Z","end":"2026-04-28T07:25:50.204952Z","steps":["trace[314920722] 'agreement among raft nodes before linearized reading' (duration: 492.278021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:25:50.205018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:25:49.712475Z","time spent":"492.535202ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:26:04.830668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.023788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:26:04.830831Z","caller":"traceutil/trace.go:171","msg":"trace[219638093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"117.255107ms","start":"2026-04-28T07:26:04.713557Z","end":"2026-04-28T07:26:04.830812Z","steps":["trace[219638093] 'range keys from in-memory index tree' (duration: 116.947495ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:26:04.972232Z","caller":"traceutil/trace.go:171","msg":"trace[304764996] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"138.007099ms","start":"2026-04-28T07:26:04.834198Z","end":"2026-04-28T07:26:04.972205Z","steps":["trace[304764996] 'process raft request' (duration: 137.831562ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:26:59.390318Z","caller":"traceutil/trace.go:171","msg":"trace[1852210187] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"123.419141ms","start":"2026-04-28T07:26:59.266874Z","end":"2026-04-28T07:26:59.390294Z","steps":["trace[1852210187] 'process raft request' (duration: 115.062273ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:27:07.50182Z","caller":"traceutil/trace.go:171","msg":"trace[1825993015] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"128.762015ms","start":"2026-04-28T07:27:07.373022Z","end":"2026-04-28T07:27:07.501784Z","steps":["trace[1825993015] 'process raft request' (duration: 110.496701ms)","trace[1825993015] 'compare' (duration: 18.011909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:27:32.813782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.047321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:27:32.8139Z","caller":"traceutil/trace.go:171","msg":"trace[1480930743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"100.221334ms","start":"2026-04-28T07:27:32.713655Z","end":"2026-04-28T07:27:32.813876Z","steps":["trace[1480930743] 'range keys from in-memory index tree' (duration: 99.91826ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:27:45.167152Z","caller":"traceutil/trace.go:171","msg":"trace[10531140] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"189.548586ms","start":"2026-04-28T07:27:44.977575Z","end":"2026-04-28T07:27:45.167123Z","steps":["trace[10531140] 'process raft request' (duration: 189.352243ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:28:06.863689Z","caller":"traceutil/trace.go:171","msg":"trace[642726920] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2164; }","duration":"150.664397ms","start":"2026-04-28T07:28:06.713005Z","end":"2026-04-28T07:28:06.86367Z","steps":["trace[642726920] 'read index received' (duration: 150.514565ms)","trace[642726920] 'applied index is now lower than readState.Index' (duration: 149.092µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:28:06.863786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.775549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:28:06.863808Z","caller":"traceutil/trace.go:171","msg":"trace[1197567590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2083; }","duration":"150.84717ms","start":"2026-04-28T07:28:06.712954Z","end":"2026-04-28T07:28:06.863801Z","steps":["trace[1197567590] 'agreement among raft nodes before linearized reading' (duration: 150.792199ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:28:06.863947Z","caller":"traceutil/trace.go:171","msg":"trace[1080010788] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"161.440563ms","start":"2026-04-28T07:28:06.70248Z","end":"2026-04-28T07:28:06.863921Z","steps":["trace[1080010788] 'process raft request' (duration: 161.053518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:28:07.835767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.419079ms","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-04-28T07:28:07.835843Z","caller":"traceutil/trace.go:171","msg":"trace[522102838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2086; }","duration":"452.53896ms","start":"2026-04-28T07:28:07.383287Z","end":"2026-04-28T07:28:07.835826Z","steps":["trace[522102838] 'range keys from in-memory index tree' (duration: 452.201015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:28:07.835887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:28:07.383269Z","time spent":"452.60475ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:28:07.835909Z","caller":"traceutil/trace.go:171","msg":"trace[975535247] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2168; }","duration":"138.819356ms","start":"2026-04-28T07:28:07.697061Z","end":"2026-04-28T07:28:07.83588Z","steps":["trace[975535247] 'read index received' (duration: 138.36651ms)","trace[975535247] 'applied index is now lower than readState.Index' (duration: 446.266µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:28:07.835998Z","caller":"traceutil/trace.go:171","msg":"trace[254823229] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"212.082909ms","start":"2026-04-28T07:28:07.623877Z","end":"2026-04-28T07:28:07.83596Z","steps":["trace[254823229] 'process raft request' (duration: 211.617742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:28:07.836291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.216503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-28T07:28:07.836383Z","caller":"traceutil/trace.go:171","msg":"trace[340727771] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"139.343174ms","start":"2026-04-28T07:28:07.697019Z","end":"2026-04-28T07:28:07.836362Z","steps":["trace[340727771] 'agreement among raft nodes before linearized reading' (duration: 139.02197ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:29:29.491312Z","caller":"traceutil/trace.go:171","msg":"trace[500578655] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"109.513299ms","start":"2026-04-28T07:29:29.381775Z","end":"2026-04-28T07:29:29.491288Z","steps":["trace[500578655] 'process raft request' (duration: 109.356967ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:29:35.336866Z","caller":"traceutil/trace.go:171","msg":"trace[318514104] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"140.765215ms","start":"2026-04-28T07:29:35.196072Z","end":"2026-04-28T07:29:35.336837Z","steps":["trace[318514104] 'process raft request' (duration: 140.615983ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:19.618786Z","caller":"traceutil/trace.go:171","msg":"trace[859545101] transaction","detail":"{read_only:false; response_revision:4438; number_of_response:1; }","duration":"101.620412ms","start":"2026-04-28T07:33:19.517137Z","end":"2026-04-28T07:33:19.618757Z","steps":["trace[859545101] 'process raft request' (duration: 67.749561ms)","trace[859545101] 'compare' (duration: 33.720039ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:21.092738Z","caller":"traceutil/trace.go:171","msg":"trace[200246622] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"100.886446ms","start":"2026-04-28T07:33:20.991832Z","end":"2026-04-28T07:33:21.092719Z","steps":["trace[200246622] 'process raft request' (duration: 100.770494ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:27.012959Z","caller":"traceutil/trace.go:171","msg":"trace[1883422237] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4834; }","duration":"108.584956ms","start":"2026-04-28T07:33:26.904354Z","end":"2026-04-28T07:33:27.012939Z","steps":["trace[1883422237] 'read index received' (duration: 108.491805ms)","trace[1883422237] 'applied index is now lower than readState.Index' (duration: 92.381µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:27.01301Z","caller":"traceutil/trace.go:171","msg":"trace[36178401] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"121.415214ms","start":"2026-04-28T07:33:26.891573Z","end":"2026-04-28T07:33:27.012988Z","steps":["trace[36178401] 'process raft request' (duration: 121.245082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:27.013765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.394378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-28T07:33:27.013832Z","caller":"traceutil/trace.go:171","msg":"trace[838968970] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4657; }","duration":"109.501649ms","start":"2026-04-28T07:33:26.904317Z","end":"2026-04-28T07:33:27.013819Z","steps":["trace[838968970] 'agreement among raft nodes before linearized reading' (duration: 108.755198ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:27.180948Z","caller":"traceutil/trace.go:171","msg":"trace[1108959172] linearizableReadLoop","detail":"{readStateIndex:4838; appliedIndex:4836; }","duration":"132.804677ms","start":"2026-04-28T07:33:27.048123Z","end":"2026-04-28T07:33:27.180928Z","steps":["trace[1108959172] 'read index received' (duration: 93.490674ms)","trace[1108959172] 'applied index is now lower than readState.Index' (duration: 39.313103ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:27.181027Z","caller":"traceutil/trace.go:171","msg":"trace[199437825] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"135.159262ms","start":"2026-04-28T07:33:27.045834Z","end":"2026-04-28T07:33:27.180994Z","steps":["trace[199437825] 'process raft request' (duration: 134.942429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:27.181134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.9913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-04-28T07:33:27.181172Z","caller":"traceutil/trace.go:171","msg":"trace[1630615200] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4659; }","duration":"133.057111ms","start":"2026-04-28T07:33:27.048104Z","end":"2026-04-28T07:33:27.181161Z","steps":["trace[1630615200] 'agreement among raft nodes before linearized reading' (duration: 132.926669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:27.187387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.272783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-28T07:33:27.187447Z","caller":"traceutil/trace.go:171","msg":"trace[1812117189] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4660; }","duration":"135.369304ms","start":"2026-04-28T07:33:27.052064Z","end":"2026-04-28T07:33:27.187433Z","steps":["trace[1812117189] 'agreement among raft nodes before linearized reading' (duration: 135.186371ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:40.253672Z","caller":"traceutil/trace.go:171","msg":"trace[14682506] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"668.866538ms","start":"2026-04-28T07:33:39.58479Z","end":"2026-04-28T07:33:40.253656Z","steps":["trace[14682506] 'process raft request' (duration: 668.761307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.253775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:39.584781Z","time spent":"668.937979ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:40.752438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.880872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:40.752706Z","caller":"traceutil/trace.go:171","msg":"trace[802392532] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"1.165332274s","start":"2026-04-28T07:33:39.587295Z","end":"2026-04-28T07:33:40.752627Z","steps":["trace[802392532] 'process raft request' (duration: 817.155158ms)","trace[802392532] 'compare' (duration: 347.519087ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:40.752808Z","caller":"traceutil/trace.go:171","msg":"trace[451023710] linearizableReadLoop","detail":"{readStateIndex:5022; appliedIndex:5019; }","duration":"965.440062ms","start":"2026-04-28T07:33:39.787339Z","end":"2026-04-28T07:33:40.752779Z","steps":["trace[451023710] 'read index received' (duration: 466.171257ms)","trace[451023710] 'applied index is now lower than readState.Index' (duration: 499.267455ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:40.753064Z","caller":"traceutil/trace.go:171","msg":"trace[1359252502] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"1.029048054s","start":"2026-04-28T07:33:39.723993Z","end":"2026-04-28T07:33:40.753041Z","steps":["trace[1359252502] 'process raft request' (duration: 1.028546306s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.753083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.718657ms","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:5"} {"level":"warn","ts":"2026-04-28T07:33:40.753114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:39.58727Z","time spent":"1.165481266s","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:40.753363Z","caller":"traceutil/trace.go:171","msg":"trace[1682263507] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4837; }","duration":"965.873529ms","start":"2026-04-28T07:33:39.78729Z","end":"2026-04-28T07:33:40.753164Z","steps":["trace[1682263507] 'agreement among raft nodes before linearized reading' (duration: 965.704906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.753418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:39.78727Z","time spent":"966.133213ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:33:40.753456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:39.723973Z","time spent":"1.029136625s","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:40.779839Z","caller":"traceutil/trace.go:171","msg":"trace[1601970735] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"524.563397ms","start":"2026-04-28T07:33:40.255242Z","end":"2026-04-28T07:33:40.779805Z","steps":["trace[1601970735] 'process raft request' (duration: 524.366094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.780034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.25522Z","time spent":"524.713629ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:40.803757Z","caller":"traceutil/trace.go:171","msg":"trace[1985177691] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"274.918759ms","start":"2026-04-28T07:33:40.528812Z","end":"2026-04-28T07:33:40.803731Z","steps":["trace[1985177691] 'process raft request' (duration: 274.661306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.803816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.728468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-04-28T07:33:40.80398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.978697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T07:33:40.804018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.355233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:40.804098Z","caller":"traceutil/trace.go:171","msg":"trace[179285842] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4839; }","duration":"738.471915ms","start":"2026-04-28T07:33:40.065611Z","end":"2026-04-28T07:33:40.804083Z","steps":["trace[179285842] 'agreement among raft nodes before linearized reading' (duration: 738.379134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.80412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.955293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-28T07:33:40.804158Z","caller":"traceutil/trace.go:171","msg":"trace[989786571] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4839; }","duration":"689.142149ms","start":"2026-04-28T07:33:40.114931Z","end":"2026-04-28T07:33:40.804073Z","steps":["trace[989786571] 'agreement among raft nodes before linearized reading' (duration: 688.958227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.804191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.06559Z","time spent":"738.587747ms","remote":"127.0.0.1:51056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-28T07:33:40.804203Z","caller":"traceutil/trace.go:171","msg":"trace[1681770343] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4839; }","duration":"616.097266ms","start":"2026-04-28T07:33:40.188086Z","end":"2026-04-28T07:33:40.804183Z","steps":["trace[1681770343] 'agreement among raft nodes before linearized reading' (duration: 615.828252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.80427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.188065Z","time spent":"616.196257ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-28T07:33:40.804287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.114905Z","time spent":"689.367433ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-04-28T07:33:40.804036Z","caller":"traceutil/trace.go:171","msg":"trace[675930901] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4839; }","duration":"349.987962ms","start":"2026-04-28T07:33:40.45403Z","end":"2026-04-28T07:33:40.804018Z","steps":["trace[675930901] 'agreement among raft nodes before linearized reading' (duration: 349.628687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.804489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.148349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T07:33:40.804537Z","caller":"traceutil/trace.go:171","msg":"trace[22859129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4839; }","duration":"125.23505ms","start":"2026-04-28T07:33:40.679289Z","end":"2026-04-28T07:33:40.804524Z","steps":["trace[22859129] 'agreement among raft nodes before linearized reading' (duration: 125.119579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.804487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.454012Z","time spent":"350.454569ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-28T07:33:40.803922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.829305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:40.80473Z","caller":"traceutil/trace.go:171","msg":"trace[1152995832] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4839; }","duration":"500.643606ms","start":"2026-04-28T07:33:40.304072Z","end":"2026-04-28T07:33:40.804716Z","steps":["trace[1152995832] 'agreement among raft nodes before linearized reading' (duration: 499.756714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.804786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.304011Z","time spent":"500.758358ms","remote":"127.0.0.1:40074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:33:40.804848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.746196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-28T07:33:40.804903Z","caller":"traceutil/trace.go:171","msg":"trace[1839559106] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4839; }","duration":"635.801036ms","start":"2026-04-28T07:33:40.169087Z","end":"2026-04-28T07:33:40.804888Z","steps":["trace[1839559106] 'agreement among raft nodes before linearized reading' (duration: 635.616584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.804935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.169043Z","time spent":"635.884688ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-28T07:33:40.805319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.362013ms","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-04-28T07:33:40.805373Z","caller":"traceutil/trace.go:171","msg":"trace[1521436169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4839; }","duration":"975.458255ms","start":"2026-04-28T07:33:39.829902Z","end":"2026-04-28T07:33:40.80536Z","steps":["trace[1521436169] 'agreement among raft nodes before linearized reading' (duration: 973.714861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:40.805403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:39.829877Z","time spent":"975.518526ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T07:33:41.025593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.19482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:41.025711Z","caller":"traceutil/trace.go:171","msg":"trace[1038761941] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"216.124729ms","start":"2026-04-28T07:33:40.809536Z","end":"2026-04-28T07:33:41.02566Z","steps":["trace[1038761941] 'process raft request' (duration: 76.807148ms)","trace[1038761941] 'compare' (duration: 139.109938ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:41.095751Z","caller":"traceutil/trace.go:171","msg":"trace[409440720] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"286.133288ms","start":"2026-04-28T07:33:40.809597Z","end":"2026-04-28T07:33:41.09573Z","steps":["trace[409440720] 'process raft request' (duration: 285.827623ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:41.096081Z","caller":"traceutil/trace.go:171","msg":"trace[1266153838] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"285.128854ms","start":"2026-04-28T07:33:40.810914Z","end":"2026-04-28T07:33:41.096042Z","steps":["trace[1266153838] 'process raft request' (duration: 284.686908ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:41.096191Z","caller":"traceutil/trace.go:171","msg":"trace[335758644] linearizableReadLoop","detail":"{readStateIndex:5028; appliedIndex:5025; }","duration":"182.974183ms","start":"2026-04-28T07:33:40.913204Z","end":"2026-04-28T07:33:41.096179Z","steps":["trace[335758644] 'read index received' (duration: 182.288453ms)","trace[335758644] 'applied index is now lower than readState.Index' (duration: 684.63µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:41.096243Z","caller":"traceutil/trace.go:171","msg":"trace[996643175] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"280.555448ms","start":"2026-04-28T07:33:40.815664Z","end":"2026-04-28T07:33:41.096219Z","steps":["trace[996643175] 'process raft request' (duration: 280.299274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.096374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.166635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-28T07:33:41.096719Z","caller":"traceutil/trace.go:171","msg":"trace[1630221595] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4843; }","duration":"183.545241ms","start":"2026-04-28T07:33:40.913145Z","end":"2026-04-28T07:33:41.096691Z","steps":["trace[1630221595] 'agreement among raft nodes before linearized reading' (duration: 183.069705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.39558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.223591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:41.395676Z","caller":"traceutil/trace.go:171","msg":"trace[1648671347] linearizableReadLoop","detail":"{readStateIndex:5029; appliedIndex:5028; }","duration":"299.17021ms","start":"2026-04-28T07:33:41.09649Z","end":"2026-04-28T07:33:41.395661Z","steps":["trace[1648671347] 'read index received' (duration: 48.832638ms)","trace[1648671347] 'applied index is now lower than readState.Index' (duration: 250.336512ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:41.396045Z","caller":"traceutil/trace.go:171","msg":"trace[694938513] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"313.93375ms","start":"2026-04-28T07:33:41.08207Z","end":"2026-04-28T07:33:41.396004Z","steps":["trace[694938513] 'process raft request' (duration: 63.209402ms)","trace[694938513] 'compare' (duration: 250.067979ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:41.396197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.08205Z","time spent":"314.069042ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:41.396404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.638033ms","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-04-28T07:33:41.39646Z","caller":"traceutil/trace.go:171","msg":"trace[1850534244] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4844; }","duration":"478.757515ms","start":"2026-04-28T07:33:40.917686Z","end":"2026-04-28T07:33:41.396444Z","steps":["trace[1850534244] 'agreement among raft nodes before linearized reading' (duration: 478.39773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.396684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:40.917669Z","time spent":"478.923067ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T07:33:41.414208Z","caller":"traceutil/trace.go:171","msg":"trace[1307659273] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"311.677289ms","start":"2026-04-28T07:33:41.102515Z","end":"2026-04-28T07:33:41.414192Z","steps":["trace[1307659273] 'process raft request' (duration: 311.541667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.414314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.102493Z","time spent":"311.76728ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:41.414517Z","caller":"traceutil/trace.go:171","msg":"trace[1295774057] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"311.632368ms","start":"2026-04-28T07:33:41.102859Z","end":"2026-04-28T07:33:41.414492Z","steps":["trace[1295774057] 'process raft request' (duration: 311.295603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.414789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.102839Z","time spent":"311.81938ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:41.415954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.23945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-90a6fa04-fa54-498f-af03-42db74d6ae62\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-28T07:33:41.416058Z","caller":"traceutil/trace.go:171","msg":"trace[1114618188] range","detail":"{range_begin:/registry/persistentvolumes/pvc-90a6fa04-fa54-498f-af03-42db74d6ae62; range_end:; response_count:1; response_revision:4848; }","duration":"313.366692ms","start":"2026-04-28T07:33:41.102673Z","end":"2026-04-28T07:33:41.41604Z","steps":["trace[1114618188] 'agreement among raft nodes before linearized reading' (duration: 313.142009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.416219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.102654Z","time spent":"313.511825ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1457,"request content":"key:\"/registry/persistentvolumes/pvc-90a6fa04-fa54-498f-af03-42db74d6ae62\" "} {"level":"warn","ts":"2026-04-28T07:33:41.416445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.182595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-28T07:33:41.416436Z","caller":"traceutil/trace.go:171","msg":"trace[1133465979] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"313.487364ms","start":"2026-04-28T07:33:41.102911Z","end":"2026-04-28T07:33:41.416399Z","steps":["trace[1133465979] 'process raft request' (duration: 311.413915ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:41.416507Z","caller":"traceutil/trace.go:171","msg":"trace[467685119] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4848; }","duration":"284.288167ms","start":"2026-04-28T07:33:41.132206Z","end":"2026-04-28T07:33:41.416494Z","steps":["trace[467685119] 'agreement among raft nodes before linearized reading' (duration: 284.111715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.416487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.625441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17209"} {"level":"warn","ts":"2026-04-28T07:33:41.416602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.1029Z","time spent":"313.620005ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:41.416707Z","caller":"traceutil/trace.go:171","msg":"trace[202667276] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4848; }","duration":"312.800263ms","start":"2026-04-28T07:33:41.103827Z","end":"2026-04-28T07:33:41.416628Z","steps":["trace[202667276] 'agreement among raft nodes before linearized reading' (duration: 312.531879ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:41.416456Z","caller":"traceutil/trace.go:171","msg":"trace[1147847898] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"313.491624ms","start":"2026-04-28T07:33:41.10295Z","end":"2026-04-28T07:33:41.416442Z","steps":["trace[1147847898] 'process raft request' (duration: 312.788765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.416814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.102942Z","time spent":"313.837419ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:41.416883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.103819Z","time spent":"313.011017ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17231,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-28T07:33:41.417052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.66877ms","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-04-28T07:33:41.417097Z","caller":"traceutil/trace.go:171","msg":"trace[1714776056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4848; }","duration":"166.75145ms","start":"2026-04-28T07:33:41.250335Z","end":"2026-04-28T07:33:41.417086Z","steps":["trace[1714776056] 'agreement among raft nodes before linearized reading' (duration: 166.642939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.417222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.795394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:33:41.417346Z","caller":"traceutil/trace.go:171","msg":"trace[1012811793] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4848; }","duration":"265.923696ms","start":"2026-04-28T07:33:41.15141Z","end":"2026-04-28T07:33:41.417333Z","steps":["trace[1012811793] 'agreement among raft nodes before linearized reading' (duration: 264.765449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.856795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.607021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:41.857103Z","caller":"traceutil/trace.go:171","msg":"trace[640915735] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"455.757145ms","start":"2026-04-28T07:33:41.401324Z","end":"2026-04-28T07:33:41.857081Z","steps":["trace[640915735] 'process raft request' (duration: 93.775478ms)","trace[640915735] 'compare' (duration: 361.431828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:41.857223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.401303Z","time spent":"455.868206ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:41.857244Z","caller":"traceutil/trace.go:171","msg":"trace[597544934] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"434.005734ms","start":"2026-04-28T07:33:41.42322Z","end":"2026-04-28T07:33:41.857226Z","steps":["trace[597544934] 'read index received' (duration: 71.905075ms)","trace[597544934] 'applied index is now lower than readState.Index' (duration: 362.097919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:41.85739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.172086ms","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-04-28T07:33:41.857422Z","caller":"traceutil/trace.go:171","msg":"trace[1628770862] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4849; }","duration":"434.233747ms","start":"2026-04-28T07:33:41.42318Z","end":"2026-04-28T07:33:41.857414Z","steps":["trace[1628770862] 'agreement among raft nodes before linearized reading' (duration: 434.118056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.857441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.423165Z","time spent":"434.271748ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-28T07:33:41.857769Z","caller":"traceutil/trace.go:171","msg":"trace[2040475969] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"434.498281ms","start":"2026-04-28T07:33:41.423252Z","end":"2026-04-28T07:33:41.85775Z","steps":["trace[2040475969] 'process raft request' (duration: 434.264768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.857868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.423214Z","time spent":"434.607392ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:41.858003Z","caller":"traceutil/trace.go:171","msg":"trace[1501713825] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"427.241198ms","start":"2026-04-28T07:33:41.430705Z","end":"2026-04-28T07:33:41.857946Z","steps":["trace[1501713825] 'process raft request' (duration: 426.991284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:41.858279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.430696Z","time spent":"427.38847ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17194,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:42.218533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.663753ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T07:33:42.218966Z","caller":"traceutil/trace.go:171","msg":"trace[953267221] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"356.29502ms","start":"2026-04-28T07:33:41.862656Z","end":"2026-04-28T07:33:42.218951Z","steps":["trace[953267221] 'process raft request' (duration: 355.967976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.219056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.862619Z","time spent":"356.376451ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:42.219443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.933994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-28T07:33:42.21951Z","caller":"traceutil/trace.go:171","msg":"trace[647369352] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4852; }","duration":"796.012405ms","start":"2026-04-28T07:33:41.423489Z","end":"2026-04-28T07:33:42.219501Z","steps":["trace[647369352] 'agreement among raft nodes before linearized reading' (duration: 795.884683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.219533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.423483Z","time spent":"796.042505ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-28T07:33:42.218962Z","caller":"traceutil/trace.go:171","msg":"trace[193884816] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5034; }","duration":"361.632537ms","start":"2026-04-28T07:33:41.85728Z","end":"2026-04-28T07:33:42.218913Z","steps":["trace[193884816] 'read index received' (duration: 48.481µs)","trace[193884816] 'applied index is now lower than readState.Index' (duration: 361.580136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:42.220531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.452038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:33:42.220596Z","caller":"traceutil/trace.go:171","msg":"trace[386461108] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4851; }","duration":"587.56548ms","start":"2026-04-28T07:33:41.633019Z","end":"2026-04-28T07:33:42.220584Z","steps":["trace[386461108] 'agreement among raft nodes before linearized reading' (duration: 585.899067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.220629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.632997Z","time spent":"587.623041ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":32,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:33:42.220968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.573333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17209"} {"level":"info","ts":"2026-04-28T07:33:42.221202Z","caller":"traceutil/trace.go:171","msg":"trace[702688395] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4852; }","duration":"358.867897ms","start":"2026-04-28T07:33:41.862319Z","end":"2026-04-28T07:33:42.221187Z","steps":["trace[702688395] 'agreement among raft nodes before linearized reading' (duration: 358.48814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.221448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:41.862296Z","time spent":"358.935128ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17231,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-28T07:33:42.221499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.482581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:42.221569Z","caller":"traceutil/trace.go:171","msg":"trace[1243259962] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4852; }","duration":"149.574882ms","start":"2026-04-28T07:33:42.071957Z","end":"2026-04-28T07:33:42.221532Z","steps":["trace[1243259962] 'agreement among raft nodes before linearized reading' (duration: 149.493711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.585155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.190514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:42.585285Z","caller":"traceutil/trace.go:171","msg":"trace[59699784] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5038; }","duration":"326.862308ms","start":"2026-04-28T07:33:42.258399Z","end":"2026-04-28T07:33:42.585262Z","steps":["trace[59699784] 'read index received' (duration: 114.448611ms)","trace[59699784] 'applied index is now lower than readState.Index' (duration: 212.411627ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:42.585768Z","caller":"traceutil/trace.go:171","msg":"trace[56422734] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"348.792452ms","start":"2026-04-28T07:33:42.23695Z","end":"2026-04-28T07:33:42.585742Z","steps":["trace[56422734] 'process raft request' (duration: 135.907648ms)","trace[56422734] 'compare' (duration: 211.943649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:42.586044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.602331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-04-28T07:33:42.586155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.23678Z","time spent":"349.320989ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17010,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:42.586179Z","caller":"traceutil/trace.go:171","msg":"trace[1755659001] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4853; }","duration":"257.780103ms","start":"2026-04-28T07:33:42.328369Z","end":"2026-04-28T07:33:42.58615Z","steps":["trace[1755659001] 'agreement among raft nodes before linearized reading' (duration: 257.415188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.586034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.626429ms","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-04-28T07:33:42.586276Z","caller":"traceutil/trace.go:171","msg":"trace[403317380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4853; }","duration":"327.893563ms","start":"2026-04-28T07:33:42.258354Z","end":"2026-04-28T07:33:42.586248Z","steps":["trace[403317380] 'agreement among raft nodes before linearized reading' (duration: 327.492597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.586379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.258323Z","time spent":"328.017144ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:33:42.99491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.694801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:42.995431Z","caller":"traceutil/trace.go:171","msg":"trace[956412875] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"402.07882ms","start":"2026-04-28T07:33:42.59333Z","end":"2026-04-28T07:33:42.995409Z","steps":["trace[956412875] 'process raft request' (duration: 401.968198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.997567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.593324Z","time spent":"404.17907ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:42.995609Z","caller":"traceutil/trace.go:171","msg":"trace[1397812763] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"402.375364ms","start":"2026-04-28T07:33:42.5932Z","end":"2026-04-28T07:33:42.995576Z","steps":["trace[1397812763] 'process raft request' (duration: 275.919472ms)","trace[1397812763] 'compare' (duration: 125.334477ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:42.99574Z","caller":"traceutil/trace.go:171","msg":"trace[762347869] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"402.455965ms","start":"2026-04-28T07:33:42.593255Z","end":"2026-04-28T07:33:42.995711Z","steps":["trace[762347869] 'process raft request' (duration: 401.876447ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:42.995822Z","caller":"traceutil/trace.go:171","msg":"trace[1510701490] linearizableReadLoop","detail":"{readStateIndex:5045; appliedIndex:5040; }","duration":"335.320498ms","start":"2026-04-28T07:33:42.660483Z","end":"2026-04-28T07:33:42.995803Z","steps":["trace[1510701490] 'read index received' (duration: 208.654474ms)","trace[1510701490] 'applied index is now lower than readState.Index' (duration: 126.664994ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:42.995837Z","caller":"traceutil/trace.go:171","msg":"trace[1658613098] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"393.261564ms","start":"2026-04-28T07:33:42.602559Z","end":"2026-04-28T07:33:42.995821Z","steps":["trace[1658613098] 'process raft request' (duration: 393.111461ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:42.995873Z","caller":"traceutil/trace.go:171","msg":"trace[1592062982] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"402.435174ms","start":"2026-04-28T07:33:42.593414Z","end":"2026-04-28T07:33:42.995849Z","steps":["trace[1592062982] 'process raft request' (duration: 401.944077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.9959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.41981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:42.998578Z","caller":"traceutil/trace.go:171","msg":"trace[2065014760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4859; }","duration":"338.133149ms","start":"2026-04-28T07:33:42.660425Z","end":"2026-04-28T07:33:42.998558Z","steps":["trace[2065014760] 'agreement among raft nodes before linearized reading' (duration: 335.43418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:42.998682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.660406Z","time spent":"338.2581ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:33:42.998745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.593242Z","time spent":"405.409817ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:42.998822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.602544Z","time spent":"396.200965ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15333,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:42.998981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.593396Z","time spent":"405.500648ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:42.999057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.593189Z","time spent":"405.809233ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:43.069206Z","caller":"traceutil/trace.go:171","msg":"trace[39571221] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"309.411518ms","start":"2026-04-28T07:33:42.759771Z","end":"2026-04-28T07:33:43.069182Z","steps":["trace[39571221] 'process raft request' (duration: 309.207605ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:43.069243Z","caller":"traceutil/trace.go:171","msg":"trace[1865468754] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"283.725401ms","start":"2026-04-28T07:33:42.785495Z","end":"2026-04-28T07:33:43.069221Z","steps":["trace[1865468754] 'process raft request' (duration: 283.61888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:43.069634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.626751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:33:43.069809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.326756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T07:33:43.069859Z","caller":"traceutil/trace.go:171","msg":"trace[1597872683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4861; }","duration":"311.423598ms","start":"2026-04-28T07:33:42.758421Z","end":"2026-04-28T07:33:43.069845Z","steps":["trace[1597872683] 'agreement among raft nodes before linearized reading' (duration: 311.277556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:43.06989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.7584Z","time spent":"311.482718ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:33:43.069682Z","caller":"traceutil/trace.go:171","msg":"trace[318647959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4861; }","duration":"356.717313ms","start":"2026-04-28T07:33:42.712953Z","end":"2026-04-28T07:33:43.06967Z","steps":["trace[318647959] 'agreement among raft nodes before linearized reading' (duration: 356.649161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:43.070416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.712931Z","time spent":"357.472063ms","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:33:43.070837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:42.759755Z","time spent":"309.518859ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:43.230914Z","caller":"traceutil/trace.go:171","msg":"trace[272098846] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"156.296814ms","start":"2026-04-28T07:33:43.074592Z","end":"2026-04-28T07:33:43.230889Z","steps":["trace[272098846] 'process raft request' (duration: 156.242663ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:43.231275Z","caller":"traceutil/trace.go:171","msg":"trace[1421938495] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"156.65834ms","start":"2026-04-28T07:33:43.074582Z","end":"2026-04-28T07:33:43.231241Z","steps":["trace[1421938495] 'process raft request' (duration: 60.585363ms)","trace[1421938495] 'compare' (duration: 95.478269ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:43.299987Z","caller":"traceutil/trace.go:171","msg":"trace[173974194] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5047; }","duration":"199.086883ms","start":"2026-04-28T07:33:43.100867Z","end":"2026-04-28T07:33:43.299953Z","steps":["trace[173974194] 'read index received' (duration: 34.308258ms)","trace[173974194] 'applied index is now lower than readState.Index' (duration: 164.777165ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:43.300091Z","caller":"traceutil/trace.go:171","msg":"trace[1919945873] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"219.25115ms","start":"2026-04-28T07:33:43.080789Z","end":"2026-04-28T07:33:43.30004Z","steps":["trace[1919945873] 'process raft request' (duration: 218.969346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:43.300197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.300026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T07:33:43.300237Z","caller":"traceutil/trace.go:171","msg":"trace[2068431178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4864; }","duration":"199.388907ms","start":"2026-04-28T07:33:43.100837Z","end":"2026-04-28T07:33:43.300226Z","steps":["trace[2068431178] 'agreement among raft nodes before linearized reading' (duration: 199.215724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:43.538173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.270052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:33:43.538406Z","caller":"traceutil/trace.go:171","msg":"trace[296530582] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5050; }","duration":"133.252707ms","start":"2026-04-28T07:33:43.405133Z","end":"2026-04-28T07:33:43.538385Z","steps":["trace[296530582] 'read index received' (duration: 51.2113ms)","trace[296530582] 'applied index is now lower than readState.Index' (duration: 82.040217ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:43.538479Z","caller":"traceutil/trace.go:171","msg":"trace[293417901] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"138.101195ms","start":"2026-04-28T07:33:43.40035Z","end":"2026-04-28T07:33:43.538451Z","steps":["trace[293417901] 'process raft request' (duration: 137.948473ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:43.538489Z","caller":"traceutil/trace.go:171","msg":"trace[1293806064] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"234.536538ms","start":"2026-04-28T07:33:43.303922Z","end":"2026-04-28T07:33:43.538459Z","steps":["trace[1293806064] 'process raft request' (duration: 42.8844ms)","trace[1293806064] 'compare' (duration: 191.045479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:43.538611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.46703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-28T07:33:43.538665Z","caller":"traceutil/trace.go:171","msg":"trace[1232620540] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4866; }","duration":"133.553941ms","start":"2026-04-28T07:33:43.405098Z","end":"2026-04-28T07:33:43.538652Z","steps":["trace[1232620540] 'agreement among raft nodes before linearized reading' (duration: 133.380008ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:35:13.39485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-04-28T07:35:13.525925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"129.780643ms","hash":466906927,"current-db-size-bytes":53182464,"current-db-size":"53 MB","current-db-size-in-use-bytes":29229056,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T07:35:13.525995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":466906927,"revision":2871,"compact-revision":-1} {"level":"info","ts":"2026-04-28T07:37:49.232484Z","caller":"traceutil/trace.go:171","msg":"trace[1963393994] transaction","detail":"{read_only:false; response_revision:6635; number_of_response:1; }","duration":"111.289471ms","start":"2026-04-28T07:37:49.121176Z","end":"2026-04-28T07:37:49.232465Z","steps":["trace[1963393994] 'process raft request' (duration: 25.441564ms)","trace[1963393994] 'compare' (duration: 85.471882ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:40:13.400729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5551} {"level":"info","ts":"2026-04-28T07:40:13.490277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5551,"took":"88.420618ms","hash":1411979540,"current-db-size-bytes":53182464,"current-db-size":"53 MB","current-db-size-in-use-bytes":18268160,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T07:40:13.490328Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1411979540,"revision":5551,"compact-revision":2871}