{"level":"warn","ts":"2026-07-30T23:46:08.240459Z","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-30T23:46:08.240544Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-30T23:46:08.240605Z","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-30T23:46:08.24062Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-30T23:46:08.240636Z","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-30T23:46:08.241232Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-07-30T23:46:08.241303Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-30T23:46:08.244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.483036ms"} {"level":"info","ts":"2026-07-30T23:46:08.249906Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-07-30T23:46:08.249986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:46:08.250006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-07-30T23:46:08.250018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:46:08.250028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-07-30T23:46:08.25006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-07-30T23:46:08.254683Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:46:08.256438Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:46:08.257921Z","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-30T23:46:08.261428Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:46:08.261612Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:46:08.26176Z","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-30T23:46:08.261834Z","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-30T23:46:08.26186Z","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-30T23:46:08.264045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-07-30T23:46:08.264213Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-30T23:46:08.265432Z","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-30T23:46:08.265627Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:46:08.265677Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:46:08.265679Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-30T23:46:08.265802Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-30T23:46:08.850987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:46:08.851061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:46:08.851116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-07-30T23:46:08.851142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:46:08.851154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-30T23:46:08.8512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-07-30T23:46:08.851221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-30T23:46:08.852302Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:46:08.852678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:46:08.852655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:46:08.85266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:46:08.853075Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:46:08.853127Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:46:08.853329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:46:08.853416Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:46:08.853442Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:46:08.856025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T23:46:08.85611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-07-30T23:46:26.338405Z","caller":"traceutil/trace.go:171","msg":"trace[404514832] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"121.980955ms","start":"2026-07-30T23:46:26.216397Z","end":"2026-07-30T23:46:26.338378Z","steps":["trace[404514832] 'read index received' (duration: 121.765251ms)","trace[404514832] 'applied index is now lower than readState.Index' (duration: 214.764µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:46:26.338459Z","caller":"traceutil/trace.go:171","msg":"trace[1473397511] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"243.229448ms","start":"2026-07-30T23:46:26.095195Z","end":"2026-07-30T23:46:26.338424Z","steps":["trace[1473397511] 'process raft request' (duration: 243.007164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:26.338658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.229949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:46:26.339305Z","caller":"traceutil/trace.go:171","msg":"trace[868435941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"122.935312ms","start":"2026-07-30T23:46:26.216347Z","end":"2026-07-30T23:46:26.339282Z","steps":["trace[868435941] 'agreement among raft nodes before linearized reading' (duration: 122.117127ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.061432Z","caller":"traceutil/trace.go:171","msg":"trace[2114045978] transaction","detail":"{read_only:false; number_of_response:0; response_revision:545; }","duration":"138.428192ms","start":"2026-07-30T23:46:45.922976Z","end":"2026-07-30T23:46:46.061404Z","steps":["trace[2114045978] 'process raft request' (duration: 138.393412ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.061751Z","caller":"traceutil/trace.go:171","msg":"trace[361004347] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"155.190176ms","start":"2026-07-30T23:46:45.906547Z","end":"2026-07-30T23:46:46.061737Z","steps":["trace[361004347] 'process raft request' (duration: 145.27616ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.061862Z","caller":"traceutil/trace.go:171","msg":"trace[2128534297] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"147.808407ms","start":"2026-07-30T23:46:45.914035Z","end":"2026-07-30T23:46:46.061843Z","steps":["trace[2128534297] 'process raft request' (duration: 147.297278ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.061982Z","caller":"traceutil/trace.go:171","msg":"trace[1951643798] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"148.019081ms","start":"2026-07-30T23:46:45.913952Z","end":"2026-07-30T23:46:46.061971Z","steps":["trace[1951643798] 'process raft request' (duration: 147.305278ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.064626Z","caller":"traceutil/trace.go:171","msg":"trace[1465951960] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"121.131009ms","start":"2026-07-30T23:46:45.943462Z","end":"2026-07-30T23:46:46.064593Z","steps":["trace[1465951960] 'process raft request' (duration: 121.024507ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.064761Z","caller":"traceutil/trace.go:171","msg":"trace[1432016186] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"131.827669ms","start":"2026-07-30T23:46:45.932917Z","end":"2026-07-30T23:46:46.064745Z","steps":["trace[1432016186] 'process raft request' (duration: 131.206427ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.064838Z","caller":"traceutil/trace.go:171","msg":"trace[680572185] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"124.437682ms","start":"2026-07-30T23:46:45.94038Z","end":"2026-07-30T23:46:46.064817Z","steps":["trace[680572185] 'process raft request' (duration: 124.011054ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.064855Z","caller":"traceutil/trace.go:171","msg":"trace[1080328697] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"126.203294ms","start":"2026-07-30T23:46:45.938632Z","end":"2026-07-30T23:46:46.064835Z","steps":["trace[1080328697] 'process raft request' (duration: 125.688605ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.064964Z","caller":"traceutil/trace.go:171","msg":"trace[813875669] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"123.603425ms","start":"2026-07-30T23:46:45.941349Z","end":"2026-07-30T23:46:46.064952Z","steps":["trace[813875669] 'process raft request' (duration: 123.085756ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:46.064964Z","caller":"traceutil/trace.go:171","msg":"trace[74279245] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"131.816329ms","start":"2026-07-30T23:46:45.93309Z","end":"2026-07-30T23:46:46.064906Z","steps":["trace[74279245] 'process raft request' (duration: 131.174717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:51.938042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.474085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T23:46:51.938078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.567853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gvrpc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T23:46:51.938179Z","caller":"traceutil/trace.go:171","msg":"trace[1140484459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"136.668359ms","start":"2026-07-30T23:46:51.801487Z","end":"2026-07-30T23:46:51.938156Z","steps":["trace[1140484459] 'range keys from in-memory index tree' (duration: 136.211821ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:46:51.938273Z","caller":"traceutil/trace.go:171","msg":"trace[1498924250] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gvrpc; range_end:; response_count:1; response_revision:634; }","duration":"124.763797ms","start":"2026-07-30T23:46:51.813414Z","end":"2026-07-30T23:46:51.938178Z","steps":["trace[1498924250] 'range keys from in-memory index tree' (duration: 124.39316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:52.575686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882642001526640,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:46:52.789443Z","caller":"traceutil/trace.go:171","msg":"trace[178269852] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"714.035738ms","start":"2026-07-30T23:46:52.075375Z","end":"2026-07-30T23:46:52.78941Z","steps":["trace[178269852] 'read index received' (duration: 712.879937ms)","trace[178269852] 'applied index is now lower than readState.Index' (duration: 1.154651ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:46:52.789525Z","caller":"traceutil/trace.go:171","msg":"trace[1571216442] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"729.335473ms","start":"2026-07-30T23:46:52.060144Z","end":"2026-07-30T23:46:52.78948Z","steps":["trace[1571216442] 'process raft request' (duration: 728.534129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:52.789589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.194302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T23:46:52.789624Z","caller":"traceutil/trace.go:171","msg":"trace[642571730] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:637; }","duration":"714.283443ms","start":"2026-07-30T23:46:52.07533Z","end":"2026-07-30T23:46:52.789613Z","steps":["trace[642571730] 'agreement among raft nodes before linearized reading' (duration: 714.190551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:46:52.789658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:52.075307Z","time spent":"714.342944ms","remote":"127.0.0.1:52820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-07-30T23:46:52.790395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:46:52.060093Z","time spent":"729.515017ms","remote":"127.0.0.1:52890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:47:08.065156Z","caller":"traceutil/trace.go:171","msg":"trace[222590570] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"141.820874ms","start":"2026-07-30T23:47:07.923304Z","end":"2026-07-30T23:47:08.065124Z","steps":["trace[222590570] 'process raft request' (duration: 141.61966ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:47:08.242218Z","caller":"traceutil/trace.go:171","msg":"trace[302067616] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"169.293065ms","start":"2026-07-30T23:47:08.072794Z","end":"2026-07-30T23:47:08.242087Z","steps":["trace[302067616] 'process raft request' (duration: 115.247573ms)","trace[302067616] 'compare' (duration: 53.838998ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:48:19.823519Z","caller":"traceutil/trace.go:171","msg":"trace[56008752] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"152.487472ms","start":"2026-07-30T23:48:19.670992Z","end":"2026-07-30T23:48:19.82348Z","steps":["trace[56008752] 'process raft request' (duration: 152.166056ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:19.948789Z","caller":"traceutil/trace.go:171","msg":"trace[1325517811] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"118.315321ms","start":"2026-07-30T23:48:19.830436Z","end":"2026-07-30T23:48:19.948751Z","steps":["trace[1325517811] 'process raft request' (duration: 84.855037ms)","trace[1325517811] 'compare' (duration: 33.191369ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:48:20.989853Z","caller":"traceutil/trace.go:171","msg":"trace[1364735335] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"225.564467ms","start":"2026-07-30T23:48:20.764244Z","end":"2026-07-30T23:48:20.989809Z","steps":["trace[1364735335] 'process raft request' (duration: 211.257074ms)","trace[1364735335] 'compare' (duration: 13.390184ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:11.239474Z","caller":"traceutil/trace.go:171","msg":"trace[1518469705] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"143.862435ms","start":"2026-07-30T23:49:11.09558Z","end":"2026-07-30T23:49:11.239442Z","steps":["trace[1518469705] 'read index received' (duration: 143.733492ms)","trace[1518469705] 'applied index is now lower than readState.Index' (duration: 127.592µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:11.239693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.09685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:49:11.239743Z","caller":"traceutil/trace.go:171","msg":"trace[121200646] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2184; }","duration":"144.220702ms","start":"2026-07-30T23:49:11.095506Z","end":"2026-07-30T23:49:11.239727Z","steps":["trace[121200646] 'agreement among raft nodes before linearized reading' (duration: 144.11019ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:11.239806Z","caller":"traceutil/trace.go:171","msg":"trace[1833058037] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"256.845368ms","start":"2026-07-30T23:49:10.982852Z","end":"2026-07-30T23:49:11.239698Z","steps":["trace[1833058037] 'process raft request' (duration: 256.416349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:11.2399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.028504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T23:49:11.240017Z","caller":"traceutil/trace.go:171","msg":"trace[176507292] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2184; }","duration":"135.218208ms","start":"2026-07-30T23:49:11.104771Z","end":"2026-07-30T23:49:11.239989Z","steps":["trace[176507292] 'agreement among raft nodes before linearized reading' (duration: 134.901131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:11.240166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.821205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-30T23:49:11.240306Z","caller":"traceutil/trace.go:171","msg":"trace[684246008] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2184; }","duration":"126.042839ms","start":"2026-07-30T23:49:11.114227Z","end":"2026-07-30T23:49:11.240269Z","steps":["trace[684246008] 'agreement among raft nodes before linearized reading' (duration: 125.59723ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:11.645676Z","caller":"traceutil/trace.go:171","msg":"trace[2125415168] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"182.172348ms","start":"2026-07-30T23:49:11.463465Z","end":"2026-07-30T23:49:11.645637Z","steps":["trace[2125415168] 'process raft request' (duration: 181.926293ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:11.647659Z","caller":"traceutil/trace.go:171","msg":"trace[1299401918] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"145.326475ms","start":"2026-07-30T23:49:11.502307Z","end":"2026-07-30T23:49:11.647634Z","steps":["trace[1299401918] 'read index received' (duration: 143.10826ms)","trace[1299401918] 'applied index is now lower than readState.Index' (duration: 2.217025ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:11.647702Z","caller":"traceutil/trace.go:171","msg":"trace[490587458] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"146.948227ms","start":"2026-07-30T23:49:11.500727Z","end":"2026-07-30T23:49:11.647675Z","steps":["trace[490587458] 'process raft request' (duration: 146.727533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:11.647971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.653571ms","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-07-30T23:49:11.648017Z","caller":"traceutil/trace.go:171","msg":"trace[1985490373] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"145.771743ms","start":"2026-07-30T23:49:11.502231Z","end":"2026-07-30T23:49:11.648003Z","steps":["trace[1985490373] 'agreement among raft nodes before linearized reading' (duration: 145.540418ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:23.975857Z","caller":"traceutil/trace.go:171","msg":"trace[473768698] linearizableReadLoop","detail":"{readStateIndex:2376; appliedIndex:2375; }","duration":"255.518979ms","start":"2026-07-30T23:49:23.72031Z","end":"2026-07-30T23:49:23.975828Z","steps":["trace[473768698] 'read index received' (duration: 255.333455ms)","trace[473768698] 'applied index is now lower than readState.Index' (duration: 184.014µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:23.975909Z","caller":"traceutil/trace.go:171","msg":"trace[1062983333] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"282.958811ms","start":"2026-07-30T23:49:23.692917Z","end":"2026-07-30T23:49:23.975876Z","steps":["trace[1062983333] 'process raft request' (duration: 282.698506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:23.976157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.827345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T23:49:23.976221Z","caller":"traceutil/trace.go:171","msg":"trace[661805351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2295; }","duration":"255.954998ms","start":"2026-07-30T23:49:23.720251Z","end":"2026-07-30T23:49:23.976206Z","steps":["trace[661805351] 'agreement among raft nodes before linearized reading' (duration: 255.727393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:23.998406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.456747ms","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-07-30T23:49:23.998476Z","caller":"traceutil/trace.go:171","msg":"trace[1895137720] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2296; }","duration":"251.548238ms","start":"2026-07-30T23:49:23.746909Z","end":"2026-07-30T23:49:23.998457Z","steps":["trace[1895137720] 'agreement among raft nodes before linearized reading' (duration: 251.365085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:23.998494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.774615ms","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-30T23:49:23.998596Z","caller":"traceutil/trace.go:171","msg":"trace[1539477307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2296; }","duration":"149.873507ms","start":"2026-07-30T23:49:23.848665Z","end":"2026-07-30T23:49:23.998539Z","steps":["trace[1539477307] 'agreement among raft nodes before linearized reading' (duration: 149.691053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:23.998666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.944282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-s8z5n\" ","response":"range_response_count:1 size:6147"} {"level":"info","ts":"2026-07-30T23:49:23.998789Z","caller":"traceutil/trace.go:171","msg":"trace[802352155] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-s8z5n; range_end:; response_count:1; response_revision:2296; }","duration":"136.130266ms","start":"2026-07-30T23:49:23.862629Z","end":"2026-07-30T23:49:23.998759Z","steps":["trace[802352155] 'agreement among raft nodes before linearized reading' (duration: 135.670696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:24.448992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.878219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:49:24.449189Z","caller":"traceutil/trace.go:171","msg":"trace[1563634176] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"295.236952ms","start":"2026-07-30T23:49:24.153926Z","end":"2026-07-30T23:49:24.449163Z","steps":["trace[1563634176] 'process raft request' (duration: 98.784012ms)","trace[1563634176] 'compare' (duration: 195.736146ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:24.449346Z","caller":"traceutil/trace.go:171","msg":"trace[1410617323] linearizableReadLoop","detail":"{readStateIndex:2385; appliedIndex:2384; }","duration":"285.292799ms","start":"2026-07-30T23:49:24.164015Z","end":"2026-07-30T23:49:24.449308Z","steps":["trace[1410617323] 'read index received' (duration: 88.768257ms)","trace[1410617323] 'applied index is now lower than readState.Index' (duration: 196.518662ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:24.449466Z","caller":"traceutil/trace.go:171","msg":"trace[1000685388] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"285.409341ms","start":"2026-07-30T23:49:24.164042Z","end":"2026-07-30T23:49:24.449451Z","steps":["trace[1000685388] 'process raft request' (duration: 285.078914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:24.449587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.552994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-30T23:49:24.449719Z","caller":"traceutil/trace.go:171","msg":"trace[1395002868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2304; }","duration":"285.714917ms","start":"2026-07-30T23:49:24.163948Z","end":"2026-07-30T23:49:24.449663Z","steps":["trace[1395002868] 'agreement among raft nodes before linearized reading' (duration: 285.404991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:24.50079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.007763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:24.500886Z","caller":"traceutil/trace.go:171","msg":"trace[1047908193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2306; }","duration":"284.174506ms","start":"2026-07-30T23:49:24.216693Z","end":"2026-07-30T23:49:24.500867Z","steps":["trace[1047908193] 'agreement among raft nodes before linearized reading' (duration: 283.981201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:24.500975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.541616ms","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-30T23:49:24.501058Z","caller":"traceutil/trace.go:171","msg":"trace[819358998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2306; }","duration":"204.676918ms","start":"2026-07-30T23:49:24.296361Z","end":"2026-07-30T23:49:24.501038Z","steps":["trace[819358998] 'agreement among raft nodes before linearized reading' (duration: 204.415303ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:24.672859Z","caller":"traceutil/trace.go:171","msg":"trace[1877123246] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"218.307728ms","start":"2026-07-30T23:49:24.454506Z","end":"2026-07-30T23:49:24.672814Z","steps":["trace[1877123246] 'process raft request' (duration: 137.629536ms)","trace[1877123246] 'compare' (duration: 80.405556ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:24.673002Z","caller":"traceutil/trace.go:171","msg":"trace[2056045641] transaction","detail":"{read_only:false; response_revision:2308; number_of_response:1; }","duration":"166.208891ms","start":"2026-07-30T23:49:24.506761Z","end":"2026-07-30T23:49:24.67297Z","steps":["trace[2056045641] 'process raft request' (duration: 165.962486ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:24.673116Z","caller":"traceutil/trace.go:171","msg":"trace[880323113] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"142.68783ms","start":"2026-07-30T23:49:24.530345Z","end":"2026-07-30T23:49:24.673033Z","steps":["trace[880323113] 'process raft request' (duration: 142.421805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:24.673302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.221514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:24.67307Z","caller":"traceutil/trace.go:171","msg":"trace[1958994593] linearizableReadLoop","detail":"{readStateIndex:2388; appliedIndex:2387; }","duration":"170.971529ms","start":"2026-07-30T23:49:24.50207Z","end":"2026-07-30T23:49:24.673041Z","steps":["trace[1958994593] 'read index received' (duration: 90.167295ms)","trace[1958994593] 'applied index is now lower than readState.Index' (duration: 80.801354ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:24.67336Z","caller":"traceutil/trace.go:171","msg":"trace[1969254508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2310; }","duration":"171.328466ms","start":"2026-07-30T23:49:24.502021Z","end":"2026-07-30T23:49:24.673349Z","steps":["trace[1969254508] 'agreement among raft nodes before linearized reading' (duration: 171.224824ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:28.079329Z","caller":"traceutil/trace.go:171","msg":"trace[1608237011] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"117.100226ms","start":"2026-07-30T23:49:27.962203Z","end":"2026-07-30T23:49:28.079304Z","steps":["trace[1608237011] 'process raft request' (duration: 114.183617ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:51.78709Z","caller":"traceutil/trace.go:171","msg":"trace[1009592254] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"293.497187ms","start":"2026-07-30T23:49:51.493541Z","end":"2026-07-30T23:49:51.787039Z","steps":["trace[1009592254] 'process raft request' (duration: 293.211971ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:11.175982Z","caller":"traceutil/trace.go:171","msg":"trace[1174185983] transaction","detail":"{read_only:false; response_revision:3186; number_of_response:1; }","duration":"107.558229ms","start":"2026-07-30T23:51:11.068389Z","end":"2026-07-30T23:51:11.175948Z","steps":["trace[1174185983] 'process raft request' (duration: 107.304644ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:39.849112Z","caller":"traceutil/trace.go:171","msg":"trace[305715062] linearizableReadLoop","detail":"{readStateIndex:3516; appliedIndex:3515; }","duration":"229.337977ms","start":"2026-07-30T23:51:39.619744Z","end":"2026-07-30T23:51:39.849082Z","steps":["trace[305715062] 'read index received' (duration: 229.200715ms)","trace[305715062] 'applied index is now lower than readState.Index' (duration: 136.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:39.849676Z","caller":"traceutil/trace.go:171","msg":"trace[1906114049] transaction","detail":"{read_only:false; response_revision:3399; number_of_response:1; }","duration":"279.282942ms","start":"2026-07-30T23:51:39.57021Z","end":"2026-07-30T23:51:39.849492Z","steps":["trace[1906114049] 'process raft request' (duration: 278.69684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:39.849813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.036652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18c7364edc9ac00f\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-07-30T23:51:39.849869Z","caller":"traceutil/trace.go:171","msg":"trace[375534877] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18c7364edc9ac00f; range_end:; response_count:1; response_revision:3399; }","duration":"230.138443ms","start":"2026-07-30T23:51:39.619718Z","end":"2026-07-30T23:51:39.849856Z","steps":["trace[375534877] 'agreement among raft nodes before linearized reading' (duration: 229.833337ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:39.855925Z","caller":"traceutil/trace.go:171","msg":"trace[1048790720] transaction","detail":"{read_only:false; response_revision:3400; number_of_response:1; }","duration":"216.768896ms","start":"2026-07-30T23:51:39.639138Z","end":"2026-07-30T23:51:39.855907Z","steps":["trace[1048790720] 'process raft request' (duration: 214.52499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:45.440673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.508705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:45.441244Z","caller":"traceutil/trace.go:171","msg":"trace[346478154] transaction","detail":"{read_only:false; response_revision:3439; number_of_response:1; }","duration":"120.670068ms","start":"2026-07-30T23:51:45.320534Z","end":"2026-07-30T23:51:45.441204Z","steps":["trace[346478154] 'process raft request' (duration: 13.49787ms)","trace[346478154] 'compare' (duration: 106.110635ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:45.633435Z","caller":"traceutil/trace.go:171","msg":"trace[594118744] transaction","detail":"{read_only:false; response_revision:3440; number_of_response:1; }","duration":"185.97649ms","start":"2026-07-30T23:51:45.447424Z","end":"2026-07-30T23:51:45.633401Z","steps":["trace[594118744] 'process raft request' (duration: 118.961373ms)","trace[594118744] 'compare' (duration: 66.877344ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:45.744873Z","caller":"traceutil/trace.go:171","msg":"trace[1473415164] linearizableReadLoop","detail":"{readStateIndex:3559; appliedIndex:3557; }","duration":"295.296843ms","start":"2026-07-30T23:51:45.449549Z","end":"2026-07-30T23:51:45.744846Z","steps":["trace[1473415164] 'read index received' (duration: 116.928031ms)","trace[1473415164] 'applied index is now lower than readState.Index' (duration: 178.367682ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:51:45.744959Z","caller":"traceutil/trace.go:171","msg":"trace[1400011797] transaction","detail":"{read_only:false; response_revision:3441; number_of_response:1; }","duration":"297.439627ms","start":"2026-07-30T23:51:45.447473Z","end":"2026-07-30T23:51:45.744913Z","steps":["trace[1400011797] 'process raft request' (duration: 293.044117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:45.745266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.6649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T23:51:45.745394Z","caller":"traceutil/trace.go:171","msg":"trace[1355840810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3441; }","duration":"295.845063ms","start":"2026-07-30T23:51:45.449523Z","end":"2026-07-30T23:51:45.745368Z","steps":["trace[1355840810] 'agreement among raft nodes before linearized reading' (duration: 295.419455ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:56:08.968294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3134} {"level":"info","ts":"2026-07-30T23:56:09.061569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3134,"took":"91.866723ms","hash":2711371190,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":9875456,"current-db-size-in-use":"9.9 MB"} {"level":"info","ts":"2026-07-30T23:56:09.061675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2711371190,"revision":3134,"compact-revision":-1} {"level":"info","ts":"2026-07-31T00:01:08.976637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5144} {"level":"info","ts":"2026-07-31T00:01:09.029031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5144,"took":"51.265491ms","hash":146784576,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":11186176,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-31T00:01:09.029137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":146784576,"revision":5144,"compact-revision":3134} {"level":"info","ts":"2026-07-31T00:05:54.893973Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T00:05:54.899819Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-31T00:05:54.899912Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-31T00:06:08.983333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7393} {"level":"info","ts":"2026-07-31T00:06:09.038543Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7393,"took":"53.886287ms","hash":2355166726,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":13877248,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-31T00:06:09.038644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2355166726,"revision":7393,"compact-revision":5144} {"level":"warn","ts":"2026-07-31T00:07:38.715144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.907341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-31T00:07:38.715302Z","caller":"traceutil/trace.go:171","msg":"trace[811117669] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:10635; }","duration":"120.115695ms","start":"2026-07-31T00:07:38.595162Z","end":"2026-07-31T00:07:38.715278Z","steps":["trace[811117669] 'range keys from in-memory index tree' (duration: 119.713247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:07:38.715166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.703677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6c5496bcf5-nkw5p.18c7371dd7fc43f2\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-31T00:07:38.715571Z","caller":"traceutil/trace.go:171","msg":"trace[336681952] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6c5496bcf5-nkw5p.18c7371dd7fc43f2; range_end:; response_count:1; response_revision:10635; }","duration":"121.098724ms","start":"2026-07-31T00:07:38.594403Z","end":"2026-07-31T00:07:38.715502Z","steps":["trace[336681952] 'range keys from in-memory index tree' (duration: 120.264128ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:11:08.989867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9827} {"level":"info","ts":"2026-07-31T00:11:09.047481Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9827,"took":"56.200092ms","hash":1508310330,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":14827520,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-31T00:11:09.047535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1508310330,"revision":9827,"compact-revision":7393} {"level":"info","ts":"2026-07-31T00:12:31.382229Z","caller":"traceutil/trace.go:171","msg":"trace[628066330] transaction","detail":"{read_only:false; response_revision:13045; number_of_response:1; }","duration":"103.358322ms","start":"2026-07-31T00:12:31.278834Z","end":"2026-07-31T00:12:31.382192Z","steps":["trace[628066330] 'process raft request' (duration: 103.147828ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:12:56.187591Z","caller":"traceutil/trace.go:171","msg":"trace[1528310602] transaction","detail":"{read_only:false; response_revision:13212; number_of_response:1; }","duration":"103.991436ms","start":"2026-07-31T00:12:56.083572Z","end":"2026-07-31T00:12:56.187563Z","steps":["trace[1528310602] 'process raft request' (duration: 103.809832ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:16:08.995403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12202} {"level":"info","ts":"2026-07-31T00:16:09.05462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12202,"took":"57.235146ms","hash":5794098,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":16322560,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-31T00:16:09.054699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":5794098,"revision":12202,"compact-revision":9827} {"level":"info","ts":"2026-07-31T00:19:06.310434Z","caller":"traceutil/trace.go:171","msg":"trace[1584707303] linearizableReadLoop","detail":"{readStateIndex:16679; appliedIndex:16678; }","duration":"216.513905ms","start":"2026-07-31T00:19:06.093889Z","end":"2026-07-31T00:19:06.310403Z","steps":["trace[1584707303] 'read index received' (duration: 216.188088ms)","trace[1584707303] 'applied index is now lower than readState.Index' (duration: 324.697µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:19:06.31063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.71738ms","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-31T00:19:06.310683Z","caller":"traceutil/trace.go:171","msg":"trace[469825291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16184; }","duration":"216.821361ms","start":"2026-07-31T00:19:06.093845Z","end":"2026-07-31T00:19:06.310666Z","steps":["trace[469825291] 'agreement among raft nodes before linearized reading' (duration: 216.677428ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:19:06.310812Z","caller":"traceutil/trace.go:171","msg":"trace[554148867] transaction","detail":"{read_only:false; response_revision:16184; number_of_response:1; }","duration":"329.760248ms","start":"2026-07-31T00:19:05.981009Z","end":"2026-07-31T00:19:06.31077Z","steps":["trace[554148867] 'process raft request' (duration: 329.146956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:19:06.311049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:19:05.980987Z","time spent":"329.961283ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:19:06.448048Z","caller":"traceutil/trace.go:171","msg":"trace[1493243365] transaction","detail":"{read_only:false; response_revision:16186; number_of_response:1; }","duration":"105.757152ms","start":"2026-07-31T00:19:06.342247Z","end":"2026-07-31T00:19:06.448005Z","steps":["trace[1493243365] 'process raft request' (duration: 36.987613ms)","trace[1493243365] 'compare' (duration: 68.338ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:19:07.565571Z","caller":"traceutil/trace.go:171","msg":"trace[1598132692] linearizableReadLoop","detail":"{readStateIndex:16698; appliedIndex:16697; }","duration":"150.703866ms","start":"2026-07-31T00:19:07.414843Z","end":"2026-07-31T00:19:07.565547Z","steps":["trace[1598132692] 'read index received' (duration: 150.498361ms)","trace[1598132692] 'applied index is now lower than readState.Index' (duration: 204.805µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:19:07.565628Z","caller":"traceutil/trace.go:171","msg":"trace[60691510] transaction","detail":"{read_only:false; response_revision:16203; number_of_response:1; }","duration":"152.307139ms","start":"2026-07-31T00:19:07.413303Z","end":"2026-07-31T00:19:07.56561Z","steps":["trace[60691510] 'process raft request' (duration: 152.093435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:19:07.565791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.91013ms","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-07-31T00:19:07.565827Z","caller":"traceutil/trace.go:171","msg":"trace[1976606847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16203; }","duration":"151.032663ms","start":"2026-07-31T00:19:07.414782Z","end":"2026-07-31T00:19:07.565815Z","steps":["trace[1976606847] 'agreement among raft nodes before linearized reading' (duration: 150.87409ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:20:19.274325Z","caller":"traceutil/trace.go:171","msg":"trace[1344132132] transaction","detail":"{read_only:false; response_revision:17206; number_of_response:1; }","duration":"159.810561ms","start":"2026-07-31T00:20:19.114468Z","end":"2026-07-31T00:20:19.274278Z","steps":["trace[1344132132] 'process raft request' (duration: 146.476009ms)","trace[1344132132] 'compare' (duration: 13.086636ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:20:43.016659Z","caller":"traceutil/trace.go:171","msg":"trace[1780503854] linearizableReadLoop","detail":"{readStateIndex:17889; appliedIndex:17888; }","duration":"212.396561ms","start":"2026-07-31T00:20:42.804239Z","end":"2026-07-31T00:20:43.016635Z","steps":["trace[1780503854] 'read index received' (duration: 212.209157ms)","trace[1780503854] 'applied index is now lower than readState.Index' (duration: 186.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:20:43.01678Z","caller":"traceutil/trace.go:171","msg":"trace[1892092551] transaction","detail":"{read_only:false; response_revision:17360; number_of_response:1; }","duration":"286.768243ms","start":"2026-07-31T00:20:42.729948Z","end":"2026-07-31T00:20:43.016716Z","steps":["trace[1892092551] 'process raft request' (duration: 286.568849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:20:43.016845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.598635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-78j2l\" ","response":"range_response_count:1 size:20566"} {"level":"warn","ts":"2026-07-31T00:20:43.016988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.311669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:20:43.017087Z","caller":"traceutil/trace.go:171","msg":"trace[2077467543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17360; }","duration":"125.453343ms","start":"2026-07-31T00:20:42.891603Z","end":"2026-07-31T00:20:43.017056Z","steps":["trace[2077467543] 'agreement among raft nodes before linearized reading' (duration: 125.284649ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:20:43.017078Z","caller":"traceutil/trace.go:171","msg":"trace[1685040177] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-78j2l; range_end:; response_count:1; response_revision:17360; }","duration":"212.85477ms","start":"2026-07-31T00:20:42.804184Z","end":"2026-07-31T00:20:43.017039Z","steps":["trace[1685040177] 'agreement among raft nodes before linearized reading' (duration: 212.556684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:20:43.017478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.080542ms","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-31T00:20:43.017557Z","caller":"traceutil/trace.go:171","msg":"trace[1437509650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17360; }","duration":"176.695804ms","start":"2026-07-31T00:20:42.840846Z","end":"2026-07-31T00:20:43.017542Z","steps":["trace[1437509650] 'agreement among raft nodes before linearized reading' (duration: 175.950609ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:20:44.190258Z","caller":"traceutil/trace.go:171","msg":"trace[1099368074] linearizableReadLoop","detail":"{readStateIndex:17897; appliedIndex:17896; }","duration":"132.743451ms","start":"2026-07-31T00:20:44.057494Z","end":"2026-07-31T00:20:44.190237Z","steps":["trace[1099368074] 'read index received' (duration: 132.597688ms)","trace[1099368074] 'applied index is now lower than readState.Index' (duration: 144.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:20:44.190324Z","caller":"traceutil/trace.go:171","msg":"trace[1791081694] transaction","detail":"{read_only:false; response_revision:17368; number_of_response:1; }","duration":"133.022507ms","start":"2026-07-31T00:20:44.057273Z","end":"2026-07-31T00:20:44.190295Z","steps":["trace[1791081694] 'process raft request' (duration: 132.825633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:20:44.190604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.038507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:20:44.190654Z","caller":"traceutil/trace.go:171","msg":"trace[441836826] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17368; }","duration":"133.20703ms","start":"2026-07-31T00:20:44.057437Z","end":"2026-07-31T00:20:44.190644Z","steps":["trace[441836826] 'agreement among raft nodes before linearized reading' (duration: 132.952795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:20:44.202222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.478069ms","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-31T00:20:44.202309Z","caller":"traceutil/trace.go:171","msg":"trace[1747277337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17369; }","duration":"144.582111ms","start":"2026-07-31T00:20:44.057706Z","end":"2026-07-31T00:20:44.202288Z","steps":["trace[1747277337] 'agreement among raft nodes before linearized reading' (duration: 144.381057ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:21:09.002772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14686} {"level":"info","ts":"2026-07-31T00:21:09.082324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14686,"took":"77.372044ms","hash":3975422859,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":18202624,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-31T00:21:09.082407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3975422859,"revision":14686,"compact-revision":12202} {"level":"info","ts":"2026-07-31T00:25:43.00781Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T00:25:43.013293Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-31T00:25:43.013367Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-31T00:26:09.011783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17525} {"level":"info","ts":"2026-07-31T00:26:09.093626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17525,"took":"79.407562ms","hash":1454703048,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":18485248,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-31T00:26:09.093693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1454703048,"revision":17525,"compact-revision":14686} {"level":"info","ts":"2026-07-31T00:31:09.018602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19739} {"level":"info","ts":"2026-07-31T00:31:09.092971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19739,"took":"70.610212ms","hash":150704411,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":18014208,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-31T00:31:09.093039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":150704411,"revision":19739,"compact-revision":17525} {"level":"info","ts":"2026-07-31T00:31:48.316876Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000005941.wal"} {"level":"info","ts":"2026-07-31T00:33:59.691197Z","caller":"traceutil/trace.go:171","msg":"trace[316082852] linearizableReadLoop","detail":"{readStateIndex:23946; appliedIndex:23945; }","duration":"325.801646ms","start":"2026-07-31T00:33:59.365369Z","end":"2026-07-31T00:33:59.69117Z","steps":["trace[316082852] 'read index received' (duration: 325.328107ms)","trace[316082852] 'applied index is now lower than readState.Index' (duration: 472.609µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:33:59.691337Z","caller":"traceutil/trace.go:171","msg":"trace[1482329980] transaction","detail":"{read_only:false; response_revision:23238; number_of_response:1; }","duration":"488.645747ms","start":"2026-07-31T00:33:59.202578Z","end":"2026-07-31T00:33:59.691224Z","steps":["trace[1482329980] 'process raft request' (duration: 488.139477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:33:59.691761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.370267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-44jtk\" ","response":"range_response_count:1 size:9715"} {"level":"info","ts":"2026-07-31T00:33:59.691811Z","caller":"traceutil/trace.go:171","msg":"trace[154656216] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-44jtk; range_end:; response_count:1; response_revision:23238; }","duration":"326.48112ms","start":"2026-07-31T00:33:59.365318Z","end":"2026-07-31T00:33:59.691799Z","steps":["trace[154656216] 'agreement among raft nodes before linearized reading' (duration: 325.98949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:33:59.691936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:33:59.202512Z","time spent":"488.968413ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:33:59.692171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:33:59.365298Z","time spent":"326.855207ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9736,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-44jtk\" "} {"level":"warn","ts":"2026-07-31T00:34:00.169284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.64208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:34:00.169449Z","caller":"traceutil/trace.go:171","msg":"trace[1577420421] linearizableReadLoop","detail":"{readStateIndex:23947; appliedIndex:23946; }","duration":"472.06615ms","start":"2026-07-31T00:33:59.697367Z","end":"2026-07-31T00:34:00.169433Z","steps":["trace[1577420421] 'read index received' (duration: 224.129154ms)","trace[1577420421] 'applied index is now lower than readState.Index' (duration: 247.935756ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:34:00.169595Z","caller":"traceutil/trace.go:171","msg":"trace[700547242] transaction","detail":"{read_only:false; response_revision:23239; number_of_response:1; }","duration":"472.442958ms","start":"2026-07-31T00:33:59.696977Z","end":"2026-07-31T00:34:00.16942Z","steps":["trace[700547242] 'process raft request' (duration: 224.583893ms)","trace[700547242] 'compare' (duration: 247.023318ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:34:00.169761Z","caller":"traceutil/trace.go:171","msg":"trace[1630642219] transaction","detail":"{read_only:false; response_revision:23240; number_of_response:1; }","duration":"244.319064ms","start":"2026-07-31T00:33:59.925414Z","end":"2026-07-31T00:34:00.169733Z","steps":["trace[1630642219] 'process raft request' (duration: 243.966456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:34:00.169948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:33:59.696956Z","time spent":"472.721914ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:34:00.170493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.118282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:87 size:879049"} {"level":"info","ts":"2026-07-31T00:34:00.17059Z","caller":"traceutil/trace.go:171","msg":"trace[1802425267] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:87; response_revision:23240; }","duration":"473.192664ms","start":"2026-07-31T00:33:59.697338Z","end":"2026-07-31T00:34:00.170531Z","steps":["trace[1802425267] 'agreement among raft nodes before linearized reading' (duration: 472.166872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:34:00.170653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:33:59.697327Z","time spent":"473.308436ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":87,"response size":879070,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-31T00:34:00.224022Z","caller":"traceutil/trace.go:171","msg":"trace[499361008] transaction","detail":"{read_only:false; response_revision:23241; number_of_response:1; }","duration":"282.576889ms","start":"2026-07-31T00:33:59.941406Z","end":"2026-07-31T00:34:00.223983Z","steps":["trace[499361008] 'process raft request' (duration: 281.922156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:34:00.224187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.008349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T00:34:00.224239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.960473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:34:00.224305Z","caller":"traceutil/trace.go:171","msg":"trace[1839900378] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23241; }","duration":"122.054195ms","start":"2026-07-31T00:34:00.102232Z","end":"2026-07-31T00:34:00.224286Z","steps":["trace[1839900378] 'agreement among raft nodes before linearized reading' (duration: 121.859452ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:34:00.224304Z","caller":"traceutil/trace.go:171","msg":"trace[1765348321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23241; }","duration":"431.178122ms","start":"2026-07-31T00:33:59.7931Z","end":"2026-07-31T00:34:00.224278Z","steps":["trace[1765348321] 'agreement among raft nodes before linearized reading' (duration: 430.966078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:34:00.224349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:33:59.793081Z","time spent":"431.259753ms","remote":"127.0.0.1:52794","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-07-31T00:34:00.224405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.261065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-31T00:34:00.224454Z","caller":"traceutil/trace.go:171","msg":"trace[2035762840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23241; }","duration":"179.349567ms","start":"2026-07-31T00:34:00.045092Z","end":"2026-07-31T00:34:00.224442Z","steps":["trace[2035762840] 'agreement among raft nodes before linearized reading' (duration: 179.246774ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:36:09.024621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21911} {"level":"info","ts":"2026-07-31T00:36:09.096895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21911,"took":"69.844915ms","hash":373483017,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":21618688,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-31T00:36:09.096968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":373483017,"revision":21911,"compact-revision":19739} {"level":"warn","ts":"2026-07-31T00:39:58.657426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.042351ms","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-31T00:39:58.657533Z","caller":"traceutil/trace.go:171","msg":"trace[916100789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26481; }","duration":"103.206375ms","start":"2026-07-31T00:39:58.554302Z","end":"2026-07-31T00:39:58.657508Z","steps":["trace[916100789] 'range keys from in-memory index tree' (duration: 102.820267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:58.657638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.624962ms","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-31T00:39:58.657741Z","caller":"traceutil/trace.go:171","msg":"trace[1884869302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26481; }","duration":"122.772005ms","start":"2026-07-31T00:39:58.534948Z","end":"2026-07-31T00:39:58.65772Z","steps":["trace[1884869302] 'range keys from in-memory index tree' (duration: 122.429407ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:58.789023Z","caller":"traceutil/trace.go:171","msg":"trace[1422880061] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"184.42917ms","start":"2026-07-31T00:39:58.604567Z","end":"2026-07-31T00:39:58.788996Z","steps":["trace[1422880061] 'process raft request' (duration: 184.262017ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:58.803036Z","caller":"traceutil/trace.go:171","msg":"trace[1745518727] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"139.24607ms","start":"2026-07-31T00:39:58.663763Z","end":"2026-07-31T00:39:58.803009Z","steps":["trace[1745518727] 'process raft request' (duration: 139.202919ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:58.803083Z","caller":"traceutil/trace.go:171","msg":"trace[5030685] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"139.473974ms","start":"2026-07-31T00:39:58.663568Z","end":"2026-07-31T00:39:58.803042Z","steps":["trace[5030685] 'process raft request' (duration: 139.217859ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:58.803276Z","caller":"traceutil/trace.go:171","msg":"trace[1799314156] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"139.656929ms","start":"2026-07-31T00:39:58.663602Z","end":"2026-07-31T00:39:58.803259Z","steps":["trace[1799314156] 'process raft request' (duration: 139.328501ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:03.725698Z","caller":"traceutil/trace.go:171","msg":"trace[432307346] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"239.650229ms","start":"2026-07-31T00:40:03.486012Z","end":"2026-07-31T00:40:03.725662Z","steps":["trace[432307346] 'process raft request' (duration: 213.989065ms)","trace[432307346] 'compare' (duration: 25.390949ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:03.879172Z","caller":"traceutil/trace.go:171","msg":"trace[1635313490] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"168.64926ms","start":"2026-07-31T00:40:03.710489Z","end":"2026-07-31T00:40:03.879139Z","steps":["trace[1635313490] 'process raft request' (duration: 144.594699ms)","trace[1635313490] 'compare' (duration: 23.900398ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:04.293989Z","caller":"traceutil/trace.go:171","msg":"trace[1197808603] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"245.453337ms","start":"2026-07-31T00:40:04.048507Z","end":"2026-07-31T00:40:04.293961Z","steps":["trace[1197808603] 'process raft request' (duration: 242.270211ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:04.679239Z","caller":"traceutil/trace.go:171","msg":"trace[550708309] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"262.689679ms","start":"2026-07-31T00:40:04.416532Z","end":"2026-07-31T00:40:04.679221Z","steps":["trace[550708309] 'process raft request' (duration: 262.550786ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:41:09.030955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24595} {"level":"info","ts":"2026-07-31T00:41:09.132715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24595,"took":"98.832996ms","hash":609212481,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":21323776,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-07-31T00:41:09.132775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":609212481,"revision":24595,"compact-revision":21911} {"level":"info","ts":"2026-07-31T00:45:28.770129Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T00:45:28.775956Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-31T00:45:28.776044Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-31T00:46:09.069505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27228} {"level":"info","ts":"2026-07-31T00:46:09.158331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27228,"took":"86.304957ms","hash":1309870933,"current-db-size-bytes":35663872,"current-db-size":"36 MB","current-db-size-in-use-bytes":22253568,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-31T00:46:09.158388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1309870933,"revision":27228,"compact-revision":24595} {"level":"info","ts":"2026-07-31T00:46:22.141357Z","caller":"traceutil/trace.go:171","msg":"trace[688379596] transaction","detail":"{read_only:false; response_revision:30197; number_of_response:1; }","duration":"178.71232ms","start":"2026-07-31T00:46:21.962627Z","end":"2026-07-31T00:46:22.141339Z","steps":["trace[688379596] 'process raft request' (duration: 178.488125ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:46:22.141333Z","caller":"traceutil/trace.go:171","msg":"trace[2048623757] linearizableReadLoop","detail":"{readStateIndex:31102; appliedIndex:31101; }","duration":"178.537466ms","start":"2026-07-31T00:46:21.962766Z","end":"2026-07-31T00:46:22.141303Z","steps":["trace[2048623757] 'read index received' (duration: 178.374363ms)","trace[2048623757] 'applied index is now lower than readState.Index' (duration: 161.993µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:46:22.141519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.72799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-31T00:46:22.141592Z","caller":"traceutil/trace.go:171","msg":"trace[1252247571] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:30197; }","duration":"178.855982ms","start":"2026-07-31T00:46:21.962723Z","end":"2026-07-31T00:46:22.141579Z","steps":["trace[1252247571] 'agreement among raft nodes before linearized reading' (duration: 178.670879ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:46:22.162631Z","caller":"traceutil/trace.go:171","msg":"trace[418906997] transaction","detail":"{read_only:false; response_revision:30198; number_of_response:1; }","duration":"165.754656ms","start":"2026-07-31T00:46:21.996828Z","end":"2026-07-31T00:46:22.162583Z","steps":["trace[418906997] 'process raft request' (duration: 165.47012ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:03.993247Z","caller":"traceutil/trace.go:171","msg":"trace[243626297] transaction","detail":"{read_only:false; response_revision:33236; number_of_response:1; }","duration":"108.889787ms","start":"2026-07-31T00:51:03.884326Z","end":"2026-07-31T00:51:03.993216Z","steps":["trace[243626297] 'process raft request' (duration: 108.680793ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:04.007353Z","caller":"traceutil/trace.go:171","msg":"trace[1347725026] transaction","detail":"{read_only:false; response_revision:33237; number_of_response:1; }","duration":"122.380662ms","start":"2026-07-31T00:51:03.884744Z","end":"2026-07-31T00:51:04.007125Z","steps":["trace[1347725026] 'process raft request' (duration: 122.108216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.320596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.155482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:51:04.321016Z","caller":"traceutil/trace.go:171","msg":"trace[1577105120] linearizableReadLoop","detail":"{readStateIndex:34213; appliedIndex:34212; }","duration":"219.48112ms","start":"2026-07-31T00:51:04.101517Z","end":"2026-07-31T00:51:04.320998Z","steps":["trace[1577105120] 'read index received' (duration: 27.764376ms)","trace[1577105120] 'applied index is now lower than readState.Index' (duration: 191.714984ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:04.321019Z","caller":"traceutil/trace.go:171","msg":"trace[1280492363] transaction","detail":"{read_only:false; response_revision:33238; number_of_response:1; }","duration":"234.066675ms","start":"2026-07-31T00:51:04.086924Z","end":"2026-07-31T00:51:04.32099Z","steps":["trace[1280492363] 'process raft request' (duration: 42.411183ms)","trace[1280492363] 'compare' (duration: 190.968449ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:04.32134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.809037ms","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-31T00:51:04.321414Z","caller":"traceutil/trace.go:171","msg":"trace[543509513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33238; }","duration":"219.94433ms","start":"2026-07-31T00:51:04.101454Z","end":"2026-07-31T00:51:04.321398Z","steps":["trace[543509513] 'agreement among raft nodes before linearized reading' (duration: 219.641524ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:04.508693Z","caller":"traceutil/trace.go:171","msg":"trace[514219409] linearizableReadLoop","detail":"{readStateIndex:34214; appliedIndex:34213; }","duration":"187.585489ms","start":"2026-07-31T00:51:04.321073Z","end":"2026-07-31T00:51:04.508658Z","steps":["trace[514219409] 'read index received' (duration: 187.255862ms)","trace[514219409] 'applied index is now lower than readState.Index' (duration: 328.327µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:04.508883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.271181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:04.508969Z","caller":"traceutil/trace.go:171","msg":"trace[385062088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33239; }","duration":"291.407804ms","start":"2026-07-31T00:51:04.217544Z","end":"2026-07-31T00:51:04.508952Z","steps":["trace[385062088] 'agreement among raft nodes before linearized reading' (duration: 291.167688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.508985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.15058ms","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-31T00:51:04.508969Z","caller":"traceutil/trace.go:171","msg":"trace[1166124291] transaction","detail":"{read_only:false; response_revision:33239; number_of_response:1; }","duration":"284.224769ms","start":"2026-07-31T00:51:04.224677Z","end":"2026-07-31T00:51:04.508902Z","steps":["trace[1166124291] 'process raft request' (duration: 283.675537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.50904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.187921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:04.509027Z","caller":"traceutil/trace.go:171","msg":"trace[2008442590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:33239; }","duration":"375.246581ms","start":"2026-07-31T00:51:04.133769Z","end":"2026-07-31T00:51:04.509016Z","steps":["trace[2008442590] 'agreement among raft nodes before linearized reading' (duration: 375.050637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.509118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.633737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:537"} {"level":"info","ts":"2026-07-31T00:51:04.509138Z","caller":"traceutil/trace.go:171","msg":"trace[1892095445] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:33239; }","duration":"293.328554ms","start":"2026-07-31T00:51:04.215787Z","end":"2026-07-31T00:51:04.509115Z","steps":["trace[1892095445] 'agreement among raft nodes before linearized reading' (duration: 293.13032ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:04.509186Z","caller":"traceutil/trace.go:171","msg":"trace[1769801837] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33239; }","duration":"395.746779ms","start":"2026-07-31T00:51:04.113422Z","end":"2026-07-31T00:51:04.509169Z","steps":["trace[1769801837] 'agreement among raft nodes before linearized reading' (duration: 395.541645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.509239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.113405Z","time spent":"395.820101ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-31T00:51:04.509239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.133756Z","time spent":"375.408634ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T00:51:04.509579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.152436ms","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-31T00:51:04.509723Z","caller":"traceutil/trace.go:171","msg":"trace[866013267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:33239; }","duration":"365.357981ms","start":"2026-07-31T00:51:04.144334Z","end":"2026-07-31T00:51:04.509692Z","steps":["trace[866013267] 'agreement among raft nodes before linearized reading' (duration: 364.421902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.509781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.144319Z","time spent":"365.451162ms","remote":"127.0.0.1:53084","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":"info","ts":"2026-07-31T00:51:04.600294Z","caller":"traceutil/trace.go:171","msg":"trace[126813687] transaction","detail":"{read_only:false; response_revision:33241; number_of_response:1; }","duration":"147.905142ms","start":"2026-07-31T00:51:04.452358Z","end":"2026-07-31T00:51:04.600264Z","steps":["trace[126813687] 'process raft request' (duration: 147.851011ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:04.600735Z","caller":"traceutil/trace.go:171","msg":"trace[1552339738] transaction","detail":"{read_only:false; response_revision:33240; number_of_response:1; }","duration":"274.814706ms","start":"2026-07-31T00:51:04.325907Z","end":"2026-07-31T00:51:04.600722Z","steps":["trace[1552339738] 'process raft request' (duration: 274.166333ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:04.858464Z","caller":"traceutil/trace.go:171","msg":"trace[1981764895] transaction","detail":"{read_only:false; response_revision:33242; number_of_response:1; }","duration":"344.424874ms","start":"2026-07-31T00:51:04.513994Z","end":"2026-07-31T00:51:04.858419Z","steps":["trace[1981764895] 'process raft request' (duration: 308.546084ms)","trace[1981764895] 'compare' (duration: 35.448791ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:04.858515Z","caller":"traceutil/trace.go:171","msg":"trace[744869074] transaction","detail":"{read_only:false; response_revision:33243; number_of_response:1; }","duration":"343.693358ms","start":"2026-07-31T00:51:04.514789Z","end":"2026-07-31T00:51:04.858482Z","steps":["trace[744869074] 'process raft request' (duration: 343.507514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.858684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.513982Z","time spent":"344.601767ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:51:04.858665Z","caller":"traceutil/trace.go:171","msg":"trace[409874270] transaction","detail":"{read_only:false; response_revision:33244; number_of_response:1; }","duration":"342.850352ms","start":"2026-07-31T00:51:04.515765Z","end":"2026-07-31T00:51:04.858616Z","steps":["trace[409874270] 'process raft request' (duration: 342.671268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.858725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.381355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:04.858771Z","caller":"traceutil/trace.go:171","msg":"trace[546204095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33244; }","duration":"348.453686ms","start":"2026-07-31T00:51:04.510306Z","end":"2026-07-31T00:51:04.85876Z","steps":["trace[546204095] 'agreement among raft nodes before linearized reading' (duration: 348.329983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.858795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.514782Z","time spent":"343.877112ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:51:04.858812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.510299Z","time spent":"348.504547ms","remote":"127.0.0.1:52784","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-07-31T00:51:04.858798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.773847ms","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-31T00:51:04.858889Z","caller":"traceutil/trace.go:171","msg":"trace[1164058842] 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:33244; }","duration":"308.889071ms","start":"2026-07-31T00:51:04.549973Z","end":"2026-07-31T00:51:04.858862Z","steps":["trace[1164058842] 'agreement among raft nodes before linearized reading' (duration: 308.690747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:04.858893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.51574Z","time spent":"343.030395ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:51:04.858581Z","caller":"traceutil/trace.go:171","msg":"trace[322889268] linearizableReadLoop","detail":"{readStateIndex:34219; appliedIndex:34216; }","duration":"257.428143ms","start":"2026-07-31T00:51:04.601105Z","end":"2026-07-31T00:51:04.858534Z","steps":["trace[322889268] 'read index received' (duration: 221.612824ms)","trace[322889268] 'applied index is now lower than readState.Index' (duration: 35.813969ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:04.85895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:04.549862Z","time spent":"309.077495ms","remote":"127.0.0.1:38412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":27,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-07-31T00:51:04.866178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.560224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1630"} {"level":"info","ts":"2026-07-31T00:51:04.866256Z","caller":"traceutil/trace.go:171","msg":"trace[361678841] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:33245; }","duration":"118.654096ms","start":"2026-07-31T00:51:04.74758Z","end":"2026-07-31T00:51:04.866234Z","steps":["trace[361678841] 'agreement among raft nodes before linearized reading' (duration: 118.390511ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:04.866673Z","caller":"traceutil/trace.go:171","msg":"trace[551792634] transaction","detail":"{read_only:false; response_revision:33245; number_of_response:1; }","duration":"131.540268ms","start":"2026-07-31T00:51:04.735121Z","end":"2026-07-31T00:51:04.866662Z","steps":["trace[551792634] 'process raft request' (duration: 130.753183ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:05.027874Z","caller":"traceutil/trace.go:171","msg":"trace[415244642] transaction","detail":"{read_only:false; response_revision:33246; number_of_response:1; }","duration":"149.051805ms","start":"2026-07-31T00:51:04.878787Z","end":"2026-07-31T00:51:05.027839Z","steps":["trace[415244642] 'process raft request' (duration: 135.090201ms)","trace[415244642] 'compare' (duration: 13.798791ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:05.254077Z","caller":"traceutil/trace.go:171","msg":"trace[1972986128] transaction","detail":"{read_only:false; response_revision:33247; number_of_response:1; }","duration":"107.200394ms","start":"2026-07-31T00:51:05.146832Z","end":"2026-07-31T00:51:05.254032Z","steps":["trace[1972986128] 'process raft request' (duration: 21.483978ms)","trace[1972986128] 'compare' (duration: 85.414159ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:05.310744Z","caller":"traceutil/trace.go:171","msg":"trace[803359071] transaction","detail":"{read_only:false; response_revision:33248; number_of_response:1; }","duration":"136.211505ms","start":"2026-07-31T00:51:05.174499Z","end":"2026-07-31T00:51:05.310711Z","steps":["trace[803359071] 'process raft request' (duration: 136.063622ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:05.666443Z","caller":"traceutil/trace.go:171","msg":"trace[122807702] linearizableReadLoop","detail":"{readStateIndex:34224; appliedIndex:34223; }","duration":"138.361568ms","start":"2026-07-31T00:51:05.52806Z","end":"2026-07-31T00:51:05.666422Z","steps":["trace[122807702] 'read index received' (duration: 138.161964ms)","trace[122807702] 'applied index is now lower than readState.Index' (duration: 198.534µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:05.666565Z","caller":"traceutil/trace.go:171","msg":"trace[2109775634] transaction","detail":"{read_only:false; response_revision:33249; number_of_response:1; }","duration":"280.4004ms","start":"2026-07-31T00:51:05.386127Z","end":"2026-07-31T00:51:05.666528Z","steps":["trace[2109775634] 'process raft request' (duration: 280.149675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:05.666596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.522711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:05.666624Z","caller":"traceutil/trace.go:171","msg":"trace[2089987006] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:33249; }","duration":"138.608283ms","start":"2026-07-31T00:51:05.528006Z","end":"2026-07-31T00:51:05.666615Z","steps":["trace[2089987006] 'agreement among raft nodes before linearized reading' (duration: 138.519551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:05.857402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.84935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:51:05.857597Z","caller":"traceutil/trace.go:171","msg":"trace[198941256] linearizableReadLoop","detail":"{readStateIndex:34225; appliedIndex:34224; }","duration":"191.073171ms","start":"2026-07-31T00:51:05.666501Z","end":"2026-07-31T00:51:05.857574Z","steps":["trace[198941256] 'read index received' (duration: 44.835003ms)","trace[198941256] 'applied index is now lower than readState.Index' (duration: 146.236178ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:05.858022Z","caller":"traceutil/trace.go:171","msg":"trace[2138664110] transaction","detail":"{read_only:false; response_revision:33250; number_of_response:1; }","duration":"268.748333ms","start":"2026-07-31T00:51:05.58923Z","end":"2026-07-31T00:51:05.857978Z","steps":["trace[2138664110] 'process raft request' (duration: 122.184028ms)","trace[2138664110] 'compare' (duration: 145.682217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:05.858096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.730434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-31T00:51:05.858055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.104451ms","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-31T00:51:05.858146Z","caller":"traceutil/trace.go:171","msg":"trace[381405600] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:33250; }","duration":"162.817686ms","start":"2026-07-31T00:51:05.695315Z","end":"2026-07-31T00:51:05.858132Z","steps":["trace[381405600] 'agreement among raft nodes before linearized reading' (duration: 162.635072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:05.858125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.146239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T00:51:05.858034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.864838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:05.858181Z","caller":"traceutil/trace.go:171","msg":"trace[536229454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33250; }","duration":"329.263764ms","start":"2026-07-31T00:51:05.528887Z","end":"2026-07-31T00:51:05.858151Z","steps":["trace[536229454] 'agreement among raft nodes before linearized reading' (duration: 328.852756ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:05.858206Z","caller":"traceutil/trace.go:171","msg":"trace[1377553546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33250; }","duration":"154.240491ms","start":"2026-07-31T00:51:05.703946Z","end":"2026-07-31T00:51:05.858187Z","steps":["trace[1377553546] 'agreement among raft nodes before linearized reading' (duration: 154.000916ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:05.858216Z","caller":"traceutil/trace.go:171","msg":"trace[571819129] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:33250; }","duration":"251.103543ms","start":"2026-07-31T00:51:05.607093Z","end":"2026-07-31T00:51:05.858197Z","steps":["trace[571819129] 'agreement among raft nodes before linearized reading' (duration: 250.827378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:05.858258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:05.528881Z","time spent":"329.356367ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T00:51:06.14033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.796088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:51:06.140613Z","caller":"traceutil/trace.go:171","msg":"trace[2082207067] linearizableReadLoop","detail":"{readStateIndex:34228; appliedIndex:34225; }","duration":"165.783086ms","start":"2026-07-31T00:51:05.974807Z","end":"2026-07-31T00:51:06.140591Z","steps":["trace[2082207067] 'read index received' (duration: 22.550779ms)","trace[2082207067] 'applied index is now lower than readState.Index' (duration: 143.230827ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:06.14071Z","caller":"traceutil/trace.go:171","msg":"trace[1234137074] transaction","detail":"{read_only:false; response_revision:33252; number_of_response:1; }","duration":"277.252006ms","start":"2026-07-31T00:51:05.863446Z","end":"2026-07-31T00:51:06.140698Z","steps":["trace[1234137074] 'process raft request' (duration: 277.039832ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.140779Z","caller":"traceutil/trace.go:171","msg":"trace[1670179812] transaction","detail":"{read_only:false; response_revision:33251; number_of_response:1; }","duration":"277.732066ms","start":"2026-07-31T00:51:05.863002Z","end":"2026-07-31T00:51:06.140734Z","steps":["trace[1670179812] 'process raft request' (duration: 134.410417ms)","trace[1670179812] 'compare' (duration: 142.630955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:06.14122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.409798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:51:06.141263Z","caller":"traceutil/trace.go:171","msg":"trace[1824913425] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33253; }","duration":"166.47845ms","start":"2026-07-31T00:51:05.974773Z","end":"2026-07-31T00:51:06.141251Z","steps":["trace[1824913425] 'agreement among raft nodes before linearized reading' (duration: 166.301267ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.140868Z","caller":"traceutil/trace.go:171","msg":"trace[618892470] transaction","detail":"{read_only:false; response_revision:33253; number_of_response:1; }","duration":"276.869049ms","start":"2026-07-31T00:51:05.863957Z","end":"2026-07-31T00:51:06.140826Z","steps":["trace[618892470] 'process raft request' (duration: 276.588983ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.225036Z","caller":"traceutil/trace.go:171","msg":"trace[637113415] transaction","detail":"{read_only:false; response_revision:33255; number_of_response:1; }","duration":"213.300334ms","start":"2026-07-31T00:51:06.011705Z","end":"2026-07-31T00:51:06.225005Z","steps":["trace[637113415] 'process raft request' (duration: 213.186251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:06.225242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.855347ms","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-31T00:51:06.225318Z","caller":"traceutil/trace.go:171","msg":"trace[1421848622] 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:33255; }","duration":"191.974969ms","start":"2026-07-31T00:51:06.033328Z","end":"2026-07-31T00:51:06.225303Z","steps":["trace[1421848622] 'agreement among raft nodes before linearized reading' (duration: 191.819616ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.225082Z","caller":"traceutil/trace.go:171","msg":"trace[560465235] transaction","detail":"{read_only:false; response_revision:33254; number_of_response:1; }","duration":"222.421868ms","start":"2026-07-31T00:51:06.002616Z","end":"2026-07-31T00:51:06.225038Z","steps":["trace[560465235] 'process raft request' (duration: 222.107622ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.383851Z","caller":"traceutil/trace.go:171","msg":"trace[1335831667] linearizableReadLoop","detail":"{readStateIndex:34231; appliedIndex:34230; }","duration":"158.857815ms","start":"2026-07-31T00:51:06.224952Z","end":"2026-07-31T00:51:06.38381Z","steps":["trace[1335831667] 'read index received' (duration: 71.023156ms)","trace[1335831667] 'applied index is now lower than readState.Index' (duration: 87.830559ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:06.383859Z","caller":"traceutil/trace.go:171","msg":"trace[4764932] transaction","detail":"{read_only:false; response_revision:33256; number_of_response:1; }","duration":"241.269413ms","start":"2026-07-31T00:51:06.142528Z","end":"2026-07-31T00:51:06.383798Z","steps":["trace[4764932] 'process raft request' (duration: 153.413613ms)","trace[4764932] 'compare' (duration: 87.504883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:06.383888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.736936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T00:51:06.38412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.254601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-07-31T00:51:06.384158Z","caller":"traceutil/trace.go:171","msg":"trace[184222346] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33257; }","duration":"183.348584ms","start":"2026-07-31T00:51:06.200795Z","end":"2026-07-31T00:51:06.384144Z","steps":["trace[184222346] 'agreement among raft nodes before linearized reading' (duration: 183.18033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:06.384298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.325493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:51:06.38411Z","caller":"traceutil/trace.go:171","msg":"trace[1341457810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33257; }","duration":"169.006251ms","start":"2026-07-31T00:51:06.215071Z","end":"2026-07-31T00:51:06.384077Z","steps":["trace[1341457810] 'agreement among raft nodes before linearized reading' (duration: 168.748226ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.384368Z","caller":"traceutil/trace.go:171","msg":"trace[1550150236] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33257; }","duration":"239.471706ms","start":"2026-07-31T00:51:06.144879Z","end":"2026-07-31T00:51:06.38435Z","steps":["trace[1550150236] 'agreement among raft nodes before linearized reading' (duration: 239.066588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:06.60902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.86253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-31T00:51:06.609152Z","caller":"traceutil/trace.go:171","msg":"trace[1161435185] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:33258; }","duration":"118.046024ms","start":"2026-07-31T00:51:06.491075Z","end":"2026-07-31T00:51:06.609121Z","steps":["trace[1161435185] 'range keys from in-memory index tree' (duration: 117.620665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:06.609028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.384478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:51:06.609324Z","caller":"traceutil/trace.go:171","msg":"trace[351941485] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:33258; }","duration":"113.734495ms","start":"2026-07-31T00:51:06.495557Z","end":"2026-07-31T00:51:06.609292Z","steps":["trace[351941485] 'count revisions from in-memory index tree' (duration: 113.311427ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.618333Z","caller":"traceutil/trace.go:171","msg":"trace[608577490] transaction","detail":"{read_only:false; response_revision:33259; number_of_response:1; }","duration":"103.659251ms","start":"2026-07-31T00:51:06.514646Z","end":"2026-07-31T00:51:06.618306Z","steps":["trace[608577490] 'process raft request' (duration: 103.413155ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:06.769114Z","caller":"traceutil/trace.go:171","msg":"trace[1312539626] transaction","detail":"{read_only:false; response_revision:33260; number_of_response:1; }","duration":"145.87288ms","start":"2026-07-31T00:51:06.623205Z","end":"2026-07-31T00:51:06.769078Z","steps":["trace[1312539626] 'process raft request' (duration: 145.664636ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:07.056117Z","caller":"traceutil/trace.go:171","msg":"trace[1326289688] transaction","detail":"{read_only:false; response_revision:33263; number_of_response:1; }","duration":"135.537171ms","start":"2026-07-31T00:51:06.920553Z","end":"2026-07-31T00:51:07.05609Z","steps":["trace[1326289688] 'process raft request' (duration: 119.542885ms)","trace[1326289688] 'compare' (duration: 15.845443ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:07.499097Z","caller":"traceutil/trace.go:171","msg":"trace[46210485] transaction","detail":"{read_only:false; response_revision:33265; number_of_response:1; }","duration":"183.120668ms","start":"2026-07-31T00:51:07.315929Z","end":"2026-07-31T00:51:07.49905Z","steps":["trace[46210485] 'process raft request' (duration: 142.334907ms)","trace[46210485] 'compare' (duration: 40.585837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:07.800198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.295911ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T00:51:07.800318Z","caller":"traceutil/trace.go:171","msg":"trace[1049053158] linearizableReadLoop","detail":"{readStateIndex:34241; appliedIndex:34240; }","duration":"186.817324ms","start":"2026-07-31T00:51:07.613482Z","end":"2026-07-31T00:51:07.800299Z","steps":["trace[1049053158] 'read index received' (duration: 5.243587ms)","trace[1049053158] 'applied index is now lower than readState.Index' (duration: 181.572257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:07.800409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.922916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:07.800468Z","caller":"traceutil/trace.go:171","msg":"trace[682799402] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:33265; }","duration":"186.997648ms","start":"2026-07-31T00:51:07.613458Z","end":"2026-07-31T00:51:07.800455Z","steps":["trace[682799402] 'agreement among raft nodes before linearized reading' (duration: 186.888135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:07.800525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.51744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:07.800588Z","caller":"traceutil/trace.go:171","msg":"trace[1148884545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:33265; }","duration":"160.705013ms","start":"2026-07-31T00:51:07.63987Z","end":"2026-07-31T00:51:07.800575Z","steps":["trace[1148884545] 'agreement among raft nodes before linearized reading' (duration: 160.5284ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:08.36825Z","caller":"traceutil/trace.go:171","msg":"trace[1697856610] transaction","detail":"{read_only:false; response_revision:33270; number_of_response:1; }","duration":"137.257295ms","start":"2026-07-31T00:51:08.230954Z","end":"2026-07-31T00:51:08.368211Z","steps":["trace[1697856610] 'process raft request' (duration: 53.846736ms)","trace[1697856610] 'compare' (duration: 83.137713ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:08.372235Z","caller":"traceutil/trace.go:171","msg":"trace[1293774574] transaction","detail":"{read_only:false; response_revision:33271; number_of_response:1; }","duration":"140.838919ms","start":"2026-07-31T00:51:08.231379Z","end":"2026-07-31T00:51:08.372218Z","steps":["trace[1293774574] 'process raft request' (duration: 140.694346ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:09.075669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29616} {"level":"info","ts":"2026-07-31T00:51:09.191643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29616,"took":"111.649734ms","hash":819140050,"current-db-size-bytes":37904384,"current-db-size":"38 MB","current-db-size-in-use-bytes":32440320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T00:51:09.191744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":819140050,"revision":29616,"compact-revision":27228} {"level":"info","ts":"2026-07-31T00:51:29.707851Z","caller":"traceutil/trace.go:171","msg":"trace[807068444] transaction","detail":"{read_only:false; response_revision:33456; number_of_response:1; }","duration":"120.423092ms","start":"2026-07-31T00:51:29.587403Z","end":"2026-07-31T00:51:29.707826Z","steps":["trace[807068444] 'process raft request' (duration: 73.555788ms)","trace[807068444] 'compare' (duration: 46.772392ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:30.07586Z","caller":"traceutil/trace.go:171","msg":"trace[1285323835] transaction","detail":"{read_only:false; response_revision:33458; number_of_response:1; }","duration":"100.586268ms","start":"2026-07-31T00:51:29.975231Z","end":"2026-07-31T00:51:30.075817Z","steps":["trace[1285323835] 'process raft request' (duration: 29.762406ms)","trace[1285323835] 'compare' (duration: 70.646299ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:30.527472Z","caller":"traceutil/trace.go:171","msg":"trace[1824196520] transaction","detail":"{read_only:false; response_revision:33461; number_of_response:1; }","duration":"230.555135ms","start":"2026-07-31T00:51:30.296887Z","end":"2026-07-31T00:51:30.527442Z","steps":["trace[1824196520] 'process raft request' (duration: 160.994709ms)","trace[1824196520] 'compare' (duration: 69.369592ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:30.528104Z","caller":"traceutil/trace.go:171","msg":"trace[526827799] transaction","detail":"{read_only:false; response_revision:33462; number_of_response:1; }","duration":"138.275287ms","start":"2026-07-31T00:51:30.389812Z","end":"2026-07-31T00:51:30.528087Z","steps":["trace[526827799] 'process raft request' (duration: 137.585142ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:31.044512Z","caller":"traceutil/trace.go:171","msg":"trace[595868005] transaction","detail":"{read_only:false; response_revision:33467; number_of_response:1; }","duration":"102.455247ms","start":"2026-07-31T00:51:30.941995Z","end":"2026-07-31T00:51:31.04445Z","steps":["trace[595868005] 'process raft request' (duration: 102.291504ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:31.236784Z","caller":"traceutil/trace.go:171","msg":"trace[498617079] transaction","detail":"{read_only:false; response_revision:33469; number_of_response:1; }","duration":"130.026859ms","start":"2026-07-31T00:51:31.106717Z","end":"2026-07-31T00:51:31.236744Z","steps":["trace[498617079] 'process raft request' (duration: 49.938358ms)","trace[498617079] 'compare' (duration: 79.923298ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:31.609619Z","caller":"traceutil/trace.go:171","msg":"trace[1813379276] transaction","detail":"{read_only:false; response_revision:33471; number_of_response:1; }","duration":"105.748543ms","start":"2026-07-31T00:51:31.503842Z","end":"2026-07-31T00:51:31.60959Z","steps":["trace[1813379276] 'process raft request' (duration: 105.047988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:32.393335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.263869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-31T00:51:32.393405Z","caller":"traceutil/trace.go:171","msg":"trace[1005814091] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:33474; }","duration":"120.347581ms","start":"2026-07-31T00:51:32.273041Z","end":"2026-07-31T00:51:32.393388Z","steps":["trace[1005814091] 'range keys from in-memory index tree' (duration: 120.026214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:32.682714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.031787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:51:32.682851Z","caller":"traceutil/trace.go:171","msg":"trace[1060649208] transaction","detail":"{read_only:false; response_revision:33475; number_of_response:1; }","duration":"284.973393ms","start":"2026-07-31T00:51:32.39785Z","end":"2026-07-31T00:51:32.682824Z","steps":["trace[1060649208] 'process raft request' (duration: 132.724103ms)","trace[1060649208] 'compare' (duration: 151.73559ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:32.686905Z","caller":"traceutil/trace.go:171","msg":"trace[949989522] linearizableReadLoop","detail":"{readStateIndex:34458; appliedIndex:34457; }","duration":"153.599157ms","start":"2026-07-31T00:51:32.533279Z","end":"2026-07-31T00:51:32.686878Z","steps":["trace[949989522] 'read index received' (duration: 153.381743ms)","trace[949989522] 'applied index is now lower than readState.Index' (duration: 215.494µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:32.686968Z","caller":"traceutil/trace.go:171","msg":"trace[1295588551] transaction","detail":"{read_only:false; response_revision:33476; number_of_response:1; }","duration":"282.058113ms","start":"2026-07-31T00:51:32.404857Z","end":"2026-07-31T00:51:32.686915Z","steps":["trace[1295588551] 'process raft request' (duration: 281.864559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:32.687335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.036966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T00:51:32.687421Z","caller":"traceutil/trace.go:171","msg":"trace[8170370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33476; }","duration":"154.164839ms","start":"2026-07-31T00:51:32.533211Z","end":"2026-07-31T00:51:32.687376Z","steps":["trace[8170370] 'agreement among raft nodes before linearized reading' (duration: 153.796912ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:32.70652Z","caller":"traceutil/trace.go:171","msg":"trace[577255882] transaction","detail":"{read_only:false; response_revision:33478; number_of_response:1; }","duration":"170.200296ms","start":"2026-07-31T00:51:32.536297Z","end":"2026-07-31T00:51:32.706497Z","steps":["trace[577255882] 'process raft request' (duration: 170.117074ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:32.706524Z","caller":"traceutil/trace.go:171","msg":"trace[701053245] transaction","detail":"{read_only:false; response_revision:33477; number_of_response:1; }","duration":"171.972641ms","start":"2026-07-31T00:51:32.534494Z","end":"2026-07-31T00:51:32.706467Z","steps":["trace[701053245] 'process raft request' (duration: 171.836929ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:32.706913Z","caller":"traceutil/trace.go:171","msg":"trace[389580544] transaction","detail":"{read_only:false; response_revision:33479; number_of_response:1; }","duration":"124.28389ms","start":"2026-07-31T00:51:32.582611Z","end":"2026-07-31T00:51:32.706894Z","steps":["trace[389580544] 'process raft request' (duration: 123.842531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:32.707131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.099044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-31T00:51:32.707248Z","caller":"traceutil/trace.go:171","msg":"trace[1007610149] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:33479; }","duration":"173.252186ms","start":"2026-07-31T00:51:32.533971Z","end":"2026-07-31T00:51:32.707224Z","steps":["trace[1007610149] 'agreement among raft nodes before linearized reading' (duration: 172.94294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:32.936679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.488076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:51:32.936782Z","caller":"traceutil/trace.go:171","msg":"trace[20194963] linearizableReadLoop","detail":"{readStateIndex:34462; appliedIndex:34461; }","duration":"229.923662ms","start":"2026-07-31T00:51:32.706842Z","end":"2026-07-31T00:51:32.936766Z","steps":["trace[20194963] 'read index received' (duration: 101.296153ms)","trace[20194963] 'applied index is now lower than readState.Index' (duration: 128.625869ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:32.936968Z","caller":"traceutil/trace.go:171","msg":"trace[1545274034] transaction","detail":"{read_only:false; response_revision:33480; number_of_response:1; }","duration":"245.652502ms","start":"2026-07-31T00:51:32.69127Z","end":"2026-07-31T00:51:32.936923Z","steps":["trace[1545274034] 'process raft request' (duration: 116.833019ms)","trace[1545274034] 'compare' (duration: 128.384135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:32.937033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.890889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-31T00:51:32.937104Z","caller":"traceutil/trace.go:171","msg":"trace[911724048] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:33480; }","duration":"248.021782ms","start":"2026-07-31T00:51:32.689064Z","end":"2026-07-31T00:51:32.937086Z","steps":["trace[911724048] 'agreement among raft nodes before linearized reading' (duration: 247.858357ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:32.942471Z","caller":"traceutil/trace.go:171","msg":"trace[130346281] transaction","detail":"{read_only:false; response_revision:33482; number_of_response:1; }","duration":"167.054741ms","start":"2026-07-31T00:51:32.775348Z","end":"2026-07-31T00:51:32.942403Z","steps":["trace[130346281] 'process raft request' (duration: 166.937479ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:32.942482Z","caller":"traceutil/trace.go:171","msg":"trace[1726345228] transaction","detail":"{read_only:false; response_revision:33481; number_of_response:1; }","duration":"229.476453ms","start":"2026-07-31T00:51:32.712908Z","end":"2026-07-31T00:51:32.942385Z","steps":["trace[1726345228] 'process raft request' (duration: 229.234148ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:36.882172Z","caller":"traceutil/trace.go:171","msg":"trace[1061830637] linearizableReadLoop","detail":"{readStateIndex:34492; appliedIndex:34491; }","duration":"219.353078ms","start":"2026-07-31T00:51:36.662793Z","end":"2026-07-31T00:51:36.882146Z","steps":["trace[1061830637] 'read index received' (duration: 219.209875ms)","trace[1061830637] 'applied index is now lower than readState.Index' (duration: 142.153µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:36.882269Z","caller":"traceutil/trace.go:171","msg":"trace[2142826183] transaction","detail":"{read_only:false; response_revision:33510; number_of_response:1; }","duration":"246.02478ms","start":"2026-07-31T00:51:36.63622Z","end":"2026-07-31T00:51:36.882244Z","steps":["trace[2142826183] 'process raft request' (duration: 245.749904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:36.882392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.579452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-31T00:51:36.882432Z","caller":"traceutil/trace.go:171","msg":"trace[1747030081] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33510; }","duration":"219.702675ms","start":"2026-07-31T00:51:36.662715Z","end":"2026-07-31T00:51:36.882418Z","steps":["trace[1747030081] 'agreement among raft nodes before linearized reading' (duration: 219.550332ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:36.892852Z","caller":"traceutil/trace.go:171","msg":"trace[168135389] transaction","detail":"{read_only:false; response_revision:33513; number_of_response:1; }","duration":"170.672455ms","start":"2026-07-31T00:51:36.722151Z","end":"2026-07-31T00:51:36.892823Z","steps":["trace[168135389] 'process raft request' (duration: 170.520002ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:36.892886Z","caller":"traceutil/trace.go:171","msg":"trace[278484879] transaction","detail":"{read_only:false; response_revision:33511; number_of_response:1; }","duration":"172.390771ms","start":"2026-07-31T00:51:36.720404Z","end":"2026-07-31T00:51:36.892795Z","steps":["trace[278484879] 'process raft request' (duration: 172.083244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:36.893265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.632769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1636"} {"level":"info","ts":"2026-07-31T00:51:36.893316Z","caller":"traceutil/trace.go:171","msg":"trace[922711534] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:33513; }","duration":"154.720631ms","start":"2026-07-31T00:51:36.73858Z","end":"2026-07-31T00:51:36.8933Z","steps":["trace[922711534] 'agreement among raft nodes before linearized reading' (duration: 154.553608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:36.893369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.098471ms","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-31T00:51:36.893413Z","caller":"traceutil/trace.go:171","msg":"trace[1894022219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33513; }","duration":"191.176303ms","start":"2026-07-31T00:51:36.702225Z","end":"2026-07-31T00:51:36.893401Z","steps":["trace[1894022219] 'agreement among raft nodes before linearized reading' (duration: 191.00669ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:36.89306Z","caller":"traceutil/trace.go:171","msg":"trace[1251215886] transaction","detail":"{read_only:false; response_revision:33512; number_of_response:1; }","duration":"171.013863ms","start":"2026-07-31T00:51:36.722019Z","end":"2026-07-31T00:51:36.893032Z","steps":["trace[1251215886] 'process raft request' (duration: 170.603164ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:37.034846Z","caller":"traceutil/trace.go:171","msg":"trace[1945754570] transaction","detail":"{read_only:false; response_revision:33515; number_of_response:1; }","duration":"134.912927ms","start":"2026-07-31T00:51:36.899901Z","end":"2026-07-31T00:51:37.034814Z","steps":["trace[1945754570] 'process raft request' (duration: 134.741444ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:37.034977Z","caller":"traceutil/trace.go:171","msg":"trace[1615943966] transaction","detail":"{read_only:false; response_revision:33514; number_of_response:1; }","duration":"145.902402ms","start":"2026-07-31T00:51:36.889028Z","end":"2026-07-31T00:51:37.03493Z","steps":["trace[1615943966] 'process raft request' (duration: 97.977836ms)","trace[1615943966] 'compare' (duration: 47.12638ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:37.035102Z","caller":"traceutil/trace.go:171","msg":"trace[1467067782] linearizableReadLoop","detail":"{readStateIndex:34496; appliedIndex:34495; }","duration":"138.395107ms","start":"2026-07-31T00:51:36.896683Z","end":"2026-07-31T00:51:37.035078Z","steps":["trace[1467067782] 'read index received' (duration: 90.497422ms)","trace[1467067782] 'applied index is now lower than readState.Index' (duration: 47.896495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:51:37.035371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.663553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bc3190e1-4c14-4cfd-b727-5a09f6713a4a\" ","response":"range_response_count:1 size:2207"} {"level":"info","ts":"2026-07-31T00:51:37.035416Z","caller":"traceutil/trace.go:171","msg":"trace[2100339935] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bc3190e1-4c14-4cfd-b727-5a09f6713a4a; range_end:; response_count:1; response_revision:33516; }","duration":"138.748026ms","start":"2026-07-31T00:51:36.896655Z","end":"2026-07-31T00:51:37.035403Z","steps":["trace[2100339935] 'agreement among raft nodes before linearized reading' (duration: 138.560141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:37.226693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.049002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:51:37.226782Z","caller":"traceutil/trace.go:171","msg":"trace[855346192] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:33519; }","duration":"114.201005ms","start":"2026-07-31T00:51:37.112561Z","end":"2026-07-31T00:51:37.226762Z","steps":["trace[855346192] 'agreement among raft nodes before linearized reading' (duration: 114.034592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:37.226778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.510826ms","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-31T00:51:37.226857Z","caller":"traceutil/trace.go:171","msg":"trace[1316636920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33519; }","duration":"153.660829ms","start":"2026-07-31T00:51:37.073176Z","end":"2026-07-31T00:51:37.226837Z","steps":["trace[1316636920] 'agreement among raft nodes before linearized reading' (duration: 153.445665ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:37.226914Z","caller":"traceutil/trace.go:171","msg":"trace[469121344] transaction","detail":"{read_only:false; response_revision:33519; number_of_response:1; }","duration":"106.366566ms","start":"2026-07-31T00:51:37.120523Z","end":"2026-07-31T00:51:37.22689Z","steps":["trace[469121344] 'process raft request' (duration: 36.986823ms)","trace[469121344] 'compare' (duration: 68.863903ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:37.408775Z","caller":"traceutil/trace.go:171","msg":"trace[552474439] linearizableReadLoop","detail":"{readStateIndex:34502; appliedIndex:34501; }","duration":"175.906802ms","start":"2026-07-31T00:51:37.232833Z","end":"2026-07-31T00:51:37.40874Z","steps":["trace[552474439] 'read index received' (duration: 169.999562ms)","trace[552474439] 'applied index is now lower than readState.Index' (duration: 5.90581ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:37.408934Z","caller":"traceutil/trace.go:171","msg":"trace[782212663] transaction","detail":"{read_only:false; response_revision:33520; number_of_response:1; }","duration":"176.935503ms","start":"2026-07-31T00:51:37.231937Z","end":"2026-07-31T00:51:37.408873Z","steps":["trace[782212663] 'process raft request' (duration: 170.981632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:37.409105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.257789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:51:37.40918Z","caller":"traceutil/trace.go:171","msg":"trace[1302315501] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33520; }","duration":"176.426753ms","start":"2026-07-31T00:51:37.232736Z","end":"2026-07-31T00:51:37.409163Z","steps":["trace[1302315501] 'agreement among raft nodes before linearized reading' (duration: 176.193538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:37.409577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.306488ms","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-31T00:51:37.409641Z","caller":"traceutil/trace.go:171","msg":"trace[1440170089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:33520; }","duration":"144.39521ms","start":"2026-07-31T00:51:37.265229Z","end":"2026-07-31T00:51:37.409625Z","steps":["trace[1440170089] 'agreement among raft nodes before linearized reading' (duration: 143.720396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:43.57131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.287653ms","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-31T00:51:43.571374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.897847ms","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-31T00:51:43.571431Z","caller":"traceutil/trace.go:171","msg":"trace[25203175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:33568; }","duration":"128.453416ms","start":"2026-07-31T00:51:43.442951Z","end":"2026-07-31T00:51:43.571405Z","steps":["trace[25203175] 'range keys from in-memory index tree' (duration: 128.027967ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:43.571462Z","caller":"traceutil/trace.go:171","msg":"trace[1059677710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33568; }","duration":"136.05146ms","start":"2026-07-31T00:51:43.435387Z","end":"2026-07-31T00:51:43.571439Z","steps":["trace[1059677710] 'range keys from in-memory index tree' (duration: 135.564841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:43.571826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.02006ms","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-31T00:51:43.571986Z","caller":"traceutil/trace.go:171","msg":"trace[1954716722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:33568; }","duration":"133.238584ms","start":"2026-07-31T00:51:43.438723Z","end":"2026-07-31T00:51:43.571962Z","steps":["trace[1954716722] 'range keys from in-memory index tree' (duration: 132.471098ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:43.5774Z","caller":"traceutil/trace.go:171","msg":"trace[66501659] transaction","detail":"{read_only:false; response_revision:33569; number_of_response:1; }","duration":"134.04378ms","start":"2026-07-31T00:51:43.44333Z","end":"2026-07-31T00:51:43.577374Z","steps":["trace[66501659] 'process raft request' (duration: 133.383107ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:43.853942Z","caller":"traceutil/trace.go:171","msg":"trace[1043165723] transaction","detail":"{read_only:false; response_revision:33571; number_of_response:1; }","duration":"276.630353ms","start":"2026-07-31T00:51:43.577274Z","end":"2026-07-31T00:51:43.853905Z","steps":["trace[1043165723] 'process raft request' (duration: 248.663384ms)","trace[1043165723] 'compare' (duration: 27.598831ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:43.854395Z","caller":"traceutil/trace.go:171","msg":"trace[916717684] transaction","detail":"{read_only:false; response_revision:33572; number_of_response:1; }","duration":"276.703445ms","start":"2026-07-31T00:51:43.577607Z","end":"2026-07-31T00:51:43.85431Z","steps":["trace[916717684] 'process raft request' (duration: 276.236644ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:43.854719Z","caller":"traceutil/trace.go:171","msg":"trace[825000024] transaction","detail":"{read_only:false; response_revision:33573; number_of_response:1; }","duration":"271.831015ms","start":"2026-07-31T00:51:43.58287Z","end":"2026-07-31T00:51:43.854701Z","steps":["trace[825000024] 'process raft request' (duration: 271.255524ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:43.93194Z","caller":"traceutil/trace.go:171","msg":"trace[1844583970] transaction","detail":"{read_only:false; response_revision:33574; number_of_response:1; }","duration":"278.425899ms","start":"2026-07-31T00:51:43.653489Z","end":"2026-07-31T00:51:43.931915Z","steps":["trace[1844583970] 'process raft request' (duration: 278.264226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:44.412674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.065375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:51:44.413033Z","caller":"traceutil/trace.go:171","msg":"trace[1855868870] transaction","detail":"{read_only:false; response_revision:33575; number_of_response:1; }","duration":"557.809239ms","start":"2026-07-31T00:51:43.855192Z","end":"2026-07-31T00:51:44.413001Z","steps":["trace[1855868870] 'process raft request' (duration: 301.284146ms)","trace[1855868870] 'compare' (duration: 255.906831ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:44.414517Z","caller":"traceutil/trace.go:171","msg":"trace[1311091369] transaction","detail":"{read_only:false; response_revision:33577; number_of_response:1; }","duration":"259.23505ms","start":"2026-07-31T00:51:44.155232Z","end":"2026-07-31T00:51:44.414467Z","steps":["trace[1311091369] 'process raft request' (duration: 258.506464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:44.413801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:43.855167Z","time spent":"558.451352ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:51:44.415534Z","caller":"traceutil/trace.go:171","msg":"trace[956252507] transaction","detail":"{read_only:false; response_revision:33576; number_of_response:1; }","duration":"417.746137ms","start":"2026-07-31T00:51:43.997735Z","end":"2026-07-31T00:51:44.415482Z","steps":["trace[956252507] 'process raft request' (duration: 415.080813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:44.415646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:43.997697Z","time spent":"417.88893ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:51:44.425456Z","caller":"traceutil/trace.go:171","msg":"trace[425281655] linearizableReadLoop","detail":"{readStateIndex:34562; appliedIndex:34561; }","duration":"209.088637ms","start":"2026-07-31T00:51:44.216308Z","end":"2026-07-31T00:51:44.425396Z","steps":["trace[425281655] 'read index received' (duration: 208.257971ms)","trace[425281655] 'applied index is now lower than readState.Index' (duration: 826.536µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:44.425559Z","caller":"traceutil/trace.go:171","msg":"trace[43878639] transaction","detail":"{read_only:false; response_revision:33578; number_of_response:1; }","duration":"236.623589ms","start":"2026-07-31T00:51:44.188892Z","end":"2026-07-31T00:51:44.425516Z","steps":["trace[43878639] 'process raft request' (duration: 235.697959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:44.426353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.011656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:51:44.426424Z","caller":"traceutil/trace.go:171","msg":"trace[2012137788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33578; }","duration":"210.146059ms","start":"2026-07-31T00:51:44.216259Z","end":"2026-07-31T00:51:44.426405Z","steps":["trace[2012137788] 'agreement among raft nodes before linearized reading' (duration: 209.982236ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:45.261566Z","caller":"traceutil/trace.go:171","msg":"trace[1992171696] linearizableReadLoop","detail":"{readStateIndex:34565; appliedIndex:34562; }","duration":"291.147539ms","start":"2026-07-31T00:51:44.970385Z","end":"2026-07-31T00:51:45.261533Z","steps":["trace[1992171696] 'read index received' (duration: 23.417767ms)","trace[1992171696] 'applied index is now lower than readState.Index' (duration: 267.728642ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:51:45.261937Z","caller":"traceutil/trace.go:171","msg":"trace[1538039862] transaction","detail":"{read_only:false; response_revision:33581; number_of_response:1; }","duration":"335.460382ms","start":"2026-07-31T00:51:44.926444Z","end":"2026-07-31T00:51:45.261904Z","steps":["trace[1538039862] 'process raft request' (duration: 335.005252ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:45.262077Z","caller":"traceutil/trace.go:171","msg":"trace[1000983873] transaction","detail":"{read_only:false; response_revision:33580; number_of_response:1; }","duration":"335.8502ms","start":"2026-07-31T00:51:44.926204Z","end":"2026-07-31T00:51:45.262054Z","steps":["trace[1000983873] 'process raft request' (duration: 325.329525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:45.262086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.68664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-07-31T00:51:45.262127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:44.926437Z","time spent":"335.595254ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:51:45.262141Z","caller":"traceutil/trace.go:171","msg":"trace[403911277] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33581; }","duration":"291.794082ms","start":"2026-07-31T00:51:44.970325Z","end":"2026-07-31T00:51:45.262119Z","steps":["trace[403911277] 'agreement among raft nodes before linearized reading' (duration: 291.622138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:45.262154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:51:44.92617Z","time spent":"335.941441ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:51:45.285274Z","caller":"traceutil/trace.go:171","msg":"trace[923647126] transaction","detail":"{read_only:false; response_revision:33582; number_of_response:1; }","duration":"224.218106ms","start":"2026-07-31T00:51:45.061015Z","end":"2026-07-31T00:51:45.285233Z","steps":["trace[923647126] 'process raft request' (duration: 224.002702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:45.335676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.330754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-31T00:51:45.335741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.47821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-31T00:51:45.3358Z","caller":"traceutil/trace.go:171","msg":"trace[767083345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33584; }","duration":"269.496478ms","start":"2026-07-31T00:51:45.06627Z","end":"2026-07-31T00:51:45.335767Z","steps":["trace[767083345] 'agreement among raft nodes before linearized reading' (duration: 269.175522ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:51:45.335871Z","caller":"traceutil/trace.go:171","msg":"trace[614160357] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:33584; }","duration":"168.588443ms","start":"2026-07-31T00:51:45.167216Z","end":"2026-07-31T00:51:45.335804Z","steps":["trace[614160357] 'agreement among raft nodes before linearized reading' (duration: 168.354878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:45.335915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.733887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:511"} {"level":"info","ts":"2026-07-31T00:51:45.33598Z","caller":"traceutil/trace.go:171","msg":"trace[1281103043] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33584; }","duration":"169.826279ms","start":"2026-07-31T00:51:45.166132Z","end":"2026-07-31T00:51:45.335958Z","steps":["trace[1281103043] 'agreement among raft nodes before linearized reading' (duration: 169.471301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:51:45.336107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.872429ms","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-31T00:51:45.336206Z","caller":"traceutil/trace.go:171","msg":"trace[692513233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33584; }","duration":"223.030943ms","start":"2026-07-31T00:51:45.113154Z","end":"2026-07-31T00:51:45.336185Z","steps":["trace[692513233] 'agreement among raft nodes before linearized reading' (duration: 222.716746ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:09.028935Z","caller":"traceutil/trace.go:171","msg":"trace[472963411] transaction","detail":"{read_only:false; response_revision:33790; number_of_response:1; }","duration":"122.105566ms","start":"2026-07-31T00:52:08.906803Z","end":"2026-07-31T00:52:09.028909Z","steps":["trace[472963411] 'process raft request' (duration: 121.907602ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:09.564363Z","caller":"traceutil/trace.go:171","msg":"trace[925028904] transaction","detail":"{read_only:false; response_revision:33795; number_of_response:1; }","duration":"149.951973ms","start":"2026-07-31T00:52:09.414362Z","end":"2026-07-31T00:52:09.564314Z","steps":["trace[925028904] 'process raft request' (duration: 149.522825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:10.338279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.81441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:10.33852Z","caller":"traceutil/trace.go:171","msg":"trace[903216014] linearizableReadLoop","detail":"{readStateIndex:34792; appliedIndex:34790; }","duration":"173.071604ms","start":"2026-07-31T00:52:10.165425Z","end":"2026-07-31T00:52:10.338497Z","steps":["trace[903216014] 'read index received' (duration: 52.928028ms)","trace[903216014] 'applied index is now lower than readState.Index' (duration: 120.142226ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:10.338674Z","caller":"traceutil/trace.go:171","msg":"trace[273346951] transaction","detail":"{read_only:false; response_revision:33802; number_of_response:1; }","duration":"253.160286ms","start":"2026-07-31T00:52:10.085501Z","end":"2026-07-31T00:52:10.338661Z","steps":["trace[273346951] 'process raft request' (duration: 252.934261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:10.33899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.476984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:52:10.339059Z","caller":"traceutil/trace.go:171","msg":"trace[983499236] transaction","detail":"{read_only:false; response_revision:33801; number_of_response:1; }","duration":"254.533223ms","start":"2026-07-31T00:52:10.084513Z","end":"2026-07-31T00:52:10.339046Z","steps":["trace[983499236] 'process raft request' (duration: 133.821615ms)","trace[983499236] 'compare' (duration: 119.624835ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:10.339124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.701168ms","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-31T00:52:10.339063Z","caller":"traceutil/trace.go:171","msg":"trace[2116810935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33802; }","duration":"119.793089ms","start":"2026-07-31T00:52:10.219253Z","end":"2026-07-31T00:52:10.339046Z","steps":["trace[2116810935] 'agreement among raft nodes before linearized reading' (duration: 119.463433ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:10.339157Z","caller":"traceutil/trace.go:171","msg":"trace[1336703460] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:33802; }","duration":"173.786169ms","start":"2026-07-31T00:52:10.16536Z","end":"2026-07-31T00:52:10.339146Z","steps":["trace[1336703460] 'agreement among raft nodes before linearized reading' (duration: 173.729008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:10.631364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.250977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:10.631537Z","caller":"traceutil/trace.go:171","msg":"trace[1710979195] transaction","detail":"{read_only:false; response_revision:33804; number_of_response:1; }","duration":"242.951466ms","start":"2026-07-31T00:52:10.388548Z","end":"2026-07-31T00:52:10.631499Z","steps":["trace[1710979195] 'process raft request' (duration: 91.673926ms)","trace[1710979195] 'compare' (duration: 142.091203ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:10.631626Z","caller":"traceutil/trace.go:171","msg":"trace[1039117431] linearizableReadLoop","detail":"{readStateIndex:34794; appliedIndex:34793; }","duration":"132.882505ms","start":"2026-07-31T00:52:10.498706Z","end":"2026-07-31T00:52:10.631588Z","steps":["trace[1039117431] 'read index received' (duration: 59.951µs)","trace[1039117431] 'applied index is now lower than readState.Index' (duration: 132.819244ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:10.631859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.152141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-07-31T00:52:10.631908Z","caller":"traceutil/trace.go:171","msg":"trace[607004438] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:33805; }","duration":"133.213322ms","start":"2026-07-31T00:52:10.498684Z","end":"2026-07-31T00:52:10.631897Z","steps":["trace[607004438] 'agreement among raft nodes before linearized reading' (duration: 133.029829ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:10.842055Z","caller":"traceutil/trace.go:171","msg":"trace[781100526] linearizableReadLoop","detail":"{readStateIndex:34796; appliedIndex:34795; }","duration":"210.322513ms","start":"2026-07-31T00:52:10.631693Z","end":"2026-07-31T00:52:10.842016Z","steps":["trace[781100526] 'read index received' (duration: 178.917414ms)","trace[781100526] 'applied index is now lower than readState.Index' (duration: 31.403839ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:10.842088Z","caller":"traceutil/trace.go:171","msg":"trace[17419624] transaction","detail":"{read_only:false; response_revision:33806; number_of_response:1; }","duration":"282.304678ms","start":"2026-07-31T00:52:10.559742Z","end":"2026-07-31T00:52:10.842047Z","steps":["trace[17419624] 'process raft request' (duration: 250.879248ms)","trace[17419624] 'compare' (duration: 31.275037ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:10.842219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.651297ms","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-31T00:52:10.842246Z","caller":"traceutil/trace.go:171","msg":"trace[21098018] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:33806; }","duration":"234.72708ms","start":"2026-07-31T00:52:10.607512Z","end":"2026-07-31T00:52:10.842239Z","steps":["trace[21098018] 'agreement among raft nodes before linearized reading' (duration: 234.647828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:10.842805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.590691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-pfstv.18c738b08b66799c\" ","response":"range_response_count:1 size:949"} {"level":"warn","ts":"2026-07-31T00:52:10.842767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.191742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129357"} {"level":"info","ts":"2026-07-31T00:52:10.842841Z","caller":"traceutil/trace.go:171","msg":"trace[606983252] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-pfstv.18c738b08b66799c; range_end:; response_count:1; response_revision:33806; }","duration":"160.673432ms","start":"2026-07-31T00:52:10.682159Z","end":"2026-07-31T00:52:10.842832Z","steps":["trace[606983252] 'agreement among raft nodes before linearized reading' (duration: 160.522339ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:10.842879Z","caller":"traceutil/trace.go:171","msg":"trace[93075096] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:33806; }","duration":"324.358725ms","start":"2026-07-31T00:52:10.518495Z","end":"2026-07-31T00:52:10.842854Z","steps":["trace[93075096] 'agreement among raft nodes before linearized reading' (duration: 323.842175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:10.842939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:10.518485Z","time spent":"324.443787ms","remote":"127.0.0.1:52846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129378,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-31T00:52:10.842924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.974426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:66 size:84848"} {"level":"info","ts":"2026-07-31T00:52:10.843007Z","caller":"traceutil/trace.go:171","msg":"trace[2016554147] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:66; response_revision:33806; }","duration":"209.069848ms","start":"2026-07-31T00:52:10.633918Z","end":"2026-07-31T00:52:10.842988Z","steps":["trace[2016554147] 'agreement among raft nodes before linearized reading' (duration: 208.338822ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:11.630853Z","caller":"traceutil/trace.go:171","msg":"trace[702600374] transaction","detail":"{read_only:false; response_revision:33810; number_of_response:1; }","duration":"209.339273ms","start":"2026-07-31T00:52:11.421478Z","end":"2026-07-31T00:52:11.630817Z","steps":["trace[702600374] 'process raft request' (duration: 169.558164ms)","trace[702600374] 'compare' (duration: 39.534945ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:11.631624Z","caller":"traceutil/trace.go:171","msg":"trace[83566434] transaction","detail":"{read_only:false; response_revision:33811; number_of_response:1; }","duration":"207.676719ms","start":"2026-07-31T00:52:11.423872Z","end":"2026-07-31T00:52:11.631549Z","steps":["trace[83566434] 'process raft request' (duration: 206.885613ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:11.822187Z","caller":"traceutil/trace.go:171","msg":"trace[606136939] linearizableReadLoop","detail":"{readStateIndex:34805; appliedIndex:34803; }","duration":"124.041726ms","start":"2026-07-31T00:52:11.698114Z","end":"2026-07-31T00:52:11.822156Z","steps":["trace[606136939] 'read index received' (duration: 60.996662ms)","trace[606136939] 'applied index is now lower than readState.Index' (duration: 63.043544ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:11.822181Z","caller":"traceutil/trace.go:171","msg":"trace[690307704] transaction","detail":"{read_only:false; response_revision:33814; number_of_response:1; }","duration":"125.27905ms","start":"2026-07-31T00:52:11.696866Z","end":"2026-07-31T00:52:11.822145Z","steps":["trace[690307704] 'process raft request' (duration: 62.267178ms)","trace[690307704] 'compare' (duration: 62.711316ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:11.822244Z","caller":"traceutil/trace.go:171","msg":"trace[291379071] transaction","detail":"{read_only:false; response_revision:33815; number_of_response:1; }","duration":"124.283111ms","start":"2026-07-31T00:52:11.697934Z","end":"2026-07-31T00:52:11.822217Z","steps":["trace[291379071] 'process raft request' (duration: 124.126608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:11.822314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.190739ms","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-31T00:52:11.822363Z","caller":"traceutil/trace.go:171","msg":"trace[1717146907] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:33815; }","duration":"124.29763ms","start":"2026-07-31T00:52:11.698054Z","end":"2026-07-31T00:52:11.822352Z","steps":["trace[1717146907] 'agreement among raft nodes before linearized reading' (duration: 124.199149ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:12.037087Z","caller":"traceutil/trace.go:171","msg":"trace[108730200] transaction","detail":"{read_only:false; response_revision:33816; number_of_response:1; }","duration":"143.691286ms","start":"2026-07-31T00:52:11.893349Z","end":"2026-07-31T00:52:12.03704Z","steps":["trace[108730200] 'process raft request' (duration: 71.579217ms)","trace[108730200] 'compare' (duration: 71.66007ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:12.216324Z","caller":"traceutil/trace.go:171","msg":"trace[1636351383] linearizableReadLoop","detail":"{readStateIndex:34807; appliedIndex:34806; }","duration":"105.336115ms","start":"2026-07-31T00:52:12.110957Z","end":"2026-07-31T00:52:12.216293Z","steps":["trace[1636351383] 'read index received' (duration: 91.028514ms)","trace[1636351383] 'applied index is now lower than readState.Index' (duration: 14.306021ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:12.21647Z","caller":"traceutil/trace.go:171","msg":"trace[1957399692] transaction","detail":"{read_only:false; response_revision:33817; number_of_response:1; }","duration":"131.380665ms","start":"2026-07-31T00:52:12.085075Z","end":"2026-07-31T00:52:12.216456Z","steps":["trace[1957399692] 'process raft request' (duration: 116.952742ms)","trace[1957399692] 'compare' (duration: 14.113587ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:12.216801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.813225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-07-31T00:52:12.216909Z","caller":"traceutil/trace.go:171","msg":"trace[1288796607] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33817; }","duration":"105.943168ms","start":"2026-07-31T00:52:12.11094Z","end":"2026-07-31T00:52:12.216883Z","steps":["trace[1288796607] 'agreement among raft nodes before linearized reading' (duration: 105.584751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:17.7423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.626428ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T00:52:17.742763Z","caller":"traceutil/trace.go:171","msg":"trace[380246269] transaction","detail":"{read_only:false; response_revision:33862; number_of_response:1; }","duration":"236.731221ms","start":"2026-07-31T00:52:17.505999Z","end":"2026-07-31T00:52:17.742731Z","steps":["trace[380246269] 'process raft request' (duration: 236.438385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:17.888961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.693555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:17.889117Z","caller":"traceutil/trace.go:171","msg":"trace[25626380] transaction","detail":"{read_only:false; response_revision:33863; number_of_response:1; }","duration":"238.276172ms","start":"2026-07-31T00:52:17.650813Z","end":"2026-07-31T00:52:17.889089Z","steps":["trace[25626380] 'process raft request' (duration: 96.316331ms)","trace[25626380] 'compare' (duration: 141.461211ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:17.900261Z","caller":"traceutil/trace.go:171","msg":"trace[10843444] transaction","detail":"{read_only:false; response_revision:33865; number_of_response:1; }","duration":"243.678092ms","start":"2026-07-31T00:52:17.656551Z","end":"2026-07-31T00:52:17.900229Z","steps":["trace[10843444] 'process raft request' (duration: 243.57324ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:17.900471Z","caller":"traceutil/trace.go:171","msg":"trace[1037142128] transaction","detail":"{read_only:false; response_revision:33864; number_of_response:1; }","duration":"247.853597ms","start":"2026-07-31T00:52:17.652578Z","end":"2026-07-31T00:52:17.900432Z","steps":["trace[1037142128] 'process raft request' (duration: 247.393988ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:17.90059Z","caller":"traceutil/trace.go:171","msg":"trace[444574353] transaction","detail":"{read_only:false; response_revision:33866; number_of_response:1; }","duration":"228.245308ms","start":"2026-07-31T00:52:17.672329Z","end":"2026-07-31T00:52:17.900574Z","steps":["trace[444574353] 'process raft request' (duration: 227.84646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:17.900975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.817704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-31T00:52:17.901081Z","caller":"traceutil/trace.go:171","msg":"trace[381988506] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:33866; }","duration":"184.954547ms","start":"2026-07-31T00:52:17.716099Z","end":"2026-07-31T00:52:17.901053Z","steps":["trace[381988506] 'agreement among raft nodes before linearized reading' (duration: 184.693741ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:17.900586Z","caller":"traceutil/trace.go:171","msg":"trace[1560326042] linearizableReadLoop","detail":"{readStateIndex:34858; appliedIndex:34854; }","duration":"184.338894ms","start":"2026-07-31T00:52:17.716134Z","end":"2026-07-31T00:52:17.900473Z","steps":["trace[1560326042] 'read index received' (duration: 30.993971ms)","trace[1560326042] 'applied index is now lower than readState.Index' (duration: 153.341373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:18.171971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.925098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:18.17226Z","caller":"traceutil/trace.go:171","msg":"trace[918018358] linearizableReadLoop","detail":"{readStateIndex:34861; appliedIndex:34858; }","duration":"109.259745ms","start":"2026-07-31T00:52:18.062984Z","end":"2026-07-31T00:52:18.172244Z","steps":["trace[918018358] 'read index received' (duration: 106.382206ms)","trace[918018358] 'applied index is now lower than readState.Index' (duration: 2.876649ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:18.17227Z","caller":"traceutil/trace.go:171","msg":"trace[1947265977] transaction","detail":"{read_only:false; response_revision:33867; number_of_response:1; }","duration":"265.533968ms","start":"2026-07-31T00:52:17.906713Z","end":"2026-07-31T00:52:18.172247Z","steps":["trace[1947265977] 'process raft request' (duration: 131.254454ms)","trace[1947265977] 'compare' (duration: 133.720383ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:18.172386Z","caller":"traceutil/trace.go:171","msg":"trace[2080034839] transaction","detail":"{read_only:false; response_revision:33868; number_of_response:1; }","duration":"264.451964ms","start":"2026-07-31T00:52:17.907868Z","end":"2026-07-31T00:52:18.17232Z","steps":["trace[2080034839] 'process raft request' (duration: 264.290881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:18.17269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.708154ms","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-31T00:52:18.172769Z","caller":"traceutil/trace.go:171","msg":"trace[1610393771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33869; }","duration":"109.830416ms","start":"2026-07-31T00:52:18.062927Z","end":"2026-07-31T00:52:18.172758Z","steps":["trace[1610393771] 'agreement among raft nodes before linearized reading' (duration: 109.384357ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:18.17241Z","caller":"traceutil/trace.go:171","msg":"trace[1339553458] transaction","detail":"{read_only:false; response_revision:33869; number_of_response:1; }","duration":"264.490505ms","start":"2026-07-31T00:52:17.907882Z","end":"2026-07-31T00:52:18.172373Z","steps":["trace[1339553458] 'process raft request' (duration: 264.334962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:18.461407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.240581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:18.461528Z","caller":"traceutil/trace.go:171","msg":"trace[968857987] linearizableReadLoop","detail":"{readStateIndex:34862; appliedIndex:34861; }","duration":"245.233953ms","start":"2026-07-31T00:52:18.216271Z","end":"2026-07-31T00:52:18.461505Z","steps":["trace[968857987] 'read index received' (duration: 142.804848ms)","trace[968857987] 'applied index is now lower than readState.Index' (duration: 102.427275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:18.46164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.366156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:52:18.461673Z","caller":"traceutil/trace.go:171","msg":"trace[1994943868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33870; }","duration":"245.425907ms","start":"2026-07-31T00:52:18.216237Z","end":"2026-07-31T00:52:18.461663Z","steps":["trace[1994943868] 'agreement among raft nodes before linearized reading' (duration: 245.356776ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:18.461652Z","caller":"traceutil/trace.go:171","msg":"trace[567834691] transaction","detail":"{read_only:false; response_revision:33870; number_of_response:1; }","duration":"283.731687ms","start":"2026-07-31T00:52:18.177875Z","end":"2026-07-31T00:52:18.461607Z","steps":["trace[567834691] 'process raft request' (duration: 181.18112ms)","trace[567834691] 'compare' (duration: 102.018007ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:18.464835Z","caller":"traceutil/trace.go:171","msg":"trace[8177773] transaction","detail":"{read_only:false; response_revision:33871; number_of_response:1; }","duration":"227.438851ms","start":"2026-07-31T00:52:18.23736Z","end":"2026-07-31T00:52:18.464799Z","steps":["trace[8177773] 'process raft request' (duration: 226.912771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:18.811842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.209063ms","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-31T00:52:18.811971Z","caller":"traceutil/trace.go:171","msg":"trace[531969577] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:33873; }","duration":"245.428507ms","start":"2026-07-31T00:52:18.566515Z","end":"2026-07-31T00:52:18.811943Z","steps":["trace[531969577] 'range keys from in-memory index tree' (duration: 245.125801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:18.812128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.278693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:18.812271Z","caller":"traceutil/trace.go:171","msg":"trace[1385638632] transaction","detail":"{read_only:false; response_revision:33874; number_of_response:1; }","duration":"152.524586ms","start":"2026-07-31T00:52:18.659721Z","end":"2026-07-31T00:52:18.812245Z","steps":["trace[1385638632] 'process raft request' (duration: 20.954687ms)","trace[1385638632] 'compare' (duration: 131.112329ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:18.892996Z","caller":"traceutil/trace.go:171","msg":"trace[68738702] transaction","detail":"{read_only:false; response_revision:33875; number_of_response:1; }","duration":"176.202168ms","start":"2026-07-31T00:52:18.716761Z","end":"2026-07-31T00:52:18.892963Z","steps":["trace[68738702] 'process raft request' (duration: 175.976204ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:19.023012Z","caller":"traceutil/trace.go:171","msg":"trace[57904502] linearizableReadLoop","detail":"{readStateIndex:34868; appliedIndex:34867; }","duration":"126.564368ms","start":"2026-07-31T00:52:18.896406Z","end":"2026-07-31T00:52:19.022971Z","steps":["trace[57904502] 'read index received' (duration: 99.085408ms)","trace[57904502] 'applied index is now lower than readState.Index' (duration: 27.477739ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:19.023088Z","caller":"traceutil/trace.go:171","msg":"trace[1218079521] transaction","detail":"{read_only:false; response_revision:33876; number_of_response:1; }","duration":"152.991556ms","start":"2026-07-31T00:52:18.870043Z","end":"2026-07-31T00:52:19.023034Z","steps":["trace[1218079521] 'process raft request' (duration: 125.456045ms)","trace[1218079521] 'compare' (duration: 27.304856ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:19.02331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.875614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:52:19.02336Z","caller":"traceutil/trace.go:171","msg":"trace[1426137329] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33876; }","duration":"126.984636ms","start":"2026-07-31T00:52:18.896362Z","end":"2026-07-31T00:52:19.023346Z","steps":["trace[1426137329] 'agreement among raft nodes before linearized reading' (duration: 126.752511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:19.273488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.468971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:19.273614Z","caller":"traceutil/trace.go:171","msg":"trace[1285644713] linearizableReadLoop","detail":"{readStateIndex:34869; appliedIndex:34868; }","duration":"194.349277ms","start":"2026-07-31T00:52:19.079244Z","end":"2026-07-31T00:52:19.273593Z","steps":["trace[1285644713] 'read index received' (duration: 79.673362ms)","trace[1285644713] 'applied index is now lower than readState.Index' (duration: 114.674255ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:19.273683Z","caller":"traceutil/trace.go:171","msg":"trace[1707454605] transaction","detail":"{read_only:false; response_revision:33877; number_of_response:1; }","duration":"224.341738ms","start":"2026-07-31T00:52:19.049309Z","end":"2026-07-31T00:52:19.273651Z","steps":["trace[1707454605] 'process raft request' (duration: 109.626082ms)","trace[1707454605] 'compare' (duration: 114.267407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:19.273737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.49931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:52:19.273772Z","caller":"traceutil/trace.go:171","msg":"trace[65418025] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:33877; }","duration":"194.583212ms","start":"2026-07-31T00:52:19.079177Z","end":"2026-07-31T00:52:19.27376Z","steps":["trace[65418025] 'agreement among raft nodes before linearized reading' (duration: 194.51758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:19.924131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.202549ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T00:52:19.938875Z","caller":"traceutil/trace.go:171","msg":"trace[934463770] transaction","detail":"{read_only:false; response_revision:33880; number_of_response:1; }","duration":"188.638622ms","start":"2026-07-31T00:52:19.750212Z","end":"2026-07-31T00:52:19.93885Z","steps":["trace[934463770] 'process raft request' (duration: 188.49563ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:20.29123Z","caller":"traceutil/trace.go:171","msg":"trace[1443584483] transaction","detail":"{read_only:false; response_revision:33881; number_of_response:1; }","duration":"395.586406ms","start":"2026-07-31T00:52:19.895619Z","end":"2026-07-31T00:52:20.291205Z","steps":["trace[1443584483] 'process raft request' (duration: 395.372612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:20.29138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:19.895588Z","time spent":"395.715939ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:20.291557Z","caller":"traceutil/trace.go:171","msg":"trace[1525109646] transaction","detail":"{read_only:false; response_revision:33882; number_of_response:1; }","duration":"384.267796ms","start":"2026-07-31T00:52:19.907272Z","end":"2026-07-31T00:52:20.29154Z","steps":["trace[1525109646] 'process raft request' (duration: 383.893717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:20.291624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:19.907259Z","time spent":"384.329938ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:20.291685Z","caller":"traceutil/trace.go:171","msg":"trace[1254618500] transaction","detail":"{read_only:false; response_revision:33883; number_of_response:1; }","duration":"383.639852ms","start":"2026-07-31T00:52:19.908039Z","end":"2026-07-31T00:52:20.291679Z","steps":["trace[1254618500] 'process raft request' (duration: 383.434268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:20.291719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:19.907997Z","time spent":"383.704664ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:20.291736Z","caller":"traceutil/trace.go:171","msg":"trace[1639211704] linearizableReadLoop","detail":"{readStateIndex:34875; appliedIndex:34872; }","duration":"322.170531ms","start":"2026-07-31T00:52:19.969524Z","end":"2026-07-31T00:52:20.291694Z","steps":["trace[1639211704] 'read index received' (duration: 321.387944ms)","trace[1639211704] 'applied index is now lower than readState.Index' (duration: 780.587µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:20.291975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.425106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T00:52:20.29203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.093163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T00:52:20.292057Z","caller":"traceutil/trace.go:171","msg":"trace[623891782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33883; }","duration":"115.127454ms","start":"2026-07-31T00:52:20.176922Z","end":"2026-07-31T00:52:20.29205Z","steps":["trace[623891782] 'agreement among raft nodes before linearized reading' (duration: 115.038522ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:20.29207Z","caller":"traceutil/trace.go:171","msg":"trace[303683038] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:33883; }","duration":"322.59203ms","start":"2026-07-31T00:52:19.969455Z","end":"2026-07-31T00:52:20.292047Z","steps":["trace[303683038] 'agreement among raft nodes before linearized reading' (duration: 322.371845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:20.29212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:19.969432Z","time spent":"322.677111ms","remote":"127.0.0.1:58946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-31T00:52:20.292194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.700897ms","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-31T00:52:20.292199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.343379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-31T00:52:20.292267Z","caller":"traceutil/trace.go:171","msg":"trace[916165511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33883; }","duration":"115.83196ms","start":"2026-07-31T00:52:20.17641Z","end":"2026-07-31T00:52:20.292242Z","steps":["trace[916165511] 'agreement among raft nodes before linearized reading' (duration: 115.535804ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:20.29229Z","caller":"traceutil/trace.go:171","msg":"trace[466139454] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:33883; }","duration":"115.453841ms","start":"2026-07-31T00:52:20.176811Z","end":"2026-07-31T00:52:20.292265Z","steps":["trace[466139454] 'agreement among raft nodes before linearized reading' (duration: 115.205496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:20.436631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.825944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:20.436952Z","caller":"traceutil/trace.go:171","msg":"trace[1530789822] transaction","detail":"{read_only:false; response_revision:33884; number_of_response:1; }","duration":"139.873167ms","start":"2026-07-31T00:52:20.29704Z","end":"2026-07-31T00:52:20.436913Z","steps":["trace[1530789822] 'process raft request' (duration: 33.612254ms)","trace[1530789822] 'compare' (duration: 105.533738ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:20.437247Z","caller":"traceutil/trace.go:171","msg":"trace[1534584311] transaction","detail":"{read_only:false; response_revision:33885; number_of_response:1; }","duration":"139.181695ms","start":"2026-07-31T00:52:20.298008Z","end":"2026-07-31T00:52:20.437189Z","steps":["trace[1534584311] 'process raft request' (duration: 138.802736ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:20.437289Z","caller":"traceutil/trace.go:171","msg":"trace[670191480] transaction","detail":"{read_only:false; response_revision:33886; number_of_response:1; }","duration":"139.093023ms","start":"2026-07-31T00:52:20.298167Z","end":"2026-07-31T00:52:20.43726Z","steps":["trace[670191480] 'process raft request' (duration: 138.726965ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.566318Z","caller":"traceutil/trace.go:171","msg":"trace[1042845171] transaction","detail":"{read_only:false; response_revision:33952; number_of_response:1; }","duration":"239.330983ms","start":"2026-07-31T00:52:28.326953Z","end":"2026-07-31T00:52:28.566284Z","steps":["trace[1042845171] 'process raft request' (duration: 201.504143ms)","trace[1042845171] 'compare' (duration: 37.550115ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:28.566398Z","caller":"traceutil/trace.go:171","msg":"trace[1724772511] transaction","detail":"{read_only:false; response_revision:33953; number_of_response:1; }","duration":"239.18284ms","start":"2026-07-31T00:52:28.327179Z","end":"2026-07-31T00:52:28.566362Z","steps":["trace[1724772511] 'process raft request' (duration: 239.018267ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.566343Z","caller":"traceutil/trace.go:171","msg":"trace[1944302244] linearizableReadLoop","detail":"{readStateIndex:34947; appliedIndex:34944; }","duration":"203.285179ms","start":"2026-07-31T00:52:28.363032Z","end":"2026-07-31T00:52:28.566318Z","steps":["trace[1944302244] 'read index received' (duration: 45.613998ms)","trace[1944302244] 'applied index is now lower than readState.Index' (duration: 157.669891ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:28.566668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.617577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:52:28.566859Z","caller":"traceutil/trace.go:171","msg":"trace[1892935335] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:33953; }","duration":"203.868322ms","start":"2026-07-31T00:52:28.362961Z","end":"2026-07-31T00:52:28.56683Z","steps":["trace[1892935335] 'agreement among raft nodes before linearized reading' (duration: 203.625868ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.735177Z","caller":"traceutil/trace.go:171","msg":"trace[1876915767] linearizableReadLoop","detail":"{readStateIndex:34948; appliedIndex:34947; }","duration":"168.355879ms","start":"2026-07-31T00:52:28.566787Z","end":"2026-07-31T00:52:28.735143Z","steps":["trace[1876915767] 'read index received' (duration: 104.379516ms)","trace[1876915767] 'applied index is now lower than readState.Index' (duration: 63.975143ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:28.73563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.933124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-31T00:52:28.73563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.234982ms","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-31T00:52:28.735704Z","caller":"traceutil/trace.go:171","msg":"trace[395351124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33954; }","duration":"266.371155ms","start":"2026-07-31T00:52:28.469317Z","end":"2026-07-31T00:52:28.735689Z","steps":["trace[395351124] 'agreement among raft nodes before linearized reading' (duration: 266.085439ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.735696Z","caller":"traceutil/trace.go:171","msg":"trace[2004145328] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:33954; }","duration":"260.076226ms","start":"2026-07-31T00:52:28.4756Z","end":"2026-07-31T00:52:28.735677Z","steps":["trace[2004145328] 'agreement among raft nodes before linearized reading' (duration: 259.75216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:28.735704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.793383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T00:52:28.735755Z","caller":"traceutil/trace.go:171","msg":"trace[421457395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33954; }","duration":"265.864304ms","start":"2026-07-31T00:52:28.469876Z","end":"2026-07-31T00:52:28.735741Z","steps":["trace[421457395] 'agreement among raft nodes before linearized reading' (duration: 265.712101ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.735727Z","caller":"traceutil/trace.go:171","msg":"trace[1677719359] transaction","detail":"{read_only:false; response_revision:33954; number_of_response:1; }","duration":"191.437169ms","start":"2026-07-31T00:52:28.54425Z","end":"2026-07-31T00:52:28.735688Z","steps":["trace[1677719359] 'process raft request' (duration: 126.864893ms)","trace[1677719359] 'compare' (duration: 63.879892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:28.735584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.544549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T00:52:28.735905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.55733ms","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-31T00:52:28.735915Z","caller":"traceutil/trace.go:171","msg":"trace[518328820] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:33954; }","duration":"188.930867ms","start":"2026-07-31T00:52:28.546928Z","end":"2026-07-31T00:52:28.735859Z","steps":["trace[518328820] 'agreement among raft nodes before linearized reading' (duration: 188.373116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:28.735951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.462644ms","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-31T00:52:28.735961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.46967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-5cb7b7bd47-7886z.18c737a2905be426\" ","response":"range_response_count:1 size:1180"} {"level":"info","ts":"2026-07-31T00:52:28.735997Z","caller":"traceutil/trace.go:171","msg":"trace[1392989576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33954; }","duration":"163.685933ms","start":"2026-07-31T00:52:28.572277Z","end":"2026-07-31T00:52:28.735963Z","steps":["trace[1392989576] 'agreement among raft nodes before linearized reading' (duration: 163.446698ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.736035Z","caller":"traceutil/trace.go:171","msg":"trace[1366917364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:33954; }","duration":"122.594087ms","start":"2026-07-31T00:52:28.613421Z","end":"2026-07-31T00:52:28.736015Z","steps":["trace[1366917364] 'agreement among raft nodes before linearized reading' (duration: 122.342072ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:28.73605Z","caller":"traceutil/trace.go:171","msg":"trace[960098063] range","detail":"{range_begin:/registry/events/openstack/cinder-api-5cb7b7bd47-7886z.18c737a2905be426; range_end:; response_count:1; response_revision:33954; }","duration":"112.572962ms","start":"2026-07-31T00:52:28.623453Z","end":"2026-07-31T00:52:28.736026Z","steps":["trace[960098063] 'agreement among raft nodes before linearized reading' (duration: 112.288627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:28.735961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.559565ms","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-31T00:52:28.736121Z","caller":"traceutil/trace.go:171","msg":"trace[363658054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:33954; }","duration":"119.744499ms","start":"2026-07-31T00:52:28.616359Z","end":"2026-07-31T00:52:28.736103Z","steps":["trace[363658054] 'agreement among raft nodes before linearized reading' (duration: 119.402232ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.038596Z","caller":"traceutil/trace.go:171","msg":"trace[1174211903] transaction","detail":"{read_only:false; response_revision:33955; number_of_response:1; }","duration":"299.817375ms","start":"2026-07-31T00:52:28.738754Z","end":"2026-07-31T00:52:29.038571Z","steps":["trace[1174211903] 'process raft request' (duration: 299.593981ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.339201Z","caller":"traceutil/trace.go:171","msg":"trace[83564413] transaction","detail":"{read_only:false; response_revision:33957; number_of_response:1; }","duration":"598.1512ms","start":"2026-07-31T00:52:28.741023Z","end":"2026-07-31T00:52:29.339174Z","steps":["trace[83564413] 'process raft request' (duration: 598.058648ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.339222Z","caller":"traceutil/trace.go:171","msg":"trace[219388719] transaction","detail":"{read_only:false; response_revision:33956; number_of_response:1; }","duration":"598.564838ms","start":"2026-07-31T00:52:28.740613Z","end":"2026-07-31T00:52:29.339178Z","steps":["trace[219388719] 'process raft request' (duration: 598.189391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.339368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.741013Z","time spent":"598.268052ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:52:29.339433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.740565Z","time spent":"598.747502ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:29.339631Z","caller":"traceutil/trace.go:171","msg":"trace[1765648665] transaction","detail":"{read_only:false; response_revision:33958; number_of_response:1; }","duration":"598.448996ms","start":"2026-07-31T00:52:28.741131Z","end":"2026-07-31T00:52:29.33958Z","steps":["trace[1765648665] 'process raft request' (duration: 598.000647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.339845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.741104Z","time spent":"598.6234ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:29.339879Z","caller":"traceutil/trace.go:171","msg":"trace[823391400] transaction","detail":"{read_only:false; response_revision:33960; number_of_response:1; }","duration":"597.960007ms","start":"2026-07-31T00:52:28.741893Z","end":"2026-07-31T00:52:29.339853Z","steps":["trace[823391400] 'process raft request' (duration: 597.835464ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.339955Z","caller":"traceutil/trace.go:171","msg":"trace[217609737] transaction","detail":"{read_only:false; response_revision:33961; number_of_response:1; }","duration":"596.916406ms","start":"2026-07-31T00:52:28.743025Z","end":"2026-07-31T00:52:29.339941Z","steps":["trace[217609737] 'process raft request' (duration: 596.783813ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.339923Z","caller":"traceutil/trace.go:171","msg":"trace[1010676303] transaction","detail":"{read_only:false; response_revision:33959; number_of_response:1; }","duration":"598.688562ms","start":"2026-07-31T00:52:28.741172Z","end":"2026-07-31T00:52:29.33986Z","steps":["trace[1010676303] 'process raft request' (duration: 598.301743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.340033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.743014Z","time spent":"596.981868ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:52:29.340028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.741862Z","time spent":"598.09314ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:29.340115Z","caller":"traceutil/trace.go:171","msg":"trace[1487811677] transaction","detail":"{read_only:false; response_revision:33962; number_of_response:1; }","duration":"486.733502ms","start":"2026-07-31T00:52:28.853365Z","end":"2026-07-31T00:52:29.340098Z","steps":["trace[1487811677] 'process raft request' (duration: 486.521407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.340209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.741147Z","time spent":"598.911546ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:52:29.340218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.85333Z","time spent":"486.838344ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:29.3403Z","caller":"traceutil/trace.go:171","msg":"trace[1296434956] transaction","detail":"{read_only:false; response_revision:33963; number_of_response:1; }","duration":"432.087279ms","start":"2026-07-31T00:52:28.908201Z","end":"2026-07-31T00:52:29.340288Z","steps":["trace[1296434956] 'process raft request' (duration: 431.842335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.340367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:28.908167Z","time spent":"432.165881ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:52:29.393887Z","caller":"traceutil/trace.go:171","msg":"trace[2043768254] linearizableReadLoop","detail":"{readStateIndex:34958; appliedIndex:34949; }","duration":"247.348177ms","start":"2026-07-31T00:52:29.146523Z","end":"2026-07-31T00:52:29.393871Z","steps":["trace[2043768254] 'read index received' (duration: 192.52699ms)","trace[2043768254] 'applied index is now lower than readState.Index' (duration: 54.820157ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:29.393967Z","caller":"traceutil/trace.go:171","msg":"trace[613760590] transaction","detail":"{read_only:false; response_revision:33964; number_of_response:1; }","duration":"335.573774ms","start":"2026-07-31T00:52:29.058364Z","end":"2026-07-31T00:52:29.393938Z","steps":["trace[613760590] 'process raft request' (duration: 335.426481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.39408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:52:29.058339Z","time spent":"335.683706ms","remote":"127.0.0.1:53084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:52:29.394134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.354095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-31T00:52:29.394169Z","caller":"traceutil/trace.go:171","msg":"trace[1751988323] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:33964; }","duration":"104.437426ms","start":"2026-07-31T00:52:29.289723Z","end":"2026-07-31T00:52:29.394161Z","steps":["trace[1751988323] 'agreement among raft nodes before linearized reading' (duration: 104.283213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.394385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.831677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12464"} {"level":"info","ts":"2026-07-31T00:52:29.394414Z","caller":"traceutil/trace.go:171","msg":"trace[961981152] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33964; }","duration":"247.956309ms","start":"2026-07-31T00:52:29.146449Z","end":"2026-07-31T00:52:29.394406Z","steps":["trace[961981152] 'agreement among raft nodes before linearized reading' (duration: 247.526041ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.518158Z","caller":"traceutil/trace.go:171","msg":"trace[1136627591] linearizableReadLoop","detail":"{readStateIndex:34959; appliedIndex:34958; }","duration":"117.014323ms","start":"2026-07-31T00:52:29.40112Z","end":"2026-07-31T00:52:29.518134Z","steps":["trace[1136627591] 'read index received' (duration: 116.933401ms)","trace[1136627591] 'applied index is now lower than readState.Index' (duration: 79.382µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:29.518292Z","caller":"traceutil/trace.go:171","msg":"trace[541990680] transaction","detail":"{read_only:false; response_revision:33965; number_of_response:1; }","duration":"118.330719ms","start":"2026-07-31T00:52:29.399918Z","end":"2026-07-31T00:52:29.518249Z","steps":["trace[541990680] 'process raft request' (duration: 118.053834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.518423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.293479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:15961"} {"level":"info","ts":"2026-07-31T00:52:29.518467Z","caller":"traceutil/trace.go:171","msg":"trace[1333327552] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:33965; }","duration":"117.3777ms","start":"2026-07-31T00:52:29.401078Z","end":"2026-07-31T00:52:29.518455Z","steps":["trace[1333327552] 'agreement among raft nodes before linearized reading' (duration: 117.159506ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.708665Z","caller":"traceutil/trace.go:171","msg":"trace[826301564] transaction","detail":"{read_only:false; response_revision:33966; number_of_response:1; }","duration":"268.149041ms","start":"2026-07-31T00:52:29.44048Z","end":"2026-07-31T00:52:29.708629Z","steps":["trace[826301564] 'process raft request' (duration: 263.061887ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.708885Z","caller":"traceutil/trace.go:171","msg":"trace[465218143] linearizableReadLoop","detail":"{readStateIndex:34962; appliedIndex:34959; }","duration":"128.037227ms","start":"2026-07-31T00:52:29.580828Z","end":"2026-07-31T00:52:29.708866Z","steps":["trace[465218143] 'read index received' (duration: 122.674117ms)","trace[465218143] 'applied index is now lower than readState.Index' (duration: 5.36173ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:29.708978Z","caller":"traceutil/trace.go:171","msg":"trace[475381358] transaction","detail":"{read_only:false; response_revision:33967; number_of_response:1; }","duration":"246.074831ms","start":"2026-07-31T00:52:29.462869Z","end":"2026-07-31T00:52:29.708943Z","steps":["trace[475381358] 'process raft request' (duration: 245.664793ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:29.709109Z","caller":"traceutil/trace.go:171","msg":"trace[1362140780] transaction","detail":"{read_only:false; response_revision:33968; number_of_response:1; }","duration":"227.653075ms","start":"2026-07-31T00:52:29.481442Z","end":"2026-07-31T00:52:29.709095Z","steps":["trace[1362140780] 'process raft request' (duration: 227.321079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:29.710609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.2187ms","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-31T00:52:29.710694Z","caller":"traceutil/trace.go:171","msg":"trace[1415423249] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:33968; }","duration":"129.922556ms","start":"2026-07-31T00:52:29.580755Z","end":"2026-07-31T00:52:29.710677Z","steps":["trace[1415423249] 'agreement among raft nodes before linearized reading' (duration: 128.204581ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:37.971797Z","caller":"traceutil/trace.go:171","msg":"trace[62944093] transaction","detail":"{read_only:false; response_revision:34035; number_of_response:1; }","duration":"235.482545ms","start":"2026-07-31T00:52:37.736282Z","end":"2026-07-31T00:52:37.971765Z","steps":["trace[62944093] 'process raft request' (duration: 235.183249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:52:38.20977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.381725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:52:38.20987Z","caller":"traceutil/trace.go:171","msg":"trace[745961375] transaction","detail":"{read_only:false; response_revision:34036; number_of_response:1; }","duration":"182.829323ms","start":"2026-07-31T00:52:38.027023Z","end":"2026-07-31T00:52:38.209852Z","steps":["trace[745961375] 'process raft request' (duration: 51.283374ms)","trace[745961375] 'compare' (duration: 131.048199ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:52:38.549399Z","caller":"traceutil/trace.go:171","msg":"trace[1072542130] transaction","detail":"{read_only:false; response_revision:34038; number_of_response:1; }","duration":"104.197832ms","start":"2026-07-31T00:52:38.445172Z","end":"2026-07-31T00:52:38.54937Z","steps":["trace[1072542130] 'process raft request' (duration: 103.991947ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:38.989395Z","caller":"traceutil/trace.go:171","msg":"trace[846447901] linearizableReadLoop","detail":"{readStateIndex:35037; appliedIndex:35036; }","duration":"174.507823ms","start":"2026-07-31T00:52:38.814851Z","end":"2026-07-31T00:52:38.989359Z","steps":["trace[846447901] 'read index received' (duration: 174.148196ms)","trace[846447901] 'applied index is now lower than readState.Index' (duration: 358.147µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:52:38.989608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.719278ms","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-31T00:52:38.989653Z","caller":"traceutil/trace.go:171","msg":"trace[794698059] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:34041; }","duration":"174.814659ms","start":"2026-07-31T00:52:38.814828Z","end":"2026-07-31T00:52:38.989642Z","steps":["trace[794698059] 'agreement among raft nodes before linearized reading' (duration: 174.658506ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:52:38.990049Z","caller":"traceutil/trace.go:171","msg":"trace[1054196857] transaction","detail":"{read_only:false; response_revision:34041; number_of_response:1; }","duration":"211.16312ms","start":"2026-07-31T00:52:38.778873Z","end":"2026-07-31T00:52:38.990036Z","steps":["trace[1054196857] 'process raft request' (duration: 210.236191ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:56:09.0819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33280} {"level":"info","ts":"2026-07-31T00:56:09.242168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33280,"took":"157.57409ms","hash":2988558363,"current-db-size-bytes":40763392,"current-db-size":"41 MB","current-db-size-in-use-bytes":22614016,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-31T00:56:09.242274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2988558363,"revision":33280,"compact-revision":29616} {"level":"info","ts":"2026-07-31T01:01:09.090021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35888} {"level":"info","ts":"2026-07-31T01:01:09.198016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35888,"took":"102.024815ms","hash":3684812234,"current-db-size-bytes":40763392,"current-db-size":"41 MB","current-db-size-in-use-bytes":21995520,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-31T01:01:09.19818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3684812234,"revision":35888,"compact-revision":33280} {"level":"info","ts":"2026-07-31T01:01:46.828113Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T01:01:46.833401Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-31T01:01:46.833544Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-31T01:06:09.10016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38567} {"level":"info","ts":"2026-07-31T01:06:09.21955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38567,"took":"115.377758ms","hash":3239132390,"current-db-size-bytes":40763392,"current-db-size":"41 MB","current-db-size-in-use-bytes":22081536,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-31T01:06:09.219625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3239132390,"revision":38567,"compact-revision":35888}