{"level":"warn","ts":"2026-07-31T21:25:26.454913Z","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-31T21:25:26.455008Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-31T21:25:26.45508Z","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-31T21:25:26.455093Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-31T21:25:26.455108Z","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-31T21:25:26.455716Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-07-31T21:25:26.455787Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-31T21:25:26.458438Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.4475ms"} {"level":"info","ts":"2026-07-31T21:25:26.464028Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-07-31T21:25:26.4642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-07-31T21:25:26.46425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-07-31T21:25:26.464273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-31T21:25:26.464298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-07-31T21:25:26.464361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-07-31T21:25:26.469701Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-31T21:25:26.471107Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-31T21:25:26.472391Z","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-31T21:25:26.47535Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-31T21:25:26.475629Z","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-31T21:25:26.475671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-31T21:25:26.475794Z","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-31T21:25:26.47694Z","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-31T21:25:26.477649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-07-31T21:25:26.477795Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-31T21:25:26.48055Z","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-31T21:25:26.480917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-31T21:25:26.480969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-31T21:25:26.481105Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-31T21:25:26.481123Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-31T21:25:27.164601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-07-31T21:25:27.164668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-31T21:25:27.164711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-07-31T21:25:27.164731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-07-31T21:25:27.164748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-31T21:25:27.164775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-07-31T21:25:27.164794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-31T21:25:27.165986Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-07-31T21:25:27.166016Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:25:27.166029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T21:25:27.166221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T21:25:27.16628Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-31T21:25:27.1663Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-31T21:25:27.167113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:25:27.167226Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:25:27.167255Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:25:27.168917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-31T21:25:27.170882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-07-31T21:26:02.575314Z","caller":"traceutil/trace.go:171","msg":"trace[1958440892] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"134.602525ms","start":"2026-07-31T21:26:02.440682Z","end":"2026-07-31T21:26:02.575284Z","steps":["trace[1958440892] 'process raft request' (duration: 134.425541ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575389Z","caller":"traceutil/trace.go:171","msg":"trace[1680661790] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"119.418093ms","start":"2026-07-31T21:26:02.455936Z","end":"2026-07-31T21:26:02.575354Z","steps":["trace[1680661790] 'process raft request' (duration: 119.31766ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575389Z","caller":"traceutil/trace.go:171","msg":"trace[1666081085] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"134.589395ms","start":"2026-07-31T21:26:02.440731Z","end":"2026-07-31T21:26:02.57532Z","steps":["trace[1666081085] 'process raft request' (duration: 134.487083ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575459Z","caller":"traceutil/trace.go:171","msg":"trace[338500128] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"119.437673ms","start":"2026-07-31T21:26:02.45601Z","end":"2026-07-31T21:26:02.575448Z","steps":["trace[338500128] 'process raft request' (duration: 119.32755ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575431Z","caller":"traceutil/trace.go:171","msg":"trace[348081742] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"119.445264ms","start":"2026-07-31T21:26:02.455945Z","end":"2026-07-31T21:26:02.57539Z","steps":["trace[348081742] 'process raft request' (duration: 119.348061ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575502Z","caller":"traceutil/trace.go:171","msg":"trace[165801327] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"115.029356ms","start":"2026-07-31T21:26:02.460447Z","end":"2026-07-31T21:26:02.575476Z","steps":["trace[165801327] 'process raft request' (duration: 114.984875ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575604Z","caller":"traceutil/trace.go:171","msg":"trace[2106715489] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"119.572096ms","start":"2026-07-31T21:26:02.456011Z","end":"2026-07-31T21:26:02.575583Z","steps":["trace[2106715489] 'process raft request' (duration: 119.375852ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.575274Z","caller":"traceutil/trace.go:171","msg":"trace[2020564440] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"145.678257ms","start":"2026-07-31T21:26:02.429542Z","end":"2026-07-31T21:26:02.57522Z","steps":["trace[2020564440] 'process raft request' (duration: 145.435711ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.579735Z","caller":"traceutil/trace.go:171","msg":"trace[1053478947] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"110.099225ms","start":"2026-07-31T21:26:02.469624Z","end":"2026-07-31T21:26:02.579723Z","steps":["trace[1053478947] 'process raft request' (duration: 109.718676ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.580383Z","caller":"traceutil/trace.go:171","msg":"trace[1959882933] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"106.481097ms","start":"2026-07-31T21:26:02.473881Z","end":"2026-07-31T21:26:02.580362Z","steps":["trace[1959882933] 'process raft request' (duration: 105.595265ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:02.580292Z","caller":"traceutil/trace.go:171","msg":"trace[1631326728] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"108.22389ms","start":"2026-07-31T21:26:02.472016Z","end":"2026-07-31T21:26:02.58024Z","steps":["trace[1631326728] 'process raft request' (duration: 107.41884ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:08.564954Z","caller":"traceutil/trace.go:171","msg":"trace[2136978604] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"280.865685ms","start":"2026-07-31T21:26:08.283524Z","end":"2026-07-31T21:26:08.564389Z","steps":["trace[2136978604] 'process raft request' (duration: 280.448035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:26:08.780004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.433318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:26:08.780137Z","caller":"traceutil/trace.go:171","msg":"trace[1390063763] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"162.805766ms","start":"2026-07-31T21:26:08.617311Z","end":"2026-07-31T21:26:08.780117Z","steps":["trace[1390063763] 'process raft request' (duration: 32.605569ms)","trace[1390063763] 'compare' (duration: 129.226933ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:26:08.818488Z","caller":"traceutil/trace.go:171","msg":"trace[109571317] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"154.039421ms","start":"2026-07-31T21:26:08.664425Z","end":"2026-07-31T21:26:08.818464Z","steps":["trace[109571317] 'read index received' (duration: 153.798155ms)","trace[109571317] 'applied index is now lower than readState.Index' (duration: 240.006µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:26:08.818559Z","caller":"traceutil/trace.go:171","msg":"trace[1060830343] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"185.594783ms","start":"2026-07-31T21:26:08.632921Z","end":"2026-07-31T21:26:08.818516Z","steps":["trace[1060830343] 'process raft request' (duration: 185.405899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:26:08.818909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.459342ms","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-31T21:26:08.818973Z","caller":"traceutil/trace.go:171","msg":"trace[971837682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"154.555934ms","start":"2026-07-31T21:26:08.6644Z","end":"2026-07-31T21:26:08.818956Z","steps":["trace[971837682] 'agreement among raft nodes before linearized reading' (duration: 154.203065ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:09.325818Z","caller":"traceutil/trace.go:171","msg":"trace[1005579169] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"119.175727ms","start":"2026-07-31T21:26:09.206611Z","end":"2026-07-31T21:26:09.325786Z","steps":["trace[1005579169] 'process raft request' (duration: 113.917348ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:24.020958Z","caller":"traceutil/trace.go:171","msg":"trace[813171588] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"105.521633ms","start":"2026-07-31T21:26:23.915417Z","end":"2026-07-31T21:26:24.020939Z","steps":["trace[813171588] 'process raft request' (duration: 105.38087ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:26:24.231428Z","caller":"traceutil/trace.go:171","msg":"trace[1632226765] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"175.814294ms","start":"2026-07-31T21:26:24.055586Z","end":"2026-07-31T21:26:24.2314Z","steps":["trace[1632226765] 'process raft request' (duration: 175.64406ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:27:43.312376Z","caller":"traceutil/trace.go:171","msg":"trace[1811412561] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"191.758048ms","start":"2026-07-31T21:27:43.120593Z","end":"2026-07-31T21:27:43.312351Z","steps":["trace[1811412561] 'process raft request' (duration: 191.603614ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:27:43.390026Z","caller":"traceutil/trace.go:171","msg":"trace[1247311257] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1954; }","duration":"132.532962ms","start":"2026-07-31T21:27:43.257466Z","end":"2026-07-31T21:27:43.389999Z","steps":["trace[1247311257] 'read index received' (duration: 54.823207ms)","trace[1247311257] 'applied index is now lower than readState.Index' (duration: 77.707705ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:27:43.390086Z","caller":"traceutil/trace.go:171","msg":"trace[894128022] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"166.686368ms","start":"2026-07-31T21:27:43.223363Z","end":"2026-07-31T21:27:43.390049Z","steps":["trace[894128022] 'process raft request' (duration: 159.774977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:27:43.390263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.775958ms","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-31T21:27:43.390322Z","caller":"traceutil/trace.go:171","msg":"trace[367529304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1880; }","duration":"132.870931ms","start":"2026-07-31T21:27:43.257436Z","end":"2026-07-31T21:27:43.390307Z","steps":["trace[367529304] 'agreement among raft nodes before linearized reading' (duration: 132.685686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:27:43.390357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.987975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-31T21:27:43.390456Z","caller":"traceutil/trace.go:171","msg":"trace[696035801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1880; }","duration":"131.151978ms","start":"2026-07-31T21:27:43.259286Z","end":"2026-07-31T21:27:43.390438Z","steps":["trace[696035801] 'agreement among raft nodes before linearized reading' (duration: 130.869721ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:28:32.699825Z","caller":"traceutil/trace.go:171","msg":"trace[1891843941] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"133.838625ms","start":"2026-07-31T21:28:32.565956Z","end":"2026-07-31T21:28:32.699794Z","steps":["trace[1891843941] 'process raft request' (duration: 45.767133ms)","trace[1891843941] 'compare' (duration: 87.853676ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:28:32.700327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.022977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:28:32.700386Z","caller":"traceutil/trace.go:171","msg":"trace[421083501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"100.685553ms","start":"2026-07-31T21:28:32.599686Z","end":"2026-07-31T21:28:32.700371Z","steps":["trace[421083501] 'agreement among raft nodes before linearized reading' (duration: 100.003876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:28:32.980288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.054559ms","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-31T21:28:32.980453Z","caller":"traceutil/trace.go:171","msg":"trace[779155361] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"136.147452ms","start":"2026-07-31T21:28:32.844283Z","end":"2026-07-31T21:28:32.980431Z","steps":["trace[779155361] 'process raft request' (duration: 109.542394ms)","trace[779155361] 'compare' (duration: 26.481695ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:28:32.98047Z","caller":"traceutil/trace.go:171","msg":"trace[2049739023] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2197; }","duration":"157.283245ms","start":"2026-07-31T21:28:32.823159Z","end":"2026-07-31T21:28:32.980442Z","steps":["trace[2049739023] 'range keys from in-memory index tree' (duration: 156.836554ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:28:38.854918Z","caller":"traceutil/trace.go:171","msg":"trace[1264579354] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"305.041655ms","start":"2026-07-31T21:28:38.549857Z","end":"2026-07-31T21:28:38.854899Z","steps":["trace[1264579354] 'process raft request' (duration: 304.914782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:28:38.855052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:28:38.549831Z","time spent":"305.149847ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:28:38.859657Z","caller":"traceutil/trace.go:171","msg":"trace[898841519] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"142.692194ms","start":"2026-07-31T21:28:38.71694Z","end":"2026-07-31T21:28:38.859632Z","steps":["trace[898841519] 'process raft request' (duration: 142.553681ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:29:13.852146Z","caller":"traceutil/trace.go:171","msg":"trace[1964712639] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"184.995911ms","start":"2026-07-31T21:29:13.667128Z","end":"2026-07-31T21:29:13.852124Z","steps":["trace[1964712639] 'process raft request' (duration: 184.859267ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:29:13.869603Z","caller":"traceutil/trace.go:171","msg":"trace[433538658] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"165.642081ms","start":"2026-07-31T21:29:13.703925Z","end":"2026-07-31T21:29:13.869567Z","steps":["trace[433538658] 'process raft request' (duration: 165.457796ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:29:13.869686Z","caller":"traceutil/trace.go:171","msg":"trace[2009121647] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"119.453829ms","start":"2026-07-31T21:29:13.7502Z","end":"2026-07-31T21:29:13.869654Z","steps":["trace[2009121647] 'process raft request' (duration: 119.270144ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:29:47.259397Z","caller":"traceutil/trace.go:171","msg":"trace[534445646] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"140.114908ms","start":"2026-07-31T21:29:47.119257Z","end":"2026-07-31T21:29:47.259372Z","steps":["trace[534445646] 'process raft request' (duration: 119.925853ms)","trace[534445646] 'compare' (duration: 19.884047ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:30:56.320993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.024234ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:30:56.321241Z","caller":"traceutil/trace.go:171","msg":"trace[1869035511] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3165; }","duration":"440.283871ms","start":"2026-07-31T21:30:55.880932Z","end":"2026-07-31T21:30:56.321216Z","steps":["trace[1869035511] 'range keys from in-memory index tree' (duration: 440.002124ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:30:56.321292Z","caller":"traceutil/trace.go:171","msg":"trace[1238197779] transaction","detail":"{read_only:false; response_revision:3166; number_of_response:1; }","duration":"785.569977ms","start":"2026-07-31T21:30:55.535691Z","end":"2026-07-31T21:30:56.321261Z","steps":["trace[1238197779] 'process raft request' (duration: 783.806753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:56.321583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:55.535682Z","time spent":"785.67859ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:30:56.327429Z","caller":"traceutil/trace.go:171","msg":"trace[515723197] transaction","detail":"{read_only:false; response_revision:3167; number_of_response:1; }","duration":"344.699443ms","start":"2026-07-31T21:30:55.982713Z","end":"2026-07-31T21:30:56.327412Z","steps":["trace[515723197] 'process raft request' (duration: 344.56852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:56.327535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:55.982692Z","time spent":"344.788565ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:30:56.327605Z","caller":"traceutil/trace.go:171","msg":"trace[1194365761] linearizableReadLoop","detail":"{readStateIndex:3291; appliedIndex:3289; }","duration":"446.421269ms","start":"2026-07-31T21:30:55.881083Z","end":"2026-07-31T21:30:56.327504Z","steps":["trace[1194365761] 'read index received' (duration: 438.434905ms)","trace[1194365761] 'applied index is now lower than readState.Index' (duration: 7.985354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:30:56.32774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.638204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-31T21:30:56.327776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.201724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:30:56.327801Z","caller":"traceutil/trace.go:171","msg":"trace[921510643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3169; }","duration":"446.737697ms","start":"2026-07-31T21:30:55.881047Z","end":"2026-07-31T21:30:56.327784Z","steps":["trace[921510643] 'agreement among raft nodes before linearized reading' (duration: 446.575613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:56.327855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:55.881026Z","time spent":"446.818218ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T21:30:56.32785Z","caller":"traceutil/trace.go:171","msg":"trace[1866294800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3169; }","duration":"446.290236ms","start":"2026-07-31T21:30:55.881541Z","end":"2026-07-31T21:30:56.327832Z","steps":["trace[1866294800] 'agreement among raft nodes before linearized reading' (duration: 446.187744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:56.327847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.747587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-07-31T21:30:56.327824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.566439ms","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":"warn","ts":"2026-07-31T21:30:56.32791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.468769ms","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-31T21:30:56.32793Z","caller":"traceutil/trace.go:171","msg":"trace[122431071] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3169; }","duration":"446.86591ms","start":"2026-07-31T21:30:55.881047Z","end":"2026-07-31T21:30:56.327913Z","steps":["trace[122431071] 'agreement among raft nodes before linearized reading' (duration: 446.644345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:56.327922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:55.881529Z","time spent":"446.377198ms","remote":"127.0.0.1:49834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T21:30:56.327956Z","caller":"traceutil/trace.go:171","msg":"trace[1394167405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3169; }","duration":"268.743593ms","start":"2026-07-31T21:30:56.059204Z","end":"2026-07-31T21:30:56.327947Z","steps":["trace[1394167405] 'agreement among raft nodes before linearized reading' (duration: 268.520248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:56.327972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:55.881031Z","time spent":"446.932671ms","remote":"127.0.0.1:50054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-31T21:30:56.327981Z","caller":"traceutil/trace.go:171","msg":"trace[812254944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3169; }","duration":"264.53939ms","start":"2026-07-31T21:30:56.06342Z","end":"2026-07-31T21:30:56.327959Z","steps":["trace[812254944] 'agreement among raft nodes before linearized reading' (duration: 264.302114ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:34:00.330813Z","caller":"traceutil/trace.go:171","msg":"trace[797681785] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"110.157961ms","start":"2026-07-31T21:34:00.220621Z","end":"2026-07-31T21:34:00.330779Z","steps":["trace[797681785] 'process raft request' (duration: 78.134225ms)","trace[797681785] 'compare' (duration: 31.719829ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:34:00.39701Z","caller":"traceutil/trace.go:171","msg":"trace[1962044726] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"107.462696ms","start":"2026-07-31T21:34:00.289518Z","end":"2026-07-31T21:34:00.396981Z","steps":["trace[1962044726] 'process raft request' (duration: 107.23291ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:35:27.19438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-07-31T21:35:27.279793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"84.219392ms","hash":76750589,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32317440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T21:35:27.279876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":76750589,"revision":3014,"compact-revision":-1} {"level":"info","ts":"2026-07-31T21:40:02.728745Z","caller":"traceutil/trace.go:171","msg":"trace[624249336] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"354.203273ms","start":"2026-07-31T21:40:02.37451Z","end":"2026-07-31T21:40:02.728713Z","steps":["trace[624249336] 'process raft request' (duration: 354.009789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:02.728987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:02.374484Z","time spent":"354.399318ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:40:02.778937Z","caller":"traceutil/trace.go:171","msg":"trace[1562113471] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"399.78869ms","start":"2026-07-31T21:40:02.379121Z","end":"2026-07-31T21:40:02.77891Z","steps":["trace[1562113471] 'process raft request' (duration: 399.589476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:02.779137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:02.379093Z","time spent":"399.936274ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:40:02.77912Z","caller":"traceutil/trace.go:171","msg":"trace[700059628] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"397.894505ms","start":"2026-07-31T21:40:02.381192Z","end":"2026-07-31T21:40:02.779086Z","steps":["trace[700059628] 'process raft request' (duration: 397.675638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:02.779256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.224161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:40:02.779119Z","caller":"traceutil/trace.go:171","msg":"trace[2074666500] linearizableReadLoop","detail":"{readStateIndex:8478; appliedIndex:8475; }","duration":"277.063416ms","start":"2026-07-31T21:40:02.502015Z","end":"2026-07-31T21:40:02.779079Z","steps":["trace[2074666500] 'read index received' (duration: 226.548799ms)","trace[2074666500] 'applied index is now lower than readState.Index' (duration: 50.511087ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:40:02.779314Z","caller":"traceutil/trace.go:171","msg":"trace[680552990] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8191; }","duration":"277.341893ms","start":"2026-07-31T21:40:02.501959Z","end":"2026-07-31T21:40:02.779301Z","steps":["trace[680552990] 'agreement among raft nodes before linearized reading' (duration: 277.22584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:02.7793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:02.381177Z","time spent":"398.031578ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:40:02.779564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.886872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-31T21:40:02.779627Z","caller":"traceutil/trace.go:171","msg":"trace[1454872285] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8191; }","duration":"109.999925ms","start":"2026-07-31T21:40:02.669611Z","end":"2026-07-31T21:40:02.77961Z","steps":["trace[1454872285] 'agreement among raft nodes before linearized reading' (duration: 109.79667ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:15.184066Z","caller":"traceutil/trace.go:171","msg":"trace[1330563314] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"132.878071ms","start":"2026-07-31T21:40:15.051161Z","end":"2026-07-31T21:40:15.184039Z","steps":["trace[1330563314] 'process raft request' (duration: 132.661026ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:15.184121Z","caller":"traceutil/trace.go:171","msg":"trace[1546396847] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"130.324ms","start":"2026-07-31T21:40:15.053765Z","end":"2026-07-31T21:40:15.184089Z","steps":["trace[1546396847] 'process raft request' (duration: 130.197647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:15.184148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.610515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T21:40:15.184225Z","caller":"traceutil/trace.go:171","msg":"trace[1570279106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8256; }","duration":"132.700297ms","start":"2026-07-31T21:40:15.051491Z","end":"2026-07-31T21:40:15.184191Z","steps":["trace[1570279106] 'agreement among raft nodes before linearized reading' (duration: 132.522373ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:27.201556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6149} {"level":"info","ts":"2026-07-31T21:40:27.301619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6149,"took":"97.942101ms","hash":2815272631,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":23367680,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-31T21:40:27.30169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2815272631,"revision":6149,"compact-revision":3014} {"level":"info","ts":"2026-07-31T21:40:30.483526Z","caller":"traceutil/trace.go:171","msg":"trace[489310731] linearizableReadLoop","detail":"{readStateIndex:8637; appliedIndex:8636; }","duration":"255.140893ms","start":"2026-07-31T21:40:30.228366Z","end":"2026-07-31T21:40:30.483506Z","steps":["trace[489310731] 'read index received' (duration: 255.02409ms)","trace[489310731] 'applied index is now lower than readState.Index' (duration: 115.823µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:40:30.48356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:30.144385Z","time spent":"339.162447ms","remote":"127.0.0.1:49870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T21:40:30.483667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.289047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:40:30.483867Z","caller":"traceutil/trace.go:171","msg":"trace[1971879307] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8342; }","duration":"255.516472ms","start":"2026-07-31T21:40:30.228326Z","end":"2026-07-31T21:40:30.483843Z","steps":["trace[1971879307] 'agreement among raft nodes before linearized reading' (duration: 255.288457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:30.483779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.279366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:40:30.483983Z","caller":"traceutil/trace.go:171","msg":"trace[558277327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8342; }","duration":"162.488941ms","start":"2026-07-31T21:40:30.321435Z","end":"2026-07-31T21:40:30.483924Z","steps":["trace[558277327] 'agreement among raft nodes before linearized reading' (duration: 162.256195ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:30.868521Z","caller":"traceutil/trace.go:171","msg":"trace[971538947] linearizableReadLoop","detail":"{readStateIndex:8638; appliedIndex:8637; }","duration":"271.320047ms","start":"2026-07-31T21:40:30.597186Z","end":"2026-07-31T21:40:30.868506Z","steps":["trace[971538947] 'read index received' (duration: 269.843041ms)","trace[971538947] 'applied index is now lower than readState.Index' (duration: 1.476156ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:40:30.868546Z","caller":"traceutil/trace.go:171","msg":"trace[30508954] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"383.980197ms","start":"2026-07-31T21:40:30.484555Z","end":"2026-07-31T21:40:30.868535Z","steps":["trace[30508954] 'process raft request' (duration: 382.519991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:30.868625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.42936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:40:30.868648Z","caller":"traceutil/trace.go:171","msg":"trace[1709729750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8343; }","duration":"271.491832ms","start":"2026-07-31T21:40:30.59715Z","end":"2026-07-31T21:40:30.868642Z","steps":["trace[1709729750] 'agreement among raft nodes before linearized reading' (duration: 271.4188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:30.868661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.748402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:40:30.86868Z","caller":"traceutil/trace.go:171","msg":"trace[1374447615] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8343; }","duration":"179.791103ms","start":"2026-07-31T21:40:30.688884Z","end":"2026-07-31T21:40:30.868675Z","steps":["trace[1374447615] 'agreement among raft nodes before linearized reading' (duration: 179.706731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:30.868681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:30.484541Z","time spent":"384.05963ms","remote":"127.0.0.1:49870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:40:31.873198Z","caller":"traceutil/trace.go:171","msg":"trace[669411191] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"395.189539ms","start":"2026-07-31T21:40:31.477996Z","end":"2026-07-31T21:40:31.873185Z","steps":["trace[669411191] 'process raft request' (duration: 395.107017ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:31.873196Z","caller":"traceutil/trace.go:171","msg":"trace[1961524077] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"395.261021ms","start":"2026-07-31T21:40:31.477907Z","end":"2026-07-31T21:40:31.873168Z","steps":["trace[1961524077] 'process raft request' (duration: 395.071136ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:31.873206Z","caller":"traceutil/trace.go:171","msg":"trace[160103432] linearizableReadLoop","detail":"{readStateIndex:8647; appliedIndex:8642; }","duration":"395.044005ms","start":"2026-07-31T21:40:31.478137Z","end":"2026-07-31T21:40:31.873181Z","steps":["trace[160103432] 'read index received' (duration: 480.061µs)","trace[160103432] 'applied index is now lower than readState.Index' (duration: 394.563074ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:40:31.873192Z","caller":"traceutil/trace.go:171","msg":"trace[1283422630] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"395.381893ms","start":"2026-07-31T21:40:31.477775Z","end":"2026-07-31T21:40:31.873157Z","steps":["trace[1283422630] 'process raft request' (duration: 393.609301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:31.873453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.115315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T21:40:31.87333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:31.47799Z","time spent":"395.274861ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:40:31.873505Z","caller":"traceutil/trace.go:171","msg":"trace[1821574087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8353; }","duration":"274.215907ms","start":"2026-07-31T21:40:31.599277Z","end":"2026-07-31T21:40:31.873493Z","steps":["trace[1821574087] 'agreement among raft nodes before linearized reading' (duration: 274.097315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:31.873545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.386383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T21:40:31.873582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.224034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:222780"} {"level":"info","ts":"2026-07-31T21:40:31.873595Z","caller":"traceutil/trace.go:171","msg":"trace[912122347] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8353; }","duration":"395.463276ms","start":"2026-07-31T21:40:31.478116Z","end":"2026-07-31T21:40:31.873579Z","steps":["trace[912122347] 'agreement among raft nodes before linearized reading' (duration: 395.187779ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:31.873607Z","caller":"traceutil/trace.go:171","msg":"trace[1876588384] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8353; }","duration":"394.249085ms","start":"2026-07-31T21:40:31.479351Z","end":"2026-07-31T21:40:31.8736Z","steps":["trace[1876588384] 'agreement among raft nodes before linearized reading' (duration: 394.016629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:31.873634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:31.479343Z","time spent":"394.285136ms","remote":"127.0.0.1:50040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":222802,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-31T21:40:31.873655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:31.478107Z","time spent":"395.527577ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":13,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-07-31T21:40:31.873258Z","caller":"traceutil/trace.go:171","msg":"trace[682466089] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"395.361393ms","start":"2026-07-31T21:40:31.477854Z","end":"2026-07-31T21:40:31.873216Z","steps":["trace[682466089] 'process raft request' (duration: 395.034065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:31.873805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:31.477849Z","time spent":"395.938066ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:40:31.873938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:31.477762Z","time spent":"395.68473ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:40:31.873329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:40:31.477887Z","time spent":"395.372313ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:40:44.084346Z","caller":"traceutil/trace.go:171","msg":"trace[683680649] linearizableReadLoop","detail":"{readStateIndex:8763; appliedIndex:8762; }","duration":"156.008014ms","start":"2026-07-31T21:40:43.928316Z","end":"2026-07-31T21:40:44.084324Z","steps":["trace[683680649] 'read index received' (duration: 155.773468ms)","trace[683680649] 'applied index is now lower than readState.Index' (duration: 233.836µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:40:44.084589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.26074ms","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-31T21:40:44.08463Z","caller":"traceutil/trace.go:171","msg":"trace[1658514651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8464; }","duration":"156.362622ms","start":"2026-07-31T21:40:43.928256Z","end":"2026-07-31T21:40:44.084618Z","steps":["trace[1658514651] 'agreement among raft nodes before linearized reading' (duration: 156.197148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:44.084711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.139233ms","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-31T21:40:44.084838Z","caller":"traceutil/trace.go:171","msg":"trace[323069133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8464; }","duration":"153.292597ms","start":"2026-07-31T21:40:43.931492Z","end":"2026-07-31T21:40:44.084785Z","steps":["trace[323069133] 'agreement among raft nodes before linearized reading' (duration: 153.02059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:44.084918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.248425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T21:40:44.084428Z","caller":"traceutil/trace.go:171","msg":"trace[263780468] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"161.63775ms","start":"2026-07-31T21:40:43.922741Z","end":"2026-07-31T21:40:44.084379Z","steps":["trace[263780468] 'process raft request' (duration: 161.416165ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:44.085005Z","caller":"traceutil/trace.go:171","msg":"trace[961429705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8464; }","duration":"153.402869ms","start":"2026-07-31T21:40:43.931581Z","end":"2026-07-31T21:40:44.084983Z","steps":["trace[961429705] 'agreement among raft nodes before linearized reading' (duration: 153.075261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:44.08493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.196451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-31T21:40:44.085007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.534537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8t8zh\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-07-31T21:40:44.085072Z","caller":"traceutil/trace.go:171","msg":"trace[1765299663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8464; }","duration":"150.366035ms","start":"2026-07-31T21:40:43.934691Z","end":"2026-07-31T21:40:44.085057Z","steps":["trace[1765299663] 'agreement among raft nodes before linearized reading' (duration: 149.942945ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:44.0851Z","caller":"traceutil/trace.go:171","msg":"trace[1166569340] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8t8zh; range_end:; response_count:1; response_revision:8464; }","duration":"140.67014ms","start":"2026-07-31T21:40:43.944406Z","end":"2026-07-31T21:40:44.085076Z","steps":["trace[1166569340] 'agreement among raft nodes before linearized reading' (duration: 140.297781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:44.373041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.858645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:40:44.373348Z","caller":"traceutil/trace.go:171","msg":"trace[1866271350] linearizableReadLoop","detail":"{readStateIndex:8767; appliedIndex:8763; }","duration":"255.868501ms","start":"2026-07-31T21:40:44.117459Z","end":"2026-07-31T21:40:44.373328Z","steps":["trace[1866271350] 'read index received' (duration: 107.572415ms)","trace[1866271350] 'applied index is now lower than readState.Index' (duration: 148.294116ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:40:44.373369Z","caller":"traceutil/trace.go:171","msg":"trace[1615328263] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"281.023083ms","start":"2026-07-31T21:40:44.092326Z","end":"2026-07-31T21:40:44.37335Z","steps":["trace[1615328263] 'process raft request' (duration: 280.848778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:44.373472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.007224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:40:44.373505Z","caller":"traceutil/trace.go:171","msg":"trace[431417218] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8468; }","duration":"256.044095ms","start":"2026-07-31T21:40:44.11745Z","end":"2026-07-31T21:40:44.373494Z","steps":["trace[431417218] 'agreement among raft nodes before linearized reading' (duration: 255.943993ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:44.373538Z","caller":"traceutil/trace.go:171","msg":"trace[602697187] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"281.045434ms","start":"2026-07-31T21:40:44.09244Z","end":"2026-07-31T21:40:44.373485Z","steps":["trace[602697187] 'process raft request' (duration: 280.810468ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:44.37362Z","caller":"traceutil/trace.go:171","msg":"trace[2052715497] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"281.056503ms","start":"2026-07-31T21:40:44.092529Z","end":"2026-07-31T21:40:44.373586Z","steps":["trace[2052715497] 'process raft request' (duration: 280.759546ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:44.373754Z","caller":"traceutil/trace.go:171","msg":"trace[1309689389] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"281.495794ms","start":"2026-07-31T21:40:44.092245Z","end":"2026-07-31T21:40:44.373741Z","steps":["trace[1309689389] 'process raft request' (duration: 132.822189ms)","trace[1309689389] 'compare' (duration: 147.696432ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:40:56.552195Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022a8.wal"} {"level":"info","ts":"2026-07-31T21:40:57.122904Z","caller":"traceutil/trace.go:171","msg":"trace[312611539] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"128.081754ms","start":"2026-07-31T21:40:56.994789Z","end":"2026-07-31T21:40:57.12287Z","steps":["trace[312611539] 'process raft request' (duration: 127.901269ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:57.139986Z","caller":"traceutil/trace.go:171","msg":"trace[1002351867] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"129.580352ms","start":"2026-07-31T21:40:57.010385Z","end":"2026-07-31T21:40:57.139965Z","steps":["trace[1002351867] 'process raft request' (duration: 129.428328ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:57.140323Z","caller":"traceutil/trace.go:171","msg":"trace[1942388540] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"115.415026ms","start":"2026-07-31T21:40:57.024894Z","end":"2026-07-31T21:40:57.140309Z","steps":["trace[1942388540] 'process raft request' (duration: 115.031487ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:58.314731Z","caller":"traceutil/trace.go:171","msg":"trace[2105275998] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"247.59544ms","start":"2026-07-31T21:40:58.067114Z","end":"2026-07-31T21:40:58.314709Z","steps":["trace[2105275998] 'process raft request' (duration: 247.430866ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:58.332001Z","caller":"traceutil/trace.go:171","msg":"trace[639426112] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"198.011955ms","start":"2026-07-31T21:40:58.133965Z","end":"2026-07-31T21:40:58.331977Z","steps":["trace[639426112] 'process raft request' (duration: 197.839671ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:40:58.332257Z","caller":"traceutil/trace.go:171","msg":"trace[732451407] linearizableReadLoop","detail":"{readStateIndex:8885; appliedIndex:8883; }","duration":"164.883809ms","start":"2026-07-31T21:40:58.167349Z","end":"2026-07-31T21:40:58.332233Z","steps":["trace[732451407] 'read index received' (duration: 147.283352ms)","trace[732451407] 'applied index is now lower than readState.Index' (duration: 17.599507ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:40:58.33249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.127125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8t8zh\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-07-31T21:40:58.332531Z","caller":"traceutil/trace.go:171","msg":"trace[816875608] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8t8zh; range_end:; response_count:1; response_revision:8582; }","duration":"165.220377ms","start":"2026-07-31T21:40:58.167298Z","end":"2026-07-31T21:40:58.332519Z","steps":["trace[816875608] 'agreement among raft nodes before linearized reading' (duration: 165.066844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:58.332888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.006743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-766dc8dcbf-d4stb.18c77db60f8fe05d\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-31T21:40:58.333001Z","caller":"traceutil/trace.go:171","msg":"trace[37115529] range","detail":"{range_begin:/registry/events/openstack/barbican-api-766dc8dcbf-d4stb.18c77db60f8fe05d; range_end:; response_count:1; response_revision:8582; }","duration":"156.177817ms","start":"2026-07-31T21:40:58.176794Z","end":"2026-07-31T21:40:58.332972Z","steps":["trace[37115529] 'agreement among raft nodes before linearized reading' (duration: 155.731256ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:42:59.948768Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T21:42:59.956168Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-31T21:42:59.956325Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-31T21:45:27.20801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8323} {"level":"info","ts":"2026-07-31T21:45:27.279554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8323,"took":"69.006219ms","hash":846176022,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":26939392,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-31T21:45:27.279621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":846176022,"revision":8323,"compact-revision":6149} {"level":"info","ts":"2026-07-31T21:50:27.214421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11008} {"level":"info","ts":"2026-07-31T21:50:27.295345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11008,"took":"78.171752ms","hash":671130460,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":28454912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-31T21:50:27.295422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":671130460,"revision":11008,"compact-revision":8323} {"level":"info","ts":"2026-07-31T21:52:04.86215Z","caller":"traceutil/trace.go:171","msg":"trace[1608953046] transaction","detail":"{read_only:false; response_revision:13667; number_of_response:1; }","duration":"126.616078ms","start":"2026-07-31T21:52:04.735509Z","end":"2026-07-31T21:52:04.862125Z","steps":["trace[1608953046] 'process raft request' (duration: 126.298469ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:52:22.031443Z","caller":"traceutil/trace.go:171","msg":"trace[88276732] linearizableReadLoop","detail":"{readStateIndex:14245; appliedIndex:14244; }","duration":"227.108279ms","start":"2026-07-31T21:52:21.804306Z","end":"2026-07-31T21:52:22.031414Z","steps":["trace[88276732] 'read index received' (duration: 226.770839ms)","trace[88276732] 'applied index is now lower than readState.Index' (duration: 336.3µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:52:22.031641Z","caller":"traceutil/trace.go:171","msg":"trace[1583326067] transaction","detail":"{read_only:false; response_revision:13772; number_of_response:1; }","duration":"253.490965ms","start":"2026-07-31T21:52:21.778112Z","end":"2026-07-31T21:52:22.031603Z","steps":["trace[1583326067] 'process raft request' (duration: 253.073933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:52:22.031683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.357376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-31T21:52:22.031843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.299215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T21:52:22.031873Z","caller":"traceutil/trace.go:171","msg":"trace[446665991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13772; }","duration":"227.604004ms","start":"2026-07-31T21:52:21.804257Z","end":"2026-07-31T21:52:22.031861Z","steps":["trace[446665991] 'agreement among raft nodes before linearized reading' (duration: 227.308324ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:52:22.031915Z","caller":"traceutil/trace.go:171","msg":"trace[1352398113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13772; }","duration":"226.380678ms","start":"2026-07-31T21:52:21.805516Z","end":"2026-07-31T21:52:22.031897Z","steps":["trace[1352398113] 'agreement among raft nodes before linearized reading' (duration: 226.113609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:52:22.031885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.029599ms","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-31T21:52:22.031999Z","caller":"traceutil/trace.go:171","msg":"trace[15345613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13772; }","duration":"217.187504ms","start":"2026-07-31T21:52:21.814786Z","end":"2026-07-31T21:52:22.031974Z","steps":["trace[15345613] 'agreement among raft nodes before linearized reading' (duration: 216.864994ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:52:22.207023Z","caller":"traceutil/trace.go:171","msg":"trace[2039523902] transaction","detail":"{read_only:false; response_revision:13774; number_of_response:1; }","duration":"170.785952ms","start":"2026-07-31T21:52:22.036215Z","end":"2026-07-31T21:52:22.207001Z","steps":["trace[2039523902] 'process raft request' (duration: 170.573326ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:52:22.210937Z","caller":"traceutil/trace.go:171","msg":"trace[1733993021] transaction","detail":"{read_only:false; response_revision:13775; number_of_response:1; }","duration":"174.595151ms","start":"2026-07-31T21:52:22.0363Z","end":"2026-07-31T21:52:22.210895Z","steps":["trace[1733993021] 'process raft request' (duration: 170.663978ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:52:31.14903Z","caller":"traceutil/trace.go:171","msg":"trace[1749907265] transaction","detail":"{read_only:false; response_revision:13934; number_of_response:1; }","duration":"139.467723ms","start":"2026-07-31T21:52:31.009524Z","end":"2026-07-31T21:52:31.148991Z","steps":["trace[1749907265] 'process raft request' (duration: 139.236506ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:52:31.312722Z","caller":"traceutil/trace.go:171","msg":"trace[1059932617] transaction","detail":"{read_only:false; response_revision:13935; number_of_response:1; }","duration":"159.843038ms","start":"2026-07-31T21:52:31.152847Z","end":"2026-07-31T21:52:31.31269Z","steps":["trace[1059932617] 'process raft request' (duration: 122.722873ms)","trace[1059932617] 'compare' (duration: 36.93556ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:53:35.473834Z","caller":"traceutil/trace.go:171","msg":"trace[975894692] linearizableReadLoop","detail":"{readStateIndex:15332; appliedIndex:15331; }","duration":"132.571166ms","start":"2026-07-31T21:53:35.341232Z","end":"2026-07-31T21:53:35.473804Z","steps":["trace[975894692] 'read index received' (duration: 112.028765ms)","trace[975894692] 'applied index is now lower than readState.Index' (duration: 20.53979ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:53:35.473894Z","caller":"traceutil/trace.go:171","msg":"trace[74925406] transaction","detail":"{read_only:false; response_revision:14830; number_of_response:1; }","duration":"113.428306ms","start":"2026-07-31T21:53:35.360433Z","end":"2026-07-31T21:53:35.473861Z","steps":["trace[74925406] 'process raft request' (duration: 113.259041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:35.474109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.858944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-pzcmm\" ","response":"range_response_count:1 size:20558"} {"level":"info","ts":"2026-07-31T21:53:35.474182Z","caller":"traceutil/trace.go:171","msg":"trace[1182903911] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-pzcmm; range_end:; response_count:1; response_revision:14830; }","duration":"132.988438ms","start":"2026-07-31T21:53:35.341179Z","end":"2026-07-31T21:53:35.474168Z","steps":["trace[1182903911] 'agreement among raft nodes before linearized reading' (duration: 132.72011ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:54:10.100741Z","caller":"traceutil/trace.go:171","msg":"trace[1654328903] transaction","detail":"{read_only:false; response_revision:15017; number_of_response:1; }","duration":"118.721942ms","start":"2026-07-31T21:54:09.981997Z","end":"2026-07-31T21:54:10.100719Z","steps":["trace[1654328903] 'process raft request' (duration: 118.594199ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:54:10.105922Z","caller":"traceutil/trace.go:171","msg":"trace[900932215] transaction","detail":"{read_only:false; response_revision:15018; number_of_response:1; }","duration":"121.254164ms","start":"2026-07-31T21:54:09.984636Z","end":"2026-07-31T21:54:10.10589Z","steps":["trace[900932215] 'process raft request' (duration: 121.10813ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:54:10.27646Z","caller":"traceutil/trace.go:171","msg":"trace[1078805051] transaction","detail":"{read_only:false; response_revision:15019; number_of_response:1; }","duration":"117.765779ms","start":"2026-07-31T21:54:10.158665Z","end":"2026-07-31T21:54:10.276431Z","steps":["trace[1078805051] 'process raft request' (duration: 107.17356ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:55:27.220873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13030} {"level":"info","ts":"2026-07-31T21:55:27.296164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13030,"took":"72.625565ms","hash":1457713509,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T21:55:27.296221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1457713509,"revision":13030,"compact-revision":11008} {"level":"info","ts":"2026-07-31T21:59:51.528113Z","caller":"traceutil/trace.go:171","msg":"trace[1408526393] transaction","detail":"{read_only:false; response_revision:17630; number_of_response:1; }","duration":"218.07396ms","start":"2026-07-31T21:59:51.310018Z","end":"2026-07-31T21:59:51.528092Z","steps":["trace[1408526393] 'process raft request' (duration: 217.945677ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:59:51.550319Z","caller":"traceutil/trace.go:171","msg":"trace[1316540279] linearizableReadLoop","detail":"{readStateIndex:18228; appliedIndex:18226; }","duration":"122.712187ms","start":"2026-07-31T21:59:51.42758Z","end":"2026-07-31T21:59:51.550293Z","steps":["trace[1316540279] 'read index received' (duration: 100.402087ms)","trace[1316540279] 'applied index is now lower than readState.Index' (duration: 22.30929ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:59:51.550522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.924112ms","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-31T21:59:51.550553Z","caller":"traceutil/trace.go:171","msg":"trace[620930293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17631; }","duration":"123.006364ms","start":"2026-07-31T21:59:51.427538Z","end":"2026-07-31T21:59:51.550544Z","steps":["trace[620930293] 'agreement among raft nodes before linearized reading' (duration: 122.85105ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:59:51.550882Z","caller":"traceutil/trace.go:171","msg":"trace[1316000868] transaction","detail":"{read_only:false; response_revision:17631; number_of_response:1; }","duration":"239.849937ms","start":"2026-07-31T21:59:51.311021Z","end":"2026-07-31T21:59:51.550871Z","steps":["trace[1316000868] 'process raft request' (duration: 239.13523ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:59:54.106579Z","caller":"traceutil/trace.go:171","msg":"trace[1554097359] transaction","detail":"{read_only:false; response_revision:17650; number_of_response:1; }","duration":"247.610038ms","start":"2026-07-31T21:59:53.858933Z","end":"2026-07-31T21:59:54.106543Z","steps":["trace[1554097359] 'process raft request' (duration: 247.371103ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:59:54.107207Z","caller":"traceutil/trace.go:171","msg":"trace[652284603] linearizableReadLoop","detail":"{readStateIndex:18247; appliedIndex:18247; }","duration":"192.250442ms","start":"2026-07-31T21:59:53.914888Z","end":"2026-07-31T21:59:54.107138Z","steps":["trace[652284603] 'read index received' (duration: 192.240572ms)","trace[652284603] 'applied index is now lower than readState.Index' (duration: 8.26µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:59:54.107411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.51031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-31T21:59:54.107492Z","caller":"traceutil/trace.go:171","msg":"trace[307417393] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17650; }","duration":"192.638783ms","start":"2026-07-31T21:59:53.914838Z","end":"2026-07-31T21:59:54.107476Z","steps":["trace[307417393] 'agreement among raft nodes before linearized reading' (duration: 192.424127ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:27.227653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15535} {"level":"info","ts":"2026-07-31T22:00:27.3254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15535,"took":"94.297346ms","hash":3339380564,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":33624064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-31T22:00:27.325478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339380564,"revision":15535,"compact-revision":13030} {"level":"info","ts":"2026-07-31T22:03:19.580383Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T22:03:19.584885Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-31T22:03:19.584976Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-31T22:03:30.586885Z","caller":"traceutil/trace.go:171","msg":"trace[987286891] transaction","detail":"{read_only:false; response_revision:19413; number_of_response:1; }","duration":"112.617305ms","start":"2026-07-31T22:03:30.47424Z","end":"2026-07-31T22:03:30.586858Z","steps":["trace[987286891] 'process raft request' (duration: 112.441561ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:03:30.58767Z","caller":"traceutil/trace.go:171","msg":"trace[1790766126] transaction","detail":"{read_only:false; response_revision:19414; number_of_response:1; }","duration":"112.001439ms","start":"2026-07-31T22:03:30.47564Z","end":"2026-07-31T22:03:30.587642Z","steps":["trace[1790766126] 'process raft request' (duration: 111.849375ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:07.263952Z","caller":"traceutil/trace.go:171","msg":"trace[2044771815] transaction","detail":"{read_only:false; response_revision:20145; number_of_response:1; }","duration":"115.69402ms","start":"2026-07-31T22:05:07.148233Z","end":"2026-07-31T22:05:07.263927Z","steps":["trace[2044771815] 'process raft request' (duration: 115.503775ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:26.185922Z","caller":"traceutil/trace.go:171","msg":"trace[1644945157] linearizableReadLoop","detail":"{readStateIndex:20941; appliedIndex:20940; }","duration":"105.180685ms","start":"2026-07-31T22:05:26.080709Z","end":"2026-07-31T22:05:26.18589Z","steps":["trace[1644945157] 'read index received' (duration: 104.97132ms)","trace[1644945157] 'applied index is now lower than readState.Index' (duration: 208.145µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:05:26.185964Z","caller":"traceutil/trace.go:171","msg":"trace[743960954] transaction","detail":"{read_only:false; response_revision:20259; number_of_response:1; }","duration":"108.648348ms","start":"2026-07-31T22:05:26.077279Z","end":"2026-07-31T22:05:26.185927Z","steps":["trace[743960954] 'process raft request' (duration: 108.387772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.186196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.438931ms","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-31T22:05:26.186242Z","caller":"traceutil/trace.go:171","msg":"trace[295547352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20259; }","duration":"105.537103ms","start":"2026-07-31T22:05:26.080692Z","end":"2026-07-31T22:05:26.186229Z","steps":["trace[295547352] 'agreement among raft nodes before linearized reading' (duration: 105.320608ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:26.359845Z","caller":"traceutil/trace.go:171","msg":"trace[660705357] transaction","detail":"{read_only:false; response_revision:20261; number_of_response:1; }","duration":"200.131074ms","start":"2026-07-31T22:05:26.159689Z","end":"2026-07-31T22:05:26.35982Z","steps":["trace[660705357] 'process raft request' (duration: 200.052262ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:26.360023Z","caller":"traceutil/trace.go:171","msg":"trace[848066043] transaction","detail":"{read_only:false; response_revision:20260; number_of_response:1; }","duration":"260.154754ms","start":"2026-07-31T22:05:26.099842Z","end":"2026-07-31T22:05:26.359997Z","steps":["trace[848066043] 'process raft request' (duration: 259.750244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.646318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.870189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:05:26.646496Z","caller":"traceutil/trace.go:171","msg":"trace[566045550] linearizableReadLoop","detail":"{readStateIndex:20944; appliedIndex:20941; }","duration":"417.106301ms","start":"2026-07-31T22:05:26.229373Z","end":"2026-07-31T22:05:26.64648Z","steps":["trace[566045550] 'read index received' (duration: 130.264346ms)","trace[566045550] 'applied index is now lower than readState.Index' (duration: 286.840805ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:05:26.646882Z","caller":"traceutil/trace.go:171","msg":"trace[661543920] transaction","detail":"{read_only:false; response_revision:20262; number_of_response:1; }","duration":"457.388948ms","start":"2026-07-31T22:05:26.189454Z","end":"2026-07-31T22:05:26.646843Z","steps":["trace[661543920] 'process raft request' (duration: 323.903625ms)","trace[661543920] 'compare' (duration: 132.739586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:05:26.646954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.380832ms","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-31T22:05:26.646932Z","caller":"traceutil/trace.go:171","msg":"trace[1433221666] transaction","detail":"{read_only:false; response_revision:20263; number_of_response:1; }","duration":"304.897478ms","start":"2026-07-31T22:05:26.342006Z","end":"2026-07-31T22:05:26.646904Z","steps":["trace[1433221666] 'process raft request' (duration: 304.413776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.646973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.585723ms","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-31T22:05:26.647037Z","caller":"traceutil/trace.go:171","msg":"trace[652639319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20263; }","duration":"417.686605ms","start":"2026-07-31T22:05:26.229333Z","end":"2026-07-31T22:05:26.647019Z","steps":["trace[652639319] 'agreement among raft nodes before linearized reading' (duration: 417.48275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.647048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.793503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-31T22:05:26.647078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:05:26.229314Z","time spent":"417.756927ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-31T22:05:26.647081Z","caller":"traceutil/trace.go:171","msg":"trace[800998390] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:20263; }","duration":"176.884056ms","start":"2026-07-31T22:05:26.470186Z","end":"2026-07-31T22:05:26.647071Z","steps":["trace[800998390] 'agreement among raft nodes before linearized reading' (duration: 176.830615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.647069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:05:26.341979Z","time spent":"305.020861ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:05:26.647077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:05:26.189439Z","time spent":"457.534542ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:05:26.646996Z","caller":"traceutil/trace.go:171","msg":"trace[2022214071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20263; }","duration":"403.458604ms","start":"2026-07-31T22:05:26.243523Z","end":"2026-07-31T22:05:26.646982Z","steps":["trace[2022214071] 'agreement among raft nodes before linearized reading' (duration: 403.31892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.647226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:05:26.243505Z","time spent":"403.701529ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T22:05:26.647273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.216183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:05:26.647307Z","caller":"traceutil/trace.go:171","msg":"trace[899148415] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:20263; }","duration":"252.285315ms","start":"2026-07-31T22:05:26.395011Z","end":"2026-07-31T22:05:26.647296Z","steps":["trace[899148415] 'agreement among raft nodes before linearized reading' (duration: 252.245674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:26.647237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.686571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:05:26.647348Z","caller":"traceutil/trace.go:171","msg":"trace[994772761] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:20263; }","duration":"291.835854ms","start":"2026-07-31T22:05:26.355499Z","end":"2026-07-31T22:05:26.647335Z","steps":["trace[994772761] 'agreement among raft nodes before linearized reading' (duration: 291.705981ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:27.22564Z","caller":"traceutil/trace.go:171","msg":"trace[1913247598] transaction","detail":"{read_only:false; response_revision:20266; number_of_response:1; }","duration":"230.616886ms","start":"2026-07-31T22:05:26.994995Z","end":"2026-07-31T22:05:27.225612Z","steps":["trace[1913247598] 'process raft request' (duration: 230.439511ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:27.431235Z","caller":"traceutil/trace.go:171","msg":"trace[79823930] transaction","detail":"{read_only:false; response_revision:20268; number_of_response:1; }","duration":"201.349363ms","start":"2026-07-31T22:05:27.229854Z","end":"2026-07-31T22:05:27.431203Z","steps":["trace[79823930] 'process raft request' (duration: 201.288651ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:27.431347Z","caller":"traceutil/trace.go:171","msg":"trace[1085478634] linearizableReadLoop","detail":"{readStateIndex:20949; appliedIndex:20947; }","duration":"345.042271ms","start":"2026-07-31T22:05:27.086273Z","end":"2026-07-31T22:05:27.431315Z","steps":["trace[1085478634] 'read index received' (duration: 139.233922ms)","trace[1085478634] 'applied index is now lower than readState.Index' (duration: 205.805269ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:05:27.431348Z","caller":"traceutil/trace.go:171","msg":"trace[957384248] transaction","detail":"{read_only:false; response_revision:20267; number_of_response:1; }","duration":"409.505989ms","start":"2026-07-31T22:05:27.021814Z","end":"2026-07-31T22:05:27.43132Z","steps":["trace[957384248] 'process raft request' (duration: 314.522329ms)","trace[957384248] 'compare' (duration: 94.625421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:05:27.431622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.328358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-31T22:05:27.431656Z","caller":"traceutil/trace.go:171","msg":"trace[25275052] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20268; }","duration":"345.42154ms","start":"2026-07-31T22:05:27.086227Z","end":"2026-07-31T22:05:27.431648Z","steps":["trace[25275052] 'agreement among raft nodes before linearized reading' (duration: 345.236686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:05:27.431684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:05:27.086209Z","time spent":"345.469432ms","remote":"127.0.0.1:50040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-31T22:05:27.431697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:05:27.02179Z","time spent":"409.800236ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:05:27.493532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18120} {"level":"info","ts":"2026-07-31T22:05:27.67641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18120,"took":"176.854765ms","hash":864246736,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30834688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T22:05:27.676499Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":864246736,"revision":18120,"compact-revision":15535} {"level":"info","ts":"2026-07-31T22:09:21.268498Z","caller":"traceutil/trace.go:171","msg":"trace[2031162081] transaction","detail":"{read_only:false; response_revision:22693; number_of_response:1; }","duration":"100.655985ms","start":"2026-07-31T22:09:21.16782Z","end":"2026-07-31T22:09:21.268476Z","steps":["trace[2031162081] 'process raft request' (duration: 100.522932ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:09:21.585853Z","caller":"traceutil/trace.go:171","msg":"trace[791151444] transaction","detail":"{read_only:false; response_revision:22694; number_of_response:1; }","duration":"233.436724ms","start":"2026-07-31T22:09:21.352388Z","end":"2026-07-31T22:09:21.585825Z","steps":["trace[791151444] 'process raft request' (duration: 195.617985ms)","trace[791151444] 'compare' (duration: 37.495031ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:09:21.586371Z","caller":"traceutil/trace.go:171","msg":"trace[784106096] transaction","detail":"{read_only:false; response_revision:22695; number_of_response:1; }","duration":"102.364127ms","start":"2026-07-31T22:09:21.483938Z","end":"2026-07-31T22:09:21.586303Z","steps":["trace[784106096] 'process raft request' (duration: 101.811494ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:10:27.501563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20268} {"level":"info","ts":"2026-07-31T22:10:27.611644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20268,"took":"106.293405ms","hash":335528698,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":43712512,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-31T22:10:27.611711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":335528698,"revision":20268,"compact-revision":18120} {"level":"info","ts":"2026-07-31T22:10:36.391414Z","caller":"traceutil/trace.go:171","msg":"trace[2053629516] transaction","detail":"{read_only:false; response_revision:23548; number_of_response:1; }","duration":"108.612213ms","start":"2026-07-31T22:10:36.282774Z","end":"2026-07-31T22:10:36.391386Z","steps":["trace[2053629516] 'process raft request' (duration: 108.441879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:30.370326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.468683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-31T22:12:30.370422Z","caller":"traceutil/trace.go:171","msg":"trace[1926692376] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:24661; }","duration":"109.630179ms","start":"2026-07-31T22:12:30.260772Z","end":"2026-07-31T22:12:30.370402Z","steps":["trace[1926692376] 'range keys from in-memory index tree' (duration: 109.260118ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:34.951795Z","caller":"traceutil/trace.go:171","msg":"trace[724485740] linearizableReadLoop","detail":"{readStateIndex:25504; appliedIndex:25503; }","duration":"121.341476ms","start":"2026-07-31T22:12:34.83044Z","end":"2026-07-31T22:12:34.951781Z","steps":["trace[724485740] 'read index received' (duration: 121.220583ms)","trace[724485740] 'applied index is now lower than readState.Index' (duration: 120.353µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:34.952088Z","caller":"traceutil/trace.go:171","msg":"trace[956794510] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"124.936804ms","start":"2026-07-31T22:12:34.827142Z","end":"2026-07-31T22:12:34.952079Z","steps":["trace[956794510] 'process raft request' (duration: 124.553905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:34.952275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.866659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-31T22:12:34.952299Z","caller":"traceutil/trace.go:171","msg":"trace[1860828475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24694; }","duration":"121.93979ms","start":"2026-07-31T22:12:34.830352Z","end":"2026-07-31T22:12:34.952292Z","steps":["trace[1860828475] 'agreement among raft nodes before linearized reading' (duration: 121.853158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:36.052378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.076266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:12:36.052646Z","caller":"traceutil/trace.go:171","msg":"trace[1896553825] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"192.112048ms","start":"2026-07-31T22:12:35.86052Z","end":"2026-07-31T22:12:36.052632Z","steps":["trace[1896553825] 'process raft request' (duration: 25.714394ms)","trace[1896553825] 'compare' (duration: 165.649036ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:36.052824Z","caller":"traceutil/trace.go:171","msg":"trace[21223246] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"123.662533ms","start":"2026-07-31T22:12:35.92912Z","end":"2026-07-31T22:12:36.052783Z","steps":["trace[21223246] 'process raft request' (duration: 123.351106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:36.411855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.360527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-31T22:12:36.411923Z","caller":"traceutil/trace.go:171","msg":"trace[1589373826] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24706; }","duration":"208.48611ms","start":"2026-07-31T22:12:36.203424Z","end":"2026-07-31T22:12:36.41191Z","steps":["trace[1589373826] 'range keys from in-memory index tree' (duration: 208.153472ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:36.413364Z","caller":"traceutil/trace.go:171","msg":"trace[1842925322] transaction","detail":"{read_only:false; response_revision:24707; number_of_response:1; }","duration":"199.064079ms","start":"2026-07-31T22:12:36.214271Z","end":"2026-07-31T22:12:36.413335Z","steps":["trace[1842925322] 'process raft request' (duration: 198.891395ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:40.499449Z","caller":"traceutil/trace.go:171","msg":"trace[1200799568] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"137.853052ms","start":"2026-07-31T22:12:40.36155Z","end":"2026-07-31T22:12:40.499403Z","steps":["trace[1200799568] 'process raft request' (duration: 90.106807ms)","trace[1200799568] 'compare' (duration: 47.578781ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:40.747179Z","caller":"traceutil/trace.go:171","msg":"trace[1594260723] linearizableReadLoop","detail":"{readStateIndex:25555; appliedIndex:25554; }","duration":"148.989916ms","start":"2026-07-31T22:12:40.59816Z","end":"2026-07-31T22:12:40.74715Z","steps":["trace[1594260723] 'read index received' (duration: 79.176828ms)","trace[1594260723] 'applied index is now lower than readState.Index' (duration: 69.811888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:40.747302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.125599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:40.747335Z","caller":"traceutil/trace.go:171","msg":"trace[1013077566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24743; }","duration":"149.222522ms","start":"2026-07-31T22:12:40.598101Z","end":"2026-07-31T22:12:40.747324Z","steps":["trace[1013077566] 'agreement among raft nodes before linearized reading' (duration: 149.14567ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:40.747466Z","caller":"traceutil/trace.go:171","msg":"trace[1471987761] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"156.993034ms","start":"2026-07-31T22:12:40.590421Z","end":"2026-07-31T22:12:40.747414Z","steps":["trace[1471987761] 'process raft request' (duration: 87.040712ms)","trace[1471987761] 'compare' (duration: 69.48279ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:41.316342Z","caller":"traceutil/trace.go:171","msg":"trace[647591620] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"281.455756ms","start":"2026-07-31T22:12:41.034864Z","end":"2026-07-31T22:12:41.31632Z","steps":["trace[647591620] 'process raft request' (duration: 281.413265ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:41.31638Z","caller":"traceutil/trace.go:171","msg":"trace[1204390845] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"281.459566ms","start":"2026-07-31T22:12:41.034864Z","end":"2026-07-31T22:12:41.316324Z","steps":["trace[1204390845] 'process raft request' (duration: 281.286902ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:41.378617Z","caller":"traceutil/trace.go:171","msg":"trace[1069919629] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"171.074849ms","start":"2026-07-31T22:12:41.207507Z","end":"2026-07-31T22:12:41.378582Z","steps":["trace[1069919629] 'process raft request' (duration: 171.021138ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:41.378674Z","caller":"traceutil/trace.go:171","msg":"trace[1084157534] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"219.996763ms","start":"2026-07-31T22:12:41.158642Z","end":"2026-07-31T22:12:41.378639Z","steps":["trace[1084157534] 'process raft request' (duration: 219.748727ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:41.532863Z","caller":"traceutil/trace.go:171","msg":"trace[1728087072] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"122.993537ms","start":"2026-07-31T22:12:41.409832Z","end":"2026-07-31T22:12:41.532825Z","steps":["trace[1728087072] 'process raft request' (duration: 70.034364ms)","trace[1728087072] 'compare' (duration: 52.776129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:41.778071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.814037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:12:41.77819Z","caller":"traceutil/trace.go:171","msg":"trace[1581992488] linearizableReadLoop","detail":"{readStateIndex:25564; appliedIndex:25563; }","duration":"179.393344ms","start":"2026-07-31T22:12:41.59878Z","end":"2026-07-31T22:12:41.778173Z","steps":["trace[1581992488] 'read index received' (duration: 66.328341ms)","trace[1581992488] 'applied index is now lower than readState.Index' (duration: 113.063713ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:41.77825Z","caller":"traceutil/trace.go:171","msg":"trace[1039204217] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"199.373476ms","start":"2026-07-31T22:12:41.578848Z","end":"2026-07-31T22:12:41.778221Z","steps":["trace[1039204217] 'process raft request' (duration: 86.310353ms)","trace[1039204217] 'compare' (duration: 112.660413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:41.778282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.502537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:41.778319Z","caller":"traceutil/trace.go:171","msg":"trace[648774557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24752; }","duration":"179.576369ms","start":"2026-07-31T22:12:41.598733Z","end":"2026-07-31T22:12:41.778309Z","steps":["trace[648774557] 'agreement among raft nodes before linearized reading' (duration: 179.513828ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:41.948674Z","caller":"traceutil/trace.go:171","msg":"trace[994847497] linearizableReadLoop","detail":"{readStateIndex:25565; appliedIndex:25564; }","duration":"170.402654ms","start":"2026-07-31T22:12:41.778216Z","end":"2026-07-31T22:12:41.948618Z","steps":["trace[994847497] 'read index received' (duration: 170.091986ms)","trace[994847497] 'applied index is now lower than readState.Index' (duration: 309.288µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:41.948698Z","caller":"traceutil/trace.go:171","msg":"trace[1047256699] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"207.016275ms","start":"2026-07-31T22:12:41.741648Z","end":"2026-07-31T22:12:41.948664Z","steps":["trace[1047256699] 'process raft request' (duration: 206.696567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:41.94889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.390314ms","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-31T22:12:41.948962Z","caller":"traceutil/trace.go:171","msg":"trace[1254512906] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24753; }","duration":"348.473936ms","start":"2026-07-31T22:12:41.600452Z","end":"2026-07-31T22:12:41.948926Z","steps":["trace[1254512906] 'agreement among raft nodes before linearized reading' (duration: 348.323512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:41.948974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.759169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-31T22:12:41.949011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:41.600445Z","time spent":"348.556808ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-31T22:12:41.94905Z","caller":"traceutil/trace.go:171","msg":"trace[1073274501] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24753; }","duration":"293.931484ms","start":"2026-07-31T22:12:41.655098Z","end":"2026-07-31T22:12:41.94903Z","steps":["trace[1073274501] 'agreement among raft nodes before linearized reading' (duration: 293.618726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:41.949043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.673456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:41.949136Z","caller":"traceutil/trace.go:171","msg":"trace[1226279751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24753; }","duration":"169.8131ms","start":"2026-07-31T22:12:41.779299Z","end":"2026-07-31T22:12:41.949113Z","steps":["trace[1226279751] 'agreement among raft nodes before linearized reading' (duration: 169.628985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:42.383733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.16497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:42.383908Z","caller":"traceutil/trace.go:171","msg":"trace[190630783] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24755; }","duration":"153.393186ms","start":"2026-07-31T22:12:42.230492Z","end":"2026-07-31T22:12:42.383885Z","steps":["trace[190630783] 'range keys from in-memory index tree' (duration: 153.099527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:42.747296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.410768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:42.74737Z","caller":"traceutil/trace.go:171","msg":"trace[600065444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24756; }","duration":"147.547641ms","start":"2026-07-31T22:12:42.599805Z","end":"2026-07-31T22:12:42.747353Z","steps":["trace[600065444] 'range keys from in-memory index tree' (duration: 147.316516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:42.747358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.061546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-31T22:12:42.747472Z","caller":"traceutil/trace.go:171","msg":"trace[1066953222] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24756; }","duration":"140.19254ms","start":"2026-07-31T22:12:42.607224Z","end":"2026-07-31T22:12:42.747416Z","steps":["trace[1066953222] 'count revisions from in-memory index tree' (duration: 139.959914ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:43.185855Z","caller":"traceutil/trace.go:171","msg":"trace[1101436367] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"154.792729ms","start":"2026-07-31T22:12:43.031032Z","end":"2026-07-31T22:12:43.185824Z","steps":["trace[1101436367] 'process raft request' (duration: 154.569613ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:43.197032Z","caller":"traceutil/trace.go:171","msg":"trace[1869726705] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25569; }","duration":"115.953193ms","start":"2026-07-31T22:12:43.081056Z","end":"2026-07-31T22:12:43.197009Z","steps":["trace[1869726705] 'read index received' (duration: 104.475811ms)","trace[1869726705] 'applied index is now lower than readState.Index' (duration: 11.476752ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:43.197106Z","caller":"traceutil/trace.go:171","msg":"trace[822858483] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"160.810627ms","start":"2026-07-31T22:12:43.036261Z","end":"2026-07-31T22:12:43.197072Z","steps":["trace[822858483] 'process raft request' (duration: 160.634512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:43.197201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.119067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:43.197285Z","caller":"traceutil/trace.go:171","msg":"trace[387465851] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24759; }","duration":"116.25712ms","start":"2026-07-31T22:12:43.081008Z","end":"2026-07-31T22:12:43.197265Z","steps":["trace[387465851] 'agreement among raft nodes before linearized reading' (duration: 116.088226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:43.197379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.996055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-31T22:12:43.197415Z","caller":"traceutil/trace.go:171","msg":"trace[529514924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24759; }","duration":"114.056726ms","start":"2026-07-31T22:12:43.08335Z","end":"2026-07-31T22:12:43.197407Z","steps":["trace[529514924] 'agreement among raft nodes before linearized reading' (duration: 113.774829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:43.477458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.083919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:12:43.477597Z","caller":"traceutil/trace.go:171","msg":"trace[1430673199] linearizableReadLoop","detail":"{readStateIndex:25572; appliedIndex:25571; }","duration":"218.085006ms","start":"2026-07-31T22:12:43.259495Z","end":"2026-07-31T22:12:43.47758Z","steps":["trace[1430673199] 'read index received' (duration: 68.720001ms)","trace[1430673199] 'applied index is now lower than readState.Index' (duration: 149.363065ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:43.477822Z","caller":"traceutil/trace.go:171","msg":"trace[1399508510] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"275.87281ms","start":"2026-07-31T22:12:43.201931Z","end":"2026-07-31T22:12:43.477804Z","steps":["trace[1399508510] 'process raft request' (duration: 126.347339ms)","trace[1399508510] 'compare' (duration: 148.768462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:43.478055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.579309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:43.478682Z","caller":"traceutil/trace.go:171","msg":"trace[634416495] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24760; }","duration":"218.733963ms","start":"2026-07-31T22:12:43.259388Z","end":"2026-07-31T22:12:43.478122Z","steps":["trace[634416495] 'agreement among raft nodes before linearized reading' (duration: 218.545338ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:43.572387Z","caller":"traceutil/trace.go:171","msg":"trace[717181708] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"189.196966ms","start":"2026-07-31T22:12:43.383161Z","end":"2026-07-31T22:12:43.572358Z","steps":["trace[717181708] 'process raft request' (duration: 189.012161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:43.574101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.422361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T22:12:43.574123Z","caller":"traceutil/trace.go:171","msg":"trace[1632399097] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"189.350641ms","start":"2026-07-31T22:12:43.384735Z","end":"2026-07-31T22:12:43.574086Z","steps":["trace[1632399097] 'process raft request' (duration: 188.90682ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:43.574203Z","caller":"traceutil/trace.go:171","msg":"trace[742945690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24762; }","duration":"254.595635ms","start":"2026-07-31T22:12:43.319585Z","end":"2026-07-31T22:12:43.57418Z","steps":["trace[742945690] 'agreement among raft nodes before linearized reading' (duration: 254.321328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:43.574282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.656227ms","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-31T22:12:43.57436Z","caller":"traceutil/trace.go:171","msg":"trace[49858738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24762; }","duration":"254.78556ms","start":"2026-07-31T22:12:43.319556Z","end":"2026-07-31T22:12:43.574342Z","steps":["trace[49858738] 'agreement among raft nodes before linearized reading' (duration: 254.568335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:43.574543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.762562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-07-31T22:12:43.57463Z","caller":"traceutil/trace.go:171","msg":"trace[1981206159] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24762; }","duration":"209.899186ms","start":"2026-07-31T22:12:43.364713Z","end":"2026-07-31T22:12:43.574612Z","steps":["trace[1981206159] 'agreement among raft nodes before linearized reading' (duration: 209.413104ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:43.821108Z","caller":"traceutil/trace.go:171","msg":"trace[1808440049] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"242.055556ms","start":"2026-07-31T22:12:43.579027Z","end":"2026-07-31T22:12:43.821083Z","steps":["trace[1808440049] 'process raft request' (duration: 241.889031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.041575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.851789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:12:44.041658Z","caller":"traceutil/trace.go:171","msg":"trace[1698194349] linearizableReadLoop","detail":"{readStateIndex:25576; appliedIndex:25574; }","duration":"442.611772ms","start":"2026-07-31T22:12:43.599028Z","end":"2026-07-31T22:12:44.04164Z","steps":["trace[1698194349] 'read index received' (duration: 221.961701ms)","trace[1698194349] 'applied index is now lower than readState.Index' (duration: 220.648961ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:44.041709Z","caller":"traceutil/trace.go:171","msg":"trace[1048345229] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"462.513651ms","start":"2026-07-31T22:12:43.579189Z","end":"2026-07-31T22:12:44.041703Z","steps":["trace[1048345229] 'process raft request' (duration: 316.447937ms)","trace[1048345229] 'compare' (duration: 145.761577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:44.041743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:43.579159Z","time spent":"462.566853ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:12:44.041889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.837828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:44.042003Z","caller":"traceutil/trace.go:171","msg":"trace[166051021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24764; }","duration":"442.997832ms","start":"2026-07-31T22:12:43.598971Z","end":"2026-07-31T22:12:44.041968Z","steps":["trace[166051021] 'agreement among raft nodes before linearized reading' (duration: 442.826538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.042061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:43.598956Z","time spent":"443.095954ms","remote":"127.0.0.1:49836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T22:12:44.108444Z","caller":"traceutil/trace.go:171","msg":"trace[591781430] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"154.106592ms","start":"2026-07-31T22:12:43.954307Z","end":"2026-07-31T22:12:44.108414Z","steps":["trace[591781430] 'process raft request' (duration: 153.946288ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:44.38494Z","caller":"traceutil/trace.go:171","msg":"trace[1293476071] linearizableReadLoop","detail":"{readStateIndex:25578; appliedIndex:25577; }","duration":"343.240506ms","start":"2026-07-31T22:12:44.041674Z","end":"2026-07-31T22:12:44.384914Z","steps":["trace[1293476071] 'read index received' (duration: 66.805953ms)","trace[1293476071] 'applied index is now lower than readState.Index' (duration: 276.433603ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:44.384992Z","caller":"traceutil/trace.go:171","msg":"trace[1198353516] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"426.424954ms","start":"2026-07-31T22:12:43.958536Z","end":"2026-07-31T22:12:44.384961Z","steps":["trace[1198353516] 'process raft request' (duration: 426.030364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.385156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:43.958508Z","time spent":"426.545247ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:12:44.385161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.393424ms","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-31T22:12:44.385215Z","caller":"traceutil/trace.go:171","msg":"trace[1775295975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24766; }","duration":"602.502057ms","start":"2026-07-31T22:12:43.782698Z","end":"2026-07-31T22:12:44.3852Z","steps":["trace[1775295975] 'agreement among raft nodes before linearized reading' (duration: 602.318612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.385254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:43.78268Z","time spent":"602.564158ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T22:12:44.711254Z","caller":"traceutil/trace.go:171","msg":"trace[247724757] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"524.375474ms","start":"2026-07-31T22:12:44.186852Z","end":"2026-07-31T22:12:44.711227Z","steps":["trace[247724757] 'process raft request' (duration: 524.286812ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:44.711356Z","caller":"traceutil/trace.go:171","msg":"trace[92423347] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"595.612027ms","start":"2026-07-31T22:12:44.115709Z","end":"2026-07-31T22:12:44.711321Z","steps":["trace[92423347] 'process raft request' (duration: 595.212048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.711406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.186831Z","time spent":"524.494876ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:12:44.711502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.115681Z","time spent":"595.741631ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:12:44.711526Z","caller":"traceutil/trace.go:171","msg":"trace[1367796396] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"558.780801ms","start":"2026-07-31T22:12:44.152696Z","end":"2026-07-31T22:12:44.711477Z","steps":["trace[1367796396] 'process raft request' (duration: 558.387722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.711643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.15266Z","time spent":"558.924785ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:12:44.993538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.987017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:12:44.993839Z","caller":"traceutil/trace.go:171","msg":"trace[1928291331] linearizableReadLoop","detail":"{readStateIndex:25584; appliedIndex:25578; }","duration":"397.115203ms","start":"2026-07-31T22:12:44.596705Z","end":"2026-07-31T22:12:44.99382Z","steps":["trace[1928291331] 'read index received' (duration: 114.19722ms)","trace[1928291331] 'applied index is now lower than readState.Index' (duration: 282.916992ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:44.994033Z","caller":"traceutil/trace.go:171","msg":"trace[1256279050] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"451.228414ms","start":"2026-07-31T22:12:44.542793Z","end":"2026-07-31T22:12:44.994021Z","steps":["trace[1256279050] 'process raft request' (duration: 450.864656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.994109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.542775Z","time spent":"451.291055ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:12:44.994231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.498892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:44.994266Z","caller":"traceutil/trace.go:171","msg":"trace[1121554548] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"604.375724ms","start":"2026-07-31T22:12:44.389859Z","end":"2026-07-31T22:12:44.994235Z","steps":["trace[1121554548] 'process raft request' (duration: 428.518056ms)","trace[1121554548] 'compare' (duration: 174.511784ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:44.994274Z","caller":"traceutil/trace.go:171","msg":"trace[455565238] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"403.284764ms","start":"2026-07-31T22:12:44.590959Z","end":"2026-07-31T22:12:44.994244Z","steps":["trace[455565238] 'process raft request' (duration: 402.807552ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:44.994312Z","caller":"traceutil/trace.go:171","msg":"trace[1938816653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24772; }","duration":"397.646086ms","start":"2026-07-31T22:12:44.596634Z","end":"2026-07-31T22:12:44.99428Z","steps":["trace[1938816653] 'agreement among raft nodes before linearized reading' (duration: 397.520603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.994343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.596613Z","time spent":"397.722417ms","remote":"127.0.0.1:49834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T22:12:44.994375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.765297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T22:12:44.994413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.091313ms","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-31T22:12:44.994427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.326117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18557"} {"level":"info","ts":"2026-07-31T22:12:44.994467Z","caller":"traceutil/trace.go:171","msg":"trace[699597678] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24772; }","duration":"297.909371ms","start":"2026-07-31T22:12:44.69654Z","end":"2026-07-31T22:12:44.994449Z","steps":["trace[699597678] 'agreement among raft nodes before linearized reading' (duration: 297.743367ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:44.994474Z","caller":"traceutil/trace.go:171","msg":"trace[1107982825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24772; }","duration":"137.201746ms","start":"2026-07-31T22:12:44.857256Z","end":"2026-07-31T22:12:44.994457Z","steps":["trace[1107982825] 'agreement among raft nodes before linearized reading' (duration: 136.994831ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:44.994501Z","caller":"traceutil/trace.go:171","msg":"trace[784941089] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24772; }","duration":"336.406609ms","start":"2026-07-31T22:12:44.658077Z","end":"2026-07-31T22:12:44.994484Z","steps":["trace[784941089] 'agreement among raft nodes before linearized reading' (duration: 336.151242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.994485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.978818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-07-31T22:12:44.994563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.658008Z","time spent":"336.545761ms","remote":"127.0.0.1:50024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18579,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-31T22:12:44.994618Z","caller":"traceutil/trace.go:171","msg":"trace[303400146] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24772; }","duration":"262.178152ms","start":"2026-07-31T22:12:44.732411Z","end":"2026-07-31T22:12:44.994589Z","steps":["trace[303400146] 'agreement among raft nodes before linearized reading' (duration: 261.865075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:44.994401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.389835Z","time spent":"604.492496ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:12:44.994404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:12:44.590935Z","time spent":"403.400507ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:12:45.178761Z","caller":"traceutil/trace.go:171","msg":"trace[1811203675] linearizableReadLoop","detail":"{readStateIndex:25585; appliedIndex:25584; }","duration":"179.055956ms","start":"2026-07-31T22:12:44.999684Z","end":"2026-07-31T22:12:45.178739Z","steps":["trace[1811203675] 'read index received' (duration: 141.782138ms)","trace[1811203675] 'applied index is now lower than readState.Index' (duration: 37.272968ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:45.178821Z","caller":"traceutil/trace.go:171","msg":"trace[116975114] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"180.104703ms","start":"2026-07-31T22:12:44.998673Z","end":"2026-07-31T22:12:45.178778Z","steps":["trace[116975114] 'process raft request' (duration: 142.842535ms)","trace[116975114] 'compare' (duration: 37.126055ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:45.179004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.304293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18557"} {"level":"info","ts":"2026-07-31T22:12:45.179074Z","caller":"traceutil/trace.go:171","msg":"trace[1083461639] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24773; }","duration":"179.420205ms","start":"2026-07-31T22:12:44.999637Z","end":"2026-07-31T22:12:45.179057Z","steps":["trace[1083461639] 'agreement among raft nodes before linearized reading' (duration: 179.177769ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:45.340717Z","caller":"traceutil/trace.go:171","msg":"trace[1510139899] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"149.191442ms","start":"2026-07-31T22:12:45.191499Z","end":"2026-07-31T22:12:45.340691Z","steps":["trace[1510139899] 'process raft request' (duration: 149.024857ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:45.353651Z","caller":"traceutil/trace.go:171","msg":"trace[1435881231] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"152.076072ms","start":"2026-07-31T22:12:45.201546Z","end":"2026-07-31T22:12:45.353622Z","steps":["trace[1435881231] 'process raft request' (duration: 151.942269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:45.641871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.849794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:12:45.64207Z","caller":"traceutil/trace.go:171","msg":"trace[335667595] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"266.58071ms","start":"2026-07-31T22:12:45.375447Z","end":"2026-07-31T22:12:45.642028Z","steps":["trace[335667595] 'process raft request' (duration: 132.513461ms)","trace[335667595] 'compare' (duration: 133.70241ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:45.770318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.369739ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-31T22:12:45.770401Z","caller":"traceutil/trace.go:171","msg":"trace[610224959] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25587; }","duration":"288.52197ms","start":"2026-07-31T22:12:45.481867Z","end":"2026-07-31T22:12:45.770389Z","steps":["trace[610224959] 'read index received' (duration: 26.097952ms)","trace[610224959] 'applied index is now lower than readState.Index' (duration: 262.422958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:12:45.771533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.672698ms","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-31T22:12:45.771569Z","caller":"traceutil/trace.go:171","msg":"trace[1125481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24776; }","duration":"289.75684ms","start":"2026-07-31T22:12:45.481804Z","end":"2026-07-31T22:12:45.771561Z","steps":["trace[1125481] 'agreement among raft nodes before linearized reading' (duration: 289.633837ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:45.780177Z","caller":"traceutil/trace.go:171","msg":"trace[830162153] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"258.548173ms","start":"2026-07-31T22:12:45.521618Z","end":"2026-07-31T22:12:45.780166Z","steps":["trace[830162153] 'process raft request' (duration: 258.416389ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:45.780429Z","caller":"traceutil/trace.go:171","msg":"trace[687743035] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"202.536564ms","start":"2026-07-31T22:12:45.57786Z","end":"2026-07-31T22:12:45.780396Z","steps":["trace[687743035] 'process raft request' (duration: 202.275358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:45.781869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.532417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:12:45.781904Z","caller":"traceutil/trace.go:171","msg":"trace[1312629425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24779; }","duration":"183.603468ms","start":"2026-07-31T22:12:45.598291Z","end":"2026-07-31T22:12:45.781894Z","steps":["trace[1312629425] 'agreement among raft nodes before linearized reading' (duration: 183.549447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:45.781894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.161514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T22:12:45.781966Z","caller":"traceutil/trace.go:171","msg":"trace[1664725164] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24779; }","duration":"269.350188ms","start":"2026-07-31T22:12:45.512598Z","end":"2026-07-31T22:12:45.781948Z","steps":["trace[1664725164] 'agreement among raft nodes before linearized reading' (duration: 269.140203ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:45.782011Z","caller":"traceutil/trace.go:171","msg":"trace[506216689] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"201.95732ms","start":"2026-07-31T22:12:45.580044Z","end":"2026-07-31T22:12:45.782001Z","steps":["trace[506216689] 'process raft request' (duration: 201.501259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:12:45.782247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.197271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T22:12:45.782273Z","caller":"traceutil/trace.go:171","msg":"trace[62538161] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24779; }","duration":"229.253231ms","start":"2026-07-31T22:12:45.553011Z","end":"2026-07-31T22:12:45.782265Z","steps":["trace[62538161] 'agreement among raft nodes before linearized reading' (duration: 229.20891ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:12:45.915939Z","caller":"traceutil/trace.go:171","msg":"trace[1428375631] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"139.666987ms","start":"2026-07-31T22:12:45.776235Z","end":"2026-07-31T22:12:45.915902Z","steps":["trace[1428375631] 'process raft request' (duration: 129.430265ms)","trace[1428375631] 'compare' (duration: 10.110199ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:12:59.949174Z","caller":"traceutil/trace.go:171","msg":"trace[1817838398] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"118.160738ms","start":"2026-07-31T22:12:59.830988Z","end":"2026-07-31T22:12:59.949148Z","steps":["trace[1817838398] 'process raft request' (duration: 117.995494ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:00.298162Z","caller":"traceutil/trace.go:171","msg":"trace[69590716] linearizableReadLoop","detail":"{readStateIndex:25755; appliedIndex:25754; }","duration":"192.142999ms","start":"2026-07-31T22:13:00.105996Z","end":"2026-07-31T22:13:00.298139Z","steps":["trace[69590716] 'read index received' (duration: 103.659311ms)","trace[69590716] 'applied index is now lower than readState.Index' (duration: 88.482798ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:00.298318Z","caller":"traceutil/trace.go:171","msg":"trace[332755420] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"227.95234ms","start":"2026-07-31T22:13:00.070327Z","end":"2026-07-31T22:13:00.298279Z","steps":["trace[332755420] 'process raft request' (duration: 139.332078ms)","trace[332755420] 'compare' (duration: 88.095379ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:00.298338Z","caller":"traceutil/trace.go:171","msg":"trace[576779010] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"116.696222ms","start":"2026-07-31T22:13:00.181615Z","end":"2026-07-31T22:13:00.298311Z","steps":["trace[576779010] 'process raft request' (duration: 116.454146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:00.298449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.440506ms","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-31T22:13:00.298528Z","caller":"traceutil/trace.go:171","msg":"trace[1867611236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24941; }","duration":"192.569979ms","start":"2026-07-31T22:13:00.105938Z","end":"2026-07-31T22:13:00.298508Z","steps":["trace[1867611236] 'agreement among raft nodes before linearized reading' (duration: 192.352804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:00.298591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.335123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T22:13:00.298648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.335493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8e649b7-7b6c-4ae7-87d4-2040d01425b7\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-31T22:13:00.298696Z","caller":"traceutil/trace.go:171","msg":"trace[1536201649] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8e649b7-7b6c-4ae7-87d4-2040d01425b7; range_end:; response_count:1; response_revision:24941; }","duration":"142.414484ms","start":"2026-07-31T22:13:00.156268Z","end":"2026-07-31T22:13:00.298683Z","steps":["trace[1536201649] 'agreement among raft nodes before linearized reading' (duration: 142.24562ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:00.298743Z","caller":"traceutil/trace.go:171","msg":"trace[945542115] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24941; }","duration":"177.568149ms","start":"2026-07-31T22:13:00.121102Z","end":"2026-07-31T22:13:00.29867Z","steps":["trace[945542115] 'agreement among raft nodes before linearized reading' (duration: 177.277902ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:00.584579Z","caller":"traceutil/trace.go:171","msg":"trace[1586684612] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"281.506157ms","start":"2026-07-31T22:13:00.303049Z","end":"2026-07-31T22:13:00.584555Z","steps":["trace[1586684612] 'process raft request' (duration: 279.437866ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:00.584592Z","caller":"traceutil/trace.go:171","msg":"trace[553814468] linearizableReadLoop","detail":"{readStateIndex:25760; appliedIndex:25757; }","duration":"211.543125ms","start":"2026-07-31T22:13:00.373027Z","end":"2026-07-31T22:13:00.58457Z","steps":["trace[553814468] 'read index received' (duration: 209.474804ms)","trace[553814468] 'applied index is now lower than readState.Index' (duration: 2.067661ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:00.584628Z","caller":"traceutil/trace.go:171","msg":"trace[295917117] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"281.013175ms","start":"2026-07-31T22:13:00.303582Z","end":"2026-07-31T22:13:00.584595Z","steps":["trace[295917117] 'process raft request' (duration: 280.842351ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:00.584702Z","caller":"traceutil/trace.go:171","msg":"trace[1396460359] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"241.880492ms","start":"2026-07-31T22:13:00.342808Z","end":"2026-07-31T22:13:00.584689Z","steps":["trace[1396460359] 'process raft request' (duration: 241.716578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:00.584961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.923194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:00.585004Z","caller":"traceutil/trace.go:171","msg":"trace[188785268] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24944; }","duration":"212.023887ms","start":"2026-07-31T22:13:00.372967Z","end":"2026-07-31T22:13:00.584991Z","steps":["trace[188785268] 'agreement among raft nodes before linearized reading' (duration: 211.943555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:00.585289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.678038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:00.585375Z","caller":"traceutil/trace.go:171","msg":"trace[1994528583] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24944; }","duration":"139.80298ms","start":"2026-07-31T22:13:00.445551Z","end":"2026-07-31T22:13:00.585354Z","steps":["trace[1994528583] 'agreement among raft nodes before linearized reading' (duration: 139.632976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:00.839768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.121116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:00.839858Z","caller":"traceutil/trace.go:171","msg":"trace[8331737] linearizableReadLoop","detail":"{readStateIndex:25761; appliedIndex:25760; }","duration":"151.764864ms","start":"2026-07-31T22:13:00.688081Z","end":"2026-07-31T22:13:00.839846Z","steps":["trace[8331737] 'read index received' (duration: 31.430043ms)","trace[8331737] 'applied index is now lower than readState.Index' (duration: 120.333491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:00.839938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.853497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:00.839964Z","caller":"traceutil/trace.go:171","msg":"trace[1563502002] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24945; }","duration":"151.912998ms","start":"2026-07-31T22:13:00.688041Z","end":"2026-07-31T22:13:00.839954Z","steps":["trace[1563502002] 'agreement among raft nodes before linearized reading' (duration: 151.857567ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:00.83994Z","caller":"traceutil/trace.go:171","msg":"trace[1981607202] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"192.342143ms","start":"2026-07-31T22:13:00.647564Z","end":"2026-07-31T22:13:00.839907Z","steps":["trace[1981607202] 'process raft request' (duration: 72.010481ms)","trace[1981607202] 'compare' (duration: 120.014154ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:00.894129Z","caller":"traceutil/trace.go:171","msg":"trace[950021542] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"132.995363ms","start":"2026-07-31T22:13:00.761109Z","end":"2026-07-31T22:13:00.894104Z","steps":["trace[950021542] 'process raft request' (duration: 132.85017ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:01.097283Z","caller":"traceutil/trace.go:171","msg":"trace[317964348] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"318.425046ms","start":"2026-07-31T22:13:00.778821Z","end":"2026-07-31T22:13:01.097246Z","steps":["trace[317964348] 'process raft request' (duration: 318.060188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:01.097449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:00.77879Z","time spent":"318.569639ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:01.097481Z","caller":"traceutil/trace.go:171","msg":"trace[393534833] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"299.223563ms","start":"2026-07-31T22:13:00.798224Z","end":"2026-07-31T22:13:01.097448Z","steps":["trace[393534833] 'process raft request' (duration: 298.841774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:01.097626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.519203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T22:13:01.097697Z","caller":"traceutil/trace.go:171","msg":"trace[516093003] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:24948; }","duration":"282.603915ms","start":"2026-07-31T22:13:00.815078Z","end":"2026-07-31T22:13:01.097682Z","steps":["trace[516093003] 'agreement among raft nodes before linearized reading' (duration: 282.437881ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:01.097369Z","caller":"traceutil/trace.go:171","msg":"trace[1396334778] linearizableReadLoop","detail":"{readStateIndex:25764; appliedIndex:25761; }","duration":"257.476186ms","start":"2026-07-31T22:13:00.839875Z","end":"2026-07-31T22:13:01.097351Z","steps":["trace[1396334778] 'read index received' (duration: 54.134593ms)","trace[1396334778] 'applied index is now lower than readState.Index' (duration: 203.340683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:01.392532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.693383ms","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-31T22:13:01.392849Z","caller":"traceutil/trace.go:171","msg":"trace[956024357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24950; }","duration":"139.017621ms","start":"2026-07-31T22:13:01.253777Z","end":"2026-07-31T22:13:01.392795Z","steps":["trace[956024357] 'range keys from in-memory index tree' (duration: 138.520008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:01.792384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.95606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8e649b7-7b6c-4ae7-87d4-2040d01425b7\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-07-31T22:13:01.792445Z","caller":"traceutil/trace.go:171","msg":"trace[624844813] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8e649b7-7b6c-4ae7-87d4-2040d01425b7; range_end:; response_count:1; response_revision:24953; }","duration":"161.072893ms","start":"2026-07-31T22:13:01.63136Z","end":"2026-07-31T22:13:01.792433Z","steps":["trace[624844813] 'range keys from in-memory index tree' (duration: 160.782486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:01.792595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.740072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:01.792664Z","caller":"traceutil/trace.go:171","msg":"trace[1943403281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24953; }","duration":"192.839945ms","start":"2026-07-31T22:13:01.599807Z","end":"2026-07-31T22:13:01.792646Z","steps":["trace[1943403281] 'range keys from in-memory index tree' (duration: 192.65227ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:03.271646Z","caller":"traceutil/trace.go:171","msg":"trace[1286334829] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"372.012385ms","start":"2026-07-31T22:13:02.89961Z","end":"2026-07-31T22:13:03.271622Z","steps":["trace[1286334829] 'process raft request' (duration: 371.867622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:03.271816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:02.899585Z","time spent":"372.159988ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:03.284369Z","caller":"traceutil/trace.go:171","msg":"trace[2081278690] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"180.618845ms","start":"2026-07-31T22:13:03.103725Z","end":"2026-07-31T22:13:03.284344Z","steps":["trace[2081278690] 'process raft request' (duration: 180.440161ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:03.284593Z","caller":"traceutil/trace.go:171","msg":"trace[408011494] linearizableReadLoop","detail":"{readStateIndex:25779; appliedIndex:25776; }","duration":"109.72913ms","start":"2026-07-31T22:13:03.174827Z","end":"2026-07-31T22:13:03.284556Z","steps":["trace[408011494] 'read index received' (duration: 96.72515ms)","trace[408011494] 'applied index is now lower than readState.Index' (duration: 13.0026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:03.284719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.876864ms","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-31T22:13:03.284672Z","caller":"traceutil/trace.go:171","msg":"trace[591785824] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"127.262051ms","start":"2026-07-31T22:13:03.157384Z","end":"2026-07-31T22:13:03.284646Z","steps":["trace[591785824] 'process raft request' (duration: 126.922403ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:03.28476Z","caller":"traceutil/trace.go:171","msg":"trace[2035058026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24963; }","duration":"109.962656ms","start":"2026-07-31T22:13:03.174786Z","end":"2026-07-31T22:13:03.284749Z","steps":["trace[2035058026] 'agreement among raft nodes before linearized reading' (duration: 109.872854ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:08.267648Z","caller":"traceutil/trace.go:171","msg":"trace[1201841611] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"106.658564ms","start":"2026-07-31T22:13:08.160965Z","end":"2026-07-31T22:13:08.267623Z","steps":["trace[1201841611] 'process raft request' (duration: 106.511281ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:08.438531Z","caller":"traceutil/trace.go:171","msg":"trace[1372499055] linearizableReadLoop","detail":"{readStateIndex:25839; appliedIndex:25838; }","duration":"109.170346ms","start":"2026-07-31T22:13:08.329338Z","end":"2026-07-31T22:13:08.438508Z","steps":["trace[1372499055] 'read index received' (duration: 108.965221ms)","trace[1372499055] 'applied index is now lower than readState.Index' (duration: 204.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:08.438573Z","caller":"traceutil/trace.go:171","msg":"trace[1711798997] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"115.919703ms","start":"2026-07-31T22:13:08.322618Z","end":"2026-07-31T22:13:08.438537Z","steps":["trace[1711798997] 'process raft request' (duration: 115.743159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:08.438773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.416333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T22:13:08.438819Z","caller":"traceutil/trace.go:171","msg":"trace[1316950457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25022; }","duration":"109.507155ms","start":"2026-07-31T22:13:08.329298Z","end":"2026-07-31T22:13:08.438805Z","steps":["trace[1316950457] 'agreement among raft nodes before linearized reading' (duration: 109.33711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:08.739104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.345896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:08.739265Z","caller":"traceutil/trace.go:171","msg":"trace[539395156] linearizableReadLoop","detail":"{readStateIndex:25840; appliedIndex:25839; }","duration":"288.210133ms","start":"2026-07-31T22:13:08.451036Z","end":"2026-07-31T22:13:08.739246Z","steps":["trace[539395156] 'read index received' (duration: 118.517917ms)","trace[539395156] 'applied index is now lower than readState.Index' (duration: 169.690516ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:08.73938Z","caller":"traceutil/trace.go:171","msg":"trace[1785614081] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"297.290215ms","start":"2026-07-31T22:13:08.442066Z","end":"2026-07-31T22:13:08.739356Z","steps":["trace[1785614081] 'process raft request' (duration: 127.57935ms)","trace[1785614081] 'compare' (duration: 169.150732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:08.739425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.861642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:08.739462Z","caller":"traceutil/trace.go:171","msg":"trace[1499127016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25023; }","duration":"141.937473ms","start":"2026-07-31T22:13:08.597512Z","end":"2026-07-31T22:13:08.73945Z","steps":["trace[1499127016] 'agreement among raft nodes before linearized reading' (duration: 141.857911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:08.7395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.441098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-31T22:13:08.739596Z","caller":"traceutil/trace.go:171","msg":"trace[779076748] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25023; }","duration":"288.557721ms","start":"2026-07-31T22:13:08.451005Z","end":"2026-07-31T22:13:08.739563Z","steps":["trace[779076748] 'agreement among raft nodes before linearized reading' (duration: 288.319686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:08.739602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.201257ms","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-31T22:13:08.739691Z","caller":"traceutil/trace.go:171","msg":"trace[867712908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25023; }","duration":"122.33476ms","start":"2026-07-31T22:13:08.617336Z","end":"2026-07-31T22:13:08.739671Z","steps":["trace[867712908] 'agreement among raft nodes before linearized reading' (duration: 122.043073ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:21.546739Z","caller":"traceutil/trace.go:171","msg":"trace[871485937] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"212.527229ms","start":"2026-07-31T22:13:21.334192Z","end":"2026-07-31T22:13:21.546719Z","steps":["trace[871485937] 'process raft request' (duration: 212.389026ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:21.678539Z","caller":"traceutil/trace.go:171","msg":"trace[1268180413] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"343.517212ms","start":"2026-07-31T22:13:21.334996Z","end":"2026-07-31T22:13:21.678513Z","steps":["trace[1268180413] 'process raft request' (duration: 343.212694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:21.678665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:21.334972Z","time spent":"343.627965ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:21.678781Z","caller":"traceutil/trace.go:171","msg":"trace[835070882] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"328.981084ms","start":"2026-07-31T22:13:21.349744Z","end":"2026-07-31T22:13:21.678725Z","steps":["trace[835070882] 'process raft request' (duration: 328.613105ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:21.678833Z","caller":"traceutil/trace.go:171","msg":"trace[1071888668] linearizableReadLoop","detail":"{readStateIndex:25946; appliedIndex:25943; }","duration":"241.87708ms","start":"2026-07-31T22:13:21.436939Z","end":"2026-07-31T22:13:21.678816Z","steps":["trace[1071888668] 'read index received' (duration: 109.703839ms)","trace[1071888668] 'applied index is now lower than readState.Index' (duration: 132.172021ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:21.678922Z","caller":"traceutil/trace.go:171","msg":"trace[1078719819] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"180.360316ms","start":"2026-07-31T22:13:21.498553Z","end":"2026-07-31T22:13:21.678913Z","steps":["trace[1078719819] 'process raft request' (duration: 180.07582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:21.678938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:21.34973Z","time spent":"329.118907ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:13:21.679019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.079546ms","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-31T22:13:21.679053Z","caller":"traceutil/trace.go:171","msg":"trace[667160411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25128; }","duration":"242.163728ms","start":"2026-07-31T22:13:21.436878Z","end":"2026-07-31T22:13:21.679041Z","steps":["trace[667160411] 'agreement among raft nodes before linearized reading' (duration: 242.041245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:21.67908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.66892ms","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-31T22:13:21.679116Z","caller":"traceutil/trace.go:171","msg":"trace[945756291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25128; }","duration":"179.724631ms","start":"2026-07-31T22:13:21.499384Z","end":"2026-07-31T22:13:21.679109Z","steps":["trace[945756291] 'agreement among raft nodes before linearized reading' (duration: 179.604518ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:21.67868Z","caller":"traceutil/trace.go:171","msg":"trace[2028813720] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"181.606168ms","start":"2026-07-31T22:13:21.497054Z","end":"2026-07-31T22:13:21.67866Z","steps":["trace[2028813720] 'process raft request' (duration: 181.535177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:21.859094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.451215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:21.859248Z","caller":"traceutil/trace.go:171","msg":"trace[434331071] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25948; }","duration":"169.972102ms","start":"2026-07-31T22:13:21.689258Z","end":"2026-07-31T22:13:21.85923Z","steps":["trace[434331071] 'read index received' (duration: 64.414545ms)","trace[434331071] 'applied index is now lower than readState.Index' (duration: 105.556597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:21.859337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.107885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:21.859365Z","caller":"traceutil/trace.go:171","msg":"trace[1186653259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25130; }","duration":"170.190038ms","start":"2026-07-31T22:13:21.689167Z","end":"2026-07-31T22:13:21.859357Z","steps":["trace[1186653259] 'agreement among raft nodes before linearized reading' (duration: 170.113796ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:21.859615Z","caller":"traceutil/trace.go:171","msg":"trace[67044954] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"170.463795ms","start":"2026-07-31T22:13:21.689139Z","end":"2026-07-31T22:13:21.859603Z","steps":["trace[67044954] 'process raft request' (duration: 64.408655ms)","trace[67044954] 'compare' (duration: 105.339431ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:21.859719Z","caller":"traceutil/trace.go:171","msg":"trace[1802682310] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"170.524286ms","start":"2026-07-31T22:13:21.689188Z","end":"2026-07-31T22:13:21.859712Z","steps":["trace[1802682310] 'process raft request' (duration: 169.995872ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:22.146826Z","caller":"traceutil/trace.go:171","msg":"trace[1633689655] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"160.96394ms","start":"2026-07-31T22:13:21.985836Z","end":"2026-07-31T22:13:22.146799Z","steps":["trace[1633689655] 'process raft request' (duration: 126.955643ms)","trace[1633689655] 'compare' (duration: 33.71677ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:22.301465Z","caller":"traceutil/trace.go:171","msg":"trace[1842167705] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"242.071657ms","start":"2026-07-31T22:13:22.059362Z","end":"2026-07-31T22:13:22.301434Z","steps":["trace[1842167705] 'process raft request' (duration: 215.939674ms)","trace[1842167705] 'compare' (duration: 25.958448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:22.631959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.386013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:22.632049Z","caller":"traceutil/trace.go:171","msg":"trace[1675446010] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25952; }","duration":"152.362639ms","start":"2026-07-31T22:13:22.479671Z","end":"2026-07-31T22:13:22.632033Z","steps":["trace[1675446010] 'read index received' (duration: 33.831313ms)","trace[1675446010] 'applied index is now lower than readState.Index' (duration: 118.530206ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:22.632456Z","caller":"traceutil/trace.go:171","msg":"trace[705659929] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"304.431011ms","start":"2026-07-31T22:13:22.327979Z","end":"2026-07-31T22:13:22.63241Z","steps":["trace[705659929] 'process raft request' (duration: 185.513765ms)","trace[705659929] 'compare' (duration: 118.25903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:22.632477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.776729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:22.632573Z","caller":"traceutil/trace.go:171","msg":"trace[1889254052] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25133; }","duration":"152.924163ms","start":"2026-07-31T22:13:22.479627Z","end":"2026-07-31T22:13:22.632551Z","steps":["trace[1889254052] 'agreement among raft nodes before linearized reading' (duration: 152.768419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:22.632641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:22.327951Z","time spent":"304.589494ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:22.708405Z","caller":"traceutil/trace.go:171","msg":"trace[1505860544] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"221.617305ms","start":"2026-07-31T22:13:22.486762Z","end":"2026-07-31T22:13:22.70838Z","steps":["trace[1505860544] 'process raft request' (duration: 221.384838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:22.70846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.31954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:22.708511Z","caller":"traceutil/trace.go:171","msg":"trace[1705068136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25134; }","duration":"109.428602ms","start":"2026-07-31T22:13:22.59907Z","end":"2026-07-31T22:13:22.708498Z","steps":["trace[1705068136] 'agreement among raft nodes before linearized reading' (duration: 109.307759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:22.915631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.760348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-31T22:13:22.915659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.642266ms","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-31T22:13:22.915695Z","caller":"traceutil/trace.go:171","msg":"trace[2142087256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25134; }","duration":"117.857501ms","start":"2026-07-31T22:13:22.797821Z","end":"2026-07-31T22:13:22.915679Z","steps":["trace[2142087256] 'range keys from in-memory index tree' (duration: 117.604854ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:22.91572Z","caller":"traceutil/trace.go:171","msg":"trace[1372064672] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25134; }","duration":"143.751239ms","start":"2026-07-31T22:13:22.771957Z","end":"2026-07-31T22:13:22.915708Z","steps":["trace[1372064672] 'range keys from in-memory index tree' (duration: 143.560964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:22.915782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.34738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-07-31T22:13:22.915852Z","caller":"traceutil/trace.go:171","msg":"trace[1332401910] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25134; }","duration":"152.451433ms","start":"2026-07-31T22:13:22.763383Z","end":"2026-07-31T22:13:22.915834Z","steps":["trace[1332401910] 'range keys from in-memory index tree' (duration: 152.216277ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:23.026131Z","caller":"traceutil/trace.go:171","msg":"trace[1861175717] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"106.362927ms","start":"2026-07-31T22:13:22.919747Z","end":"2026-07-31T22:13:23.02611Z","steps":["trace[1861175717] 'process raft request' (duration: 106.199953ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:23.260662Z","caller":"traceutil/trace.go:171","msg":"trace[1810945499] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25954; }","duration":"338.497739ms","start":"2026-07-31T22:13:22.922137Z","end":"2026-07-31T22:13:23.260634Z","steps":["trace[1810945499] 'read index received' (duration: 103.860975ms)","trace[1810945499] 'applied index is now lower than readState.Index' (duration: 234.635424ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:23.260988Z","caller":"traceutil/trace.go:171","msg":"trace[1914607109] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"340.840887ms","start":"2026-07-31T22:13:22.920133Z","end":"2026-07-31T22:13:23.260974Z","steps":["trace[1914607109] 'process raft request' (duration: 296.438414ms)","trace[1914607109] 'compare' (duration: 43.928822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:23.261101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:22.92011Z","time spent":"340.918249ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:13:23.261177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.017072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-31T22:13:23.261169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.441948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:23.261261Z","caller":"traceutil/trace.go:171","msg":"trace[1819501026] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25136; }","duration":"339.121215ms","start":"2026-07-31T22:13:22.92212Z","end":"2026-07-31T22:13:23.261241Z","steps":["trace[1819501026] 'agreement among raft nodes before linearized reading' (duration: 338.893339ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:23.261291Z","caller":"traceutil/trace.go:171","msg":"trace[2111300677] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25136; }","duration":"173.630982ms","start":"2026-07-31T22:13:23.087631Z","end":"2026-07-31T22:13:23.261262Z","steps":["trace[2111300677] 'agreement among raft nodes before linearized reading' (duration: 173.409797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:23.26131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:22.922112Z","time spent":"339.188736ms","remote":"127.0.0.1:50040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-31T22:13:23.261185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.413048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-07-31T22:13:23.261427Z","caller":"traceutil/trace.go:171","msg":"trace[1269140470] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25136; }","duration":"338.683714ms","start":"2026-07-31T22:13:22.922715Z","end":"2026-07-31T22:13:23.261399Z","steps":["trace[1269140470] 'agreement among raft nodes before linearized reading' (duration: 338.310345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:23.261522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:22.922706Z","time spent":"338.786677ms","remote":"127.0.0.1:50000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-07-31T22:13:26.888564Z","caller":"traceutil/trace.go:171","msg":"trace[284850622] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"168.868136ms","start":"2026-07-31T22:13:26.719653Z","end":"2026-07-31T22:13:26.888522Z","steps":["trace[284850622] 'process raft request' (duration: 129.564909ms)","trace[284850622] 'compare' (duration: 39.134793ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:27.466412Z","caller":"traceutil/trace.go:171","msg":"trace[2140574425] linearizableReadLoop","detail":"{readStateIndex:25999; appliedIndex:25998; }","duration":"119.475761ms","start":"2026-07-31T22:13:27.346916Z","end":"2026-07-31T22:13:27.466392Z","steps":["trace[2140574425] 'read index received' (duration: 119.326467ms)","trace[2140574425] 'applied index is now lower than readState.Index' (duration: 148.314µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:27.466544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.610594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T22:13:27.466573Z","caller":"traceutil/trace.go:171","msg":"trace[1763295275] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25177; }","duration":"119.662935ms","start":"2026-07-31T22:13:27.346903Z","end":"2026-07-31T22:13:27.466566Z","steps":["trace[1763295275] 'agreement among raft nodes before linearized reading' (duration: 119.560792ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:27.466681Z","caller":"traceutil/trace.go:171","msg":"trace[646410474] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"147.831468ms","start":"2026-07-31T22:13:27.318817Z","end":"2026-07-31T22:13:27.466649Z","steps":["trace[646410474] 'process raft request' (duration: 147.444018ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:27.685069Z","caller":"traceutil/trace.go:171","msg":"trace[564774540] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"210.07833ms","start":"2026-07-31T22:13:27.474965Z","end":"2026-07-31T22:13:27.685043Z","steps":["trace[564774540] 'process raft request' (duration: 120.24584ms)","trace[564774540] 'compare' (duration: 89.601304ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:27.685485Z","caller":"traceutil/trace.go:171","msg":"trace[425979208] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"120.166887ms","start":"2026-07-31T22:13:27.56529Z","end":"2026-07-31T22:13:27.685457Z","steps":["trace[425979208] 'process raft request' (duration: 119.695116ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:27.866165Z","caller":"traceutil/trace.go:171","msg":"trace[533768204] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"171.239074ms","start":"2026-07-31T22:13:27.6949Z","end":"2026-07-31T22:13:27.866139Z","steps":["trace[533768204] 'process raft request' (duration: 99.828206ms)","trace[533768204] 'compare' (duration: 71.234024ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:27.866427Z","caller":"traceutil/trace.go:171","msg":"trace[1502578517] linearizableReadLoop","detail":"{readStateIndex:26004; appliedIndex:26001; }","duration":"165.3567ms","start":"2026-07-31T22:13:27.701053Z","end":"2026-07-31T22:13:27.86641Z","steps":["trace[1502578517] 'read index received' (duration: 93.689905ms)","trace[1502578517] 'applied index is now lower than readState.Index' (duration: 71.666025ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:27.866549Z","caller":"traceutil/trace.go:171","msg":"trace[1876091340] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"167.751718ms","start":"2026-07-31T22:13:27.698781Z","end":"2026-07-31T22:13:27.866532Z","steps":["trace[1876091340] 'process raft request' (duration: 167.489282ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:27.866557Z","caller":"traceutil/trace.go:171","msg":"trace[578361540] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"168.486306ms","start":"2026-07-31T22:13:27.698043Z","end":"2026-07-31T22:13:27.86653Z","steps":["trace[578361540] 'process raft request' (duration: 168.052556ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:27.866625Z","caller":"traceutil/trace.go:171","msg":"trace[1279769760] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"162.426877ms","start":"2026-07-31T22:13:27.704186Z","end":"2026-07-31T22:13:27.866613Z","steps":["trace[1279769760] 'process raft request' (duration: 162.189622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:27.866678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.605315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-31T22:13:27.866758Z","caller":"traceutil/trace.go:171","msg":"trace[475895109] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25183; }","duration":"165.711297ms","start":"2026-07-31T22:13:27.701027Z","end":"2026-07-31T22:13:27.866739Z","steps":["trace[475895109] 'agreement among raft nodes before linearized reading' (duration: 165.508203ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:31.049424Z","caller":"traceutil/trace.go:171","msg":"trace[389527134] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"193.622014ms","start":"2026-07-31T22:13:30.855776Z","end":"2026-07-31T22:13:31.049398Z","steps":["trace[389527134] 'process raft request' (duration: 193.45704ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:31.057319Z","caller":"traceutil/trace.go:171","msg":"trace[735709319] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"154.825299ms","start":"2026-07-31T22:13:30.902468Z","end":"2026-07-31T22:13:31.057293Z","steps":["trace[735709319] 'process raft request' (duration: 154.671256ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:36.289525Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065da.wal"} {"level":"info","ts":"2026-07-31T22:13:43.656927Z","caller":"traceutil/trace.go:171","msg":"trace[1919540449] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"186.364957ms","start":"2026-07-31T22:13:43.470537Z","end":"2026-07-31T22:13:43.656902Z","steps":["trace[1919540449] 'process raft request' (duration: 186.183362ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:43.861188Z","caller":"traceutil/trace.go:171","msg":"trace[157732218] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"130.493021ms","start":"2026-07-31T22:13:43.730668Z","end":"2026-07-31T22:13:43.861161Z","steps":["trace[157732218] 'process raft request' (duration: 130.306356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.138477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.363432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:44.138588Z","caller":"traceutil/trace.go:171","msg":"trace[1216678251] linearizableReadLoop","detail":"{readStateIndex:26125; appliedIndex:26123; }","duration":"351.097469ms","start":"2026-07-31T22:13:43.787473Z","end":"2026-07-31T22:13:44.13857Z","steps":["trace[1216678251] 'read index received' (duration: 73.539399ms)","trace[1216678251] 'applied index is now lower than readState.Index' (duration: 277.55709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:44.138681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.203023ms","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-31T22:13:44.138714Z","caller":"traceutil/trace.go:171","msg":"trace[1949326179] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25300; }","duration":"351.276214ms","start":"2026-07-31T22:13:43.787429Z","end":"2026-07-31T22:13:44.138705Z","steps":["trace[1949326179] 'agreement among raft nodes before linearized reading' (duration: 351.212012ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:44.138664Z","caller":"traceutil/trace.go:171","msg":"trace[370113735] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"404.083643ms","start":"2026-07-31T22:13:43.734521Z","end":"2026-07-31T22:13:44.138605Z","steps":["trace[370113735] 'process raft request' (duration: 294.516297ms)","trace[370113735] 'compare' (duration: 109.109475ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:44.138752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.787412Z","time spent":"351.332946ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-31T22:13:44.138859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.734486Z","time spent":"404.277209ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418437,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:44.433616Z","caller":"traceutil/trace.go:171","msg":"trace[1334294131] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"513.647751ms","start":"2026-07-31T22:13:43.919948Z","end":"2026-07-31T22:13:44.433596Z","steps":["trace[1334294131] 'process raft request' (duration: 513.480197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.433731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.919921Z","time spent":"513.748643ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:44.433894Z","caller":"traceutil/trace.go:171","msg":"trace[1872286233] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"513.834446ms","start":"2026-07-31T22:13:43.920034Z","end":"2026-07-31T22:13:44.433868Z","steps":["trace[1872286233] 'process raft request' (duration: 513.528718ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:44.434112Z","caller":"traceutil/trace.go:171","msg":"trace[591428671] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"513.309542ms","start":"2026-07-31T22:13:43.920779Z","end":"2026-07-31T22:13:44.434088Z","steps":["trace[591428671] 'process raft request' (duration: 513.013455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.434111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.920026Z","time spent":"513.977379ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:44.434243Z","caller":"traceutil/trace.go:171","msg":"trace[1105271609] linearizableReadLoop","detail":"{readStateIndex:26129; appliedIndex:26125; }","duration":"295.610265ms","start":"2026-07-31T22:13:44.138613Z","end":"2026-07-31T22:13:44.434224Z","steps":["trace[1105271609] 'read index received' (duration: 294.820126ms)","trace[1105271609] 'applied index is now lower than readState.Index' (duration: 786.429µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:44.434253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.920766Z","time spent":"513.416744ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:44.434282Z","caller":"traceutil/trace.go:171","msg":"trace[1951546884] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"513.23612ms","start":"2026-07-31T22:13:43.921016Z","end":"2026-07-31T22:13:44.434252Z","steps":["trace[1951546884] 'process raft request' (duration: 513.004664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.434406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.280862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-31T22:13:44.434417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.116547ms","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-31T22:13:44.434434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.92101Z","time spent":"513.350193ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:44.434462Z","caller":"traceutil/trace.go:171","msg":"trace[1948198237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25304; }","duration":"472.358945ms","start":"2026-07-31T22:13:43.96209Z","end":"2026-07-31T22:13:44.434448Z","steps":["trace[1948198237] 'agreement among raft nodes before linearized reading' (duration: 472.200821ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:44.434482Z","caller":"traceutil/trace.go:171","msg":"trace[629993333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25304; }","duration":"474.207359ms","start":"2026-07-31T22:13:43.960261Z","end":"2026-07-31T22:13:44.434468Z","steps":["trace[629993333] 'agreement among raft nodes before linearized reading' (duration: 474.050235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.434548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.96025Z","time spent":"474.289981ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T22:13:44.434547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:43.962061Z","time spent":"472.477767ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T22:13:44.648304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.900727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:44.64856Z","caller":"traceutil/trace.go:171","msg":"trace[534585708] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"209.528286ms","start":"2026-07-31T22:13:44.439015Z","end":"2026-07-31T22:13:44.648543Z","steps":["trace[534585708] 'process raft request' (duration: 209.487655ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:44.648662Z","caller":"traceutil/trace.go:171","msg":"trace[1998164536] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"284.457061ms","start":"2026-07-31T22:13:44.364171Z","end":"2026-07-31T22:13:44.648628Z","steps":["trace[1998164536] 'process raft request' (duration: 284.223184ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:44.648895Z","caller":"traceutil/trace.go:171","msg":"trace[993583378] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"332.636296ms","start":"2026-07-31T22:13:44.316228Z","end":"2026-07-31T22:13:44.648864Z","steps":["trace[993583378] 'process raft request' (duration: 191.086462ms)","trace[993583378] 'compare' (duration: 140.62531ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:44.649297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:44.316205Z","time spent":"333.029036ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:13:44.649077Z","caller":"traceutil/trace.go:171","msg":"trace[124163579] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26129; }","duration":"214.783376ms","start":"2026-07-31T22:13:44.434279Z","end":"2026-07-31T22:13:44.649062Z","steps":["trace[124163579] 'read index received' (duration: 73.16097ms)","trace[124163579] 'applied index is now lower than readState.Index' (duration: 141.620716ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:44.649107Z","caller":"traceutil/trace.go:171","msg":"trace[1091016091] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"210.279785ms","start":"2026-07-31T22:13:44.438801Z","end":"2026-07-31T22:13:44.64908Z","steps":["trace[1091016091] 'process raft request' (duration: 209.665299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.649182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.064224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:44.649461Z","caller":"traceutil/trace.go:171","msg":"trace[704872118] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25308; }","duration":"506.405362ms","start":"2026-07-31T22:13:44.143041Z","end":"2026-07-31T22:13:44.649446Z","steps":["trace[704872118] 'agreement among raft nodes before linearized reading' (duration: 506.062914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:44.649506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:13:44.143024Z","time spent":"506.461944ms","remote":"127.0.0.1:50054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-31T22:13:48.637576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.483072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:48.63786Z","caller":"traceutil/trace.go:171","msg":"trace[1132147425] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"188.298784ms","start":"2026-07-31T22:13:48.449544Z","end":"2026-07-31T22:13:48.637843Z","steps":["trace[1132147425] 'process raft request' (duration: 188.237643ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:48.63807Z","caller":"traceutil/trace.go:171","msg":"trace[1871207140] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"190.235301ms","start":"2026-07-31T22:13:48.447806Z","end":"2026-07-31T22:13:48.638041Z","steps":["trace[1871207140] 'process raft request' (duration: 189.880472ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:48.638074Z","caller":"traceutil/trace.go:171","msg":"trace[1493238645] transaction","detail":"{read_only:false; response_revision:25332; number_of_response:1; }","duration":"190.236692ms","start":"2026-07-31T22:13:48.447789Z","end":"2026-07-31T22:13:48.638026Z","steps":["trace[1493238645] 'process raft request' (duration: 76.200085ms)","trace[1493238645] 'compare' (duration: 113.338299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:48.866714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.763204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:48.866876Z","caller":"traceutil/trace.go:171","msg":"trace[19218670] linearizableReadLoop","detail":"{readStateIndex:26162; appliedIndex:26160; }","duration":"204.8253ms","start":"2026-07-31T22:13:48.662039Z","end":"2026-07-31T22:13:48.866865Z","steps":["trace[19218670] 'read index received' (duration: 102.914073ms)","trace[19218670] 'applied index is now lower than readState.Index' (duration: 101.908717ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:48.86688Z","caller":"traceutil/trace.go:171","msg":"trace[432863887] transaction","detail":"{read_only:false; response_revision:25335; number_of_response:1; }","duration":"206.269595ms","start":"2026-07-31T22:13:48.660583Z","end":"2026-07-31T22:13:48.866853Z","steps":["trace[432863887] 'process raft request' (duration: 104.310717ms)","trace[432863887] 'compare' (duration: 101.60162ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:48.866932Z","caller":"traceutil/trace.go:171","msg":"trace[1612919673] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"205.717042ms","start":"2026-07-31T22:13:48.661209Z","end":"2026-07-31T22:13:48.866926Z","steps":["trace[1612919673] 'process raft request' (duration: 205.570708ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:48.867004Z","caller":"traceutil/trace.go:171","msg":"trace[794164481] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"203.127138ms","start":"2026-07-31T22:13:48.663873Z","end":"2026-07-31T22:13:48.867Z","steps":["trace[794164481] 'process raft request' (duration: 202.965754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:48.867069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.028285ms","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-31T22:13:48.867083Z","caller":"traceutil/trace.go:171","msg":"trace[144060823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25337; }","duration":"205.059226ms","start":"2026-07-31T22:13:48.662019Z","end":"2026-07-31T22:13:48.867078Z","steps":["trace[144060823] 'agreement among raft nodes before linearized reading' (duration: 205.024065ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.423052Z","caller":"traceutil/trace.go:171","msg":"trace[898089545] linearizableReadLoop","detail":"{readStateIndex:26206; appliedIndex:26204; }","duration":"177.37581ms","start":"2026-07-31T22:13:54.245655Z","end":"2026-07-31T22:13:54.423031Z","steps":["trace[898089545] 'read index received' (duration: 8.110198ms)","trace[898089545] 'applied index is now lower than readState.Index' (duration: 169.264152ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:54.423079Z","caller":"traceutil/trace.go:171","msg":"trace[161783598] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"240.026496ms","start":"2026-07-31T22:13:54.183022Z","end":"2026-07-31T22:13:54.423048Z","steps":["trace[161783598] 'process raft request' (duration: 187.615689ms)","trace[161783598] 'compare' (duration: 52.287664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:54.423297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.620256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:54.423423Z","caller":"traceutil/trace.go:171","msg":"trace[362028466] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25379; }","duration":"177.78795ms","start":"2026-07-31T22:13:54.245611Z","end":"2026-07-31T22:13:54.423399Z","steps":["trace[362028466] 'agreement among raft nodes before linearized reading' (duration: 177.595175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:54.423827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.823635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-vn7qp\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-07-31T22:13:54.423928Z","caller":"traceutil/trace.go:171","msg":"trace[1667597738] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-vn7qp; range_end:; response_count:1; response_revision:25379; }","duration":"116.969318ms","start":"2026-07-31T22:13:54.306938Z","end":"2026-07-31T22:13:54.423907Z","steps":["trace[1667597738] 'agreement among raft nodes before linearized reading' (duration: 116.306732ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.626341Z","caller":"traceutil/trace.go:171","msg":"trace[1678683668] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"194.049046ms","start":"2026-07-31T22:13:54.432265Z","end":"2026-07-31T22:13:54.626314Z","steps":["trace[1678683668] 'process raft request' (duration: 101.980503ms)","trace[1678683668] 'compare' (duration: 91.874228ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:54.626656Z","caller":"traceutil/trace.go:171","msg":"trace[2033057708] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"134.868094ms","start":"2026-07-31T22:13:54.491764Z","end":"2026-07-31T22:13:54.626632Z","steps":["trace[2033057708] 'process raft request' (duration: 134.503445ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.790517Z","caller":"traceutil/trace.go:171","msg":"trace[1099596935] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"156.403239ms","start":"2026-07-31T22:13:54.634071Z","end":"2026-07-31T22:13:54.790474Z","steps":["trace[1099596935] 'process raft request' (duration: 156.113952ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.790661Z","caller":"traceutil/trace.go:171","msg":"trace[201094086] linearizableReadLoop","detail":"{readStateIndex:26210; appliedIndex:26209; }","duration":"155.096246ms","start":"2026-07-31T22:13:54.635538Z","end":"2026-07-31T22:13:54.790634Z","steps":["trace[201094086] 'read index received' (duration: 65.394652ms)","trace[201094086] 'applied index is now lower than readState.Index' (duration: 89.700514ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:54.790681Z","caller":"traceutil/trace.go:171","msg":"trace[718774162] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"131.657856ms","start":"2026-07-31T22:13:54.658991Z","end":"2026-07-31T22:13:54.790649Z","steps":["trace[718774162] 'process raft request' (duration: 131.472842ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.79053Z","caller":"traceutil/trace.go:171","msg":"trace[1623993175] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"132.61414ms","start":"2026-07-31T22:13:54.657887Z","end":"2026-07-31T22:13:54.790502Z","steps":["trace[1623993175] 'process raft request' (duration: 132.536648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:54.790868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.306442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T22:13:54.790931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.349163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-07-31T22:13:54.790549Z","caller":"traceutil/trace.go:171","msg":"trace[816352171] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"134.657399ms","start":"2026-07-31T22:13:54.655859Z","end":"2026-07-31T22:13:54.790516Z","steps":["trace[816352171] 'process raft request' (duration: 134.468345ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.790981Z","caller":"traceutil/trace.go:171","msg":"trace[617306738] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:25386; }","duration":"155.411624ms","start":"2026-07-31T22:13:54.635557Z","end":"2026-07-31T22:13:54.790969Z","steps":["trace[617306738] 'agreement among raft nodes before linearized reading' (duration: 155.24134ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:54.790907Z","caller":"traceutil/trace.go:171","msg":"trace[1261078960] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25386; }","duration":"155.396833ms","start":"2026-07-31T22:13:54.635501Z","end":"2026-07-31T22:13:54.790898Z","steps":["trace[1261078960] 'agreement among raft nodes before linearized reading' (duration: 155.280711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:54.7911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.136768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:54.791161Z","caller":"traceutil/trace.go:171","msg":"trace[687989369] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25386; }","duration":"107.250121ms","start":"2026-07-31T22:13:54.683894Z","end":"2026-07-31T22:13:54.791144Z","steps":["trace[687989369] 'agreement among raft nodes before linearized reading' (duration: 107.126508ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:55.009163Z","caller":"traceutil/trace.go:171","msg":"trace[517933955] linearizableReadLoop","detail":"{readStateIndex:26214; appliedIndex:26213; }","duration":"207.152825ms","start":"2026-07-31T22:13:54.801983Z","end":"2026-07-31T22:13:55.009136Z","steps":["trace[517933955] 'read index received' (duration: 197.582491ms)","trace[517933955] 'applied index is now lower than readState.Index' (duration: 9.568984ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:55.009345Z","caller":"traceutil/trace.go:171","msg":"trace[1125785091] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"208.454765ms","start":"2026-07-31T22:13:54.800873Z","end":"2026-07-31T22:13:55.009328Z","steps":["trace[1125785091] 'process raft request' (duration: 198.613875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.00967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.672517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-31T22:13:55.009755Z","caller":"traceutil/trace.go:171","msg":"trace[1348751593] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25387; }","duration":"207.786039ms","start":"2026-07-31T22:13:54.801945Z","end":"2026-07-31T22:13:55.009731Z","steps":["trace[1348751593] 'agreement among raft nodes before linearized reading' (duration: 207.507502ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:55.017951Z","caller":"traceutil/trace.go:171","msg":"trace[1451768769] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"122.903873ms","start":"2026-07-31T22:13:54.895021Z","end":"2026-07-31T22:13:55.017925Z","steps":["trace[1451768769] 'process raft request' (duration: 122.673497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.018211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.654988ms","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-31T22:13:55.018236Z","caller":"traceutil/trace.go:171","msg":"trace[1395662029] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"122.123654ms","start":"2026-07-31T22:13:54.896099Z","end":"2026-07-31T22:13:55.018223Z","steps":["trace[1395662029] 'process raft request' (duration: 121.770755ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:55.018284Z","caller":"traceutil/trace.go:171","msg":"trace[586040642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25389; }","duration":"120.757701ms","start":"2026-07-31T22:13:54.897502Z","end":"2026-07-31T22:13:55.01826Z","steps":["trace[586040642] 'agreement among raft nodes before linearized reading' (duration: 120.604117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.018294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.216072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-31T22:13:55.01838Z","caller":"traceutil/trace.go:171","msg":"trace[1117490048] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25389; }","duration":"213.318184ms","start":"2026-07-31T22:13:54.805041Z","end":"2026-07-31T22:13:55.018359Z","steps":["trace[1117490048] 'agreement among raft nodes before linearized reading' (duration: 213.123849ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:55.171957Z","caller":"traceutil/trace.go:171","msg":"trace[631192486] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"148.948297ms","start":"2026-07-31T22:13:55.022987Z","end":"2026-07-31T22:13:55.171936Z","steps":["trace[631192486] 'process raft request' (duration: 147.355247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.172183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.210443ms","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-31T22:13:55.172249Z","caller":"traceutil/trace.go:171","msg":"trace[1998805474] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25393; }","duration":"103.314366ms","start":"2026-07-31T22:13:55.06892Z","end":"2026-07-31T22:13:55.172234Z","steps":["trace[1998805474] 'agreement among raft nodes before linearized reading' (duration: 103.172422ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:55.172007Z","caller":"traceutil/trace.go:171","msg":"trace[493469847] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"147.304467ms","start":"2026-07-31T22:13:55.024683Z","end":"2026-07-31T22:13:55.171987Z","steps":["trace[493469847] 'process raft request' (duration: 147.176044ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:55.172385Z","caller":"traceutil/trace.go:171","msg":"trace[1080639555] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"143.007502ms","start":"2026-07-31T22:13:55.029356Z","end":"2026-07-31T22:13:55.172363Z","steps":["trace[1080639555] 'process raft request' (duration: 142.575281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.408292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.569441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:55.408469Z","caller":"traceutil/trace.go:171","msg":"trace[869035438] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26220; }","duration":"233.084886ms","start":"2026-07-31T22:13:55.175366Z","end":"2026-07-31T22:13:55.408451Z","steps":["trace[869035438] 'read index received' (duration: 104.185397ms)","trace[869035438] 'applied index is now lower than readState.Index' (duration: 128.897699ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:55.40851Z","caller":"traceutil/trace.go:171","msg":"trace[2126718739] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"269.746198ms","start":"2026-07-31T22:13:55.138736Z","end":"2026-07-31T22:13:55.408483Z","steps":["trace[2126718739] 'process raft request' (duration: 140.908551ms)","trace[2126718739] 'compare' (duration: 128.408947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:55.408561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.175738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:55.408614Z","caller":"traceutil/trace.go:171","msg":"trace[988652821] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25394; }","duration":"233.27553ms","start":"2026-07-31T22:13:55.175322Z","end":"2026-07-31T22:13:55.408598Z","steps":["trace[988652821] 'agreement among raft nodes before linearized reading' (duration: 233.185498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.747894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.371619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-31T22:13:55.747981Z","caller":"traceutil/trace.go:171","msg":"trace[870114386] linearizableReadLoop","detail":"{readStateIndex:26223; appliedIndex:26222; }","duration":"260.167256ms","start":"2026-07-31T22:13:55.487799Z","end":"2026-07-31T22:13:55.747966Z","steps":["trace[870114386] 'read index received' (duration: 59.737055ms)","trace[870114386] 'applied index is now lower than readState.Index' (duration: 200.428981ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:55.748106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.064191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:13:55.748164Z","caller":"traceutil/trace.go:171","msg":"trace[219840721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25395; }","duration":"149.163173ms","start":"2026-07-31T22:13:55.598988Z","end":"2026-07-31T22:13:55.748151Z","steps":["trace[219840721] 'agreement among raft nodes before linearized reading' (duration: 149.059501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:55.748268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.447242ms","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-31T22:13:55.748347Z","caller":"traceutil/trace.go:171","msg":"trace[1951481063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25395; }","duration":"260.564855ms","start":"2026-07-31T22:13:55.487763Z","end":"2026-07-31T22:13:55.748328Z","steps":["trace[1951481063] 'agreement among raft nodes before linearized reading' (duration: 260.284699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:13:56.000104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.441824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:13:56.00037Z","caller":"traceutil/trace.go:171","msg":"trace[1642866126] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"117.952422ms","start":"2026-07-31T22:13:55.882356Z","end":"2026-07-31T22:13:56.000309Z","steps":["trace[1642866126] 'process raft request' (duration: 14.80648ms)","trace[1642866126] 'compare' (duration: 102.24654ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:56.427812Z","caller":"traceutil/trace.go:171","msg":"trace[1966901115] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"111.370152ms","start":"2026-07-31T22:13:56.316412Z","end":"2026-07-31T22:13:56.427782Z","steps":["trace[1966901115] 'process raft request' (duration: 111.174697ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:56.778331Z","caller":"traceutil/trace.go:171","msg":"trace[440829537] linearizableReadLoop","detail":"{readStateIndex:26229; appliedIndex:26228; }","duration":"101.285096ms","start":"2026-07-31T22:13:56.677014Z","end":"2026-07-31T22:13:56.778299Z","steps":["trace[440829537] 'read index received' (duration: 30.511573ms)","trace[440829537] 'applied index is now lower than readState.Index' (duration: 70.771963ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:13:56.778432Z","caller":"traceutil/trace.go:171","msg":"trace[1965809295] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"146.879256ms","start":"2026-07-31T22:13:56.631478Z","end":"2026-07-31T22:13:56.778357Z","steps":["trace[1965809295] 'process raft request' (duration: 76.134454ms)","trace[1965809295] 'compare' (duration: 70.436785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:13:56.778503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.465301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T22:13:56.778556Z","caller":"traceutil/trace.go:171","msg":"trace[326633392] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25401; }","duration":"101.576293ms","start":"2026-07-31T22:13:56.676962Z","end":"2026-07-31T22:13:56.778538Z","steps":["trace[326633392] 'agreement among raft nodes before linearized reading' (duration: 101.453721ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:56.928545Z","caller":"traceutil/trace.go:171","msg":"trace[186709777] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"131.943333ms","start":"2026-07-31T22:13:56.796568Z","end":"2026-07-31T22:13:56.928512Z","steps":["trace[186709777] 'process raft request' (duration: 131.81308ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:13:56.92854Z","caller":"traceutil/trace.go:171","msg":"trace[1333889177] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"131.979084ms","start":"2026-07-31T22:13:56.796507Z","end":"2026-07-31T22:13:56.928486Z","steps":["trace[1333889177] 'process raft request' (duration: 131.694307ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:18.955714Z","caller":"traceutil/trace.go:171","msg":"trace[1637645062] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"104.471914ms","start":"2026-07-31T22:14:18.851219Z","end":"2026-07-31T22:14:18.955691Z","steps":["trace[1637645062] 'process raft request' (duration: 104.346101ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:19.194759Z","caller":"traceutil/trace.go:171","msg":"trace[233744517] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"181.334675ms","start":"2026-07-31T22:14:19.013399Z","end":"2026-07-31T22:14:19.194734Z","steps":["trace[233744517] 'process raft request' (duration: 106.445372ms)","trace[233744517] 'compare' (duration: 74.77949ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:14:19.366278Z","caller":"traceutil/trace.go:171","msg":"trace[635903328] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"255.919033ms","start":"2026-07-31T22:14:19.110333Z","end":"2026-07-31T22:14:19.366253Z","steps":["trace[635903328] 'process raft request' (duration: 255.867402ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:19.366492Z","caller":"traceutil/trace.go:171","msg":"trace[552444373] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"352.501744ms","start":"2026-07-31T22:14:19.013974Z","end":"2026-07-31T22:14:19.366476Z","steps":["trace[552444373] 'process raft request' (duration: 351.982801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:19.36669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:19.013951Z","time spent":"352.658958ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:14:19.366756Z","caller":"traceutil/trace.go:171","msg":"trace[979533339] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"273.025779ms","start":"2026-07-31T22:14:19.093682Z","end":"2026-07-31T22:14:19.366708Z","steps":["trace[979533339] 'process raft request' (duration: 272.462705ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:19.374017Z","caller":"traceutil/trace.go:171","msg":"trace[1549038157] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"227.157312ms","start":"2026-07-31T22:14:19.146841Z","end":"2026-07-31T22:14:19.373998Z","steps":["trace[1549038157] 'process raft request' (duration: 226.785773ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:19.374259Z","caller":"traceutil/trace.go:171","msg":"trace[1692098971] linearizableReadLoop","detail":"{readStateIndex:26542; appliedIndex:26538; }","duration":"160.055738ms","start":"2026-07-31T22:14:19.213861Z","end":"2026-07-31T22:14:19.373917Z","steps":["trace[1692098971] 'read index received' (duration: 152.167336ms)","trace[1692098971] 'applied index is now lower than readState.Index' (duration: 7.874351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:14:19.374347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.474698ms","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-31T22:14:19.374436Z","caller":"traceutil/trace.go:171","msg":"trace[519507709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25709; }","duration":"160.56776ms","start":"2026-07-31T22:14:19.213853Z","end":"2026-07-31T22:14:19.374421Z","steps":["trace[519507709] 'agreement among raft nodes before linearized reading' (duration: 160.269043ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:15:27.508996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23476} {"level":"info","ts":"2026-07-31T22:15:27.647886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23476,"took":"132.798424ms","hash":1452564557,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":36933632,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-31T22:15:27.647965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1452564557,"revision":23476,"compact-revision":20268} {"level":"info","ts":"2026-07-31T22:20:27.515927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26327} {"level":"info","ts":"2026-07-31T22:20:27.658504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26327,"took":"138.973636ms","hash":462171116,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":34574336,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-31T22:20:27.658575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":462171116,"revision":26327,"compact-revision":23476} {"level":"info","ts":"2026-07-31T22:20:42.407418Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T22:20:42.412295Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-31T22:20:42.412397Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-31T22:25:27.52296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28970} {"level":"info","ts":"2026-07-31T22:25:27.655556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28970,"took":"128.309331ms","hash":3593756728,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30806016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T22:25:27.655625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593756728,"revision":28970,"compact-revision":26327} {"level":"info","ts":"2026-07-31T22:30:27.529351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31187} {"level":"info","ts":"2026-07-31T22:30:27.662985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31187,"took":"128.970596ms","hash":156542424,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30748672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T22:30:27.663114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":156542424,"revision":31187,"compact-revision":28970} {"level":"info","ts":"2026-07-31T22:35:27.536759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33451} {"level":"info","ts":"2026-07-31T22:35:27.686959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33451,"took":"144.046967ms","hash":1613973216,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T22:35:27.687036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1613973216,"revision":33451,"compact-revision":31187} {"level":"info","ts":"2026-07-31T22:40:27.542918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35740} {"level":"info","ts":"2026-07-31T22:40:27.684841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35740,"took":"136.299189ms","hash":456518307,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T22:40:27.684931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":456518307,"revision":35740,"compact-revision":33451} {"level":"info","ts":"2026-07-31T22:42:11.388434Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T22:42:11.395051Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-31T22:42:11.395193Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-31T22:45:27.550586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37972} {"level":"info","ts":"2026-07-31T22:45:27.664526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37972,"took":"110.231363ms","hash":74298245,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":31481856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T22:45:27.66457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":74298245,"revision":37972,"compact-revision":35740}