{"level":"warn","ts":"2026-07-01T11:02:20.141245Z","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-01T11:02:20.141334Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-01T11:02:20.141398Z","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-01T11:02:20.141413Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-01T11:02:20.141429Z","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-01T11:02:20.142047Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-07-01T11:02:20.142131Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-01T11:02:20.145924Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.408949ms"} {"level":"info","ts":"2026-07-01T11:02:20.151126Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-07-01T11:02:20.151207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T11:02:20.15123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-07-01T11:02:20.15124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T11:02:20.151252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-07-01T11:02:20.151388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-07-01T11:02:20.156869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T11:02:20.15851Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T11:02:20.159817Z","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-01T11:02:20.164201Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T11:02:20.16461Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T11:02:20.164846Z","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-01T11:02:20.164914Z","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-01T11:02:20.164933Z","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-01T11:02:20.16828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-07-01T11:02:20.168476Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-01T11:02:20.171231Z","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-01T11:02:20.171306Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-01T11:02:20.171401Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-01T11:02:20.171653Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T11:02:20.171721Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T11:02:20.552562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T11:02:20.552648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T11:02:20.552677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-07-01T11:02:20.552703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-07-01T11:02:20.552717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-01T11:02:20.552763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-07-01T11:02:20.552786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-01T11:02:20.553857Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:20.554274Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T11:02:20.554485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T11:02:20.554922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T11:02:20.55516Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T11:02:20.555185Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T11:02:20.558355Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-07-01T11:02:20.559001Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:20.559113Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:20.559153Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:20.559389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-01T11:02:38.930411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.622162ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T11:02:38.930569Z","caller":"traceutil/trace.go:171","msg":"trace[1718275888] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"198.070958ms","start":"2026-07-01T11:02:38.732476Z","end":"2026-07-01T11:02:38.930547Z","steps":["trace[1718275888] 'read index received' (duration: 45.158081ms)","trace[1718275888] 'applied index is now lower than readState.Index' (duration: 152.910887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:02:38.930715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.228603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T11:02:38.930751Z","caller":"traceutil/trace.go:171","msg":"trace[1351601460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"198.314144ms","start":"2026-07-01T11:02:38.732428Z","end":"2026-07-01T11:02:38.930742Z","steps":["trace[1351601460] 'agreement among raft nodes before linearized reading' (duration: 198.204142ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.776763Z","caller":"traceutil/trace.go:171","msg":"trace[229071453] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"109.447716ms","start":"2026-07-01T11:02:57.66729Z","end":"2026-07-01T11:02:57.776738Z","steps":["trace[229071453] 'process raft request' (duration: 105.993506ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.779671Z","caller":"traceutil/trace.go:171","msg":"trace[1397573486] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"108.056577ms","start":"2026-07-01T11:02:57.671578Z","end":"2026-07-01T11:02:57.779635Z","steps":["trace[1397573486] 'process raft request' (duration: 107.881694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.779859Z","caller":"traceutil/trace.go:171","msg":"trace[1302697153] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"108.191111ms","start":"2026-07-01T11:02:57.671654Z","end":"2026-07-01T11:02:57.779846Z","steps":["trace[1302697153] 'process raft request' (duration: 107.910585ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.779893Z","caller":"traceutil/trace.go:171","msg":"trace[1297282467] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"105.947584ms","start":"2026-07-01T11:02:57.673933Z","end":"2026-07-01T11:02:57.779881Z","steps":["trace[1297282467] 'process raft request' (duration: 105.73116ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.780036Z","caller":"traceutil/trace.go:171","msg":"trace[718648223] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"106.486465ms","start":"2026-07-01T11:02:57.673537Z","end":"2026-07-01T11:02:57.780024Z","steps":["trace[718648223] 'process raft request' (duration: 106.067767ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.780125Z","caller":"traceutil/trace.go:171","msg":"trace[1237123283] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"103.662728ms","start":"2026-07-01T11:02:57.676452Z","end":"2026-07-01T11:02:57.780115Z","steps":["trace[1237123283] 'process raft request' (duration: 103.294231ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.780125Z","caller":"traceutil/trace.go:171","msg":"trace[1226504627] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"103.75523ms","start":"2026-07-01T11:02:57.676359Z","end":"2026-07-01T11:02:57.780114Z","steps":["trace[1226504627] 'process raft request' (duration: 103.340612ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.780177Z","caller":"traceutil/trace.go:171","msg":"trace[1646226446] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"103.070956ms","start":"2026-07-01T11:02:57.677035Z","end":"2026-07-01T11:02:57.780105Z","steps":["trace[1646226446] 'process raft request' (duration: 102.79106ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:02:57.780284Z","caller":"traceutil/trace.go:171","msg":"trace[1017376881] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"100.459612ms","start":"2026-07-01T11:02:57.679813Z","end":"2026-07-01T11:02:57.780273Z","steps":["trace[1017376881] 'process raft request' (duration: 100.050214ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:04.13185Z","caller":"traceutil/trace.go:171","msg":"trace[452349268] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"130.013417ms","start":"2026-07-01T11:03:04.001806Z","end":"2026-07-01T11:03:04.13182Z","steps":["trace[452349268] 'process raft request' (duration: 129.944245ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:04.131959Z","caller":"traceutil/trace.go:171","msg":"trace[1271961814] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"188.873319ms","start":"2026-07-01T11:03:03.943042Z","end":"2026-07-01T11:03:04.131915Z","steps":["trace[1271961814] 'process raft request' (duration: 184.05612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:04.575972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.776363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T11:03:04.576165Z","caller":"traceutil/trace.go:171","msg":"trace[1582393061] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"215.709368ms","start":"2026-07-01T11:03:04.360438Z","end":"2026-07-01T11:03:04.576148Z","steps":["trace[1582393061] 'read index received' (duration: 104.25649ms)","trace[1582393061] 'applied index is now lower than readState.Index' (duration: 111.451098ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:03:04.576232Z","caller":"traceutil/trace.go:171","msg":"trace[62295467] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"386.391164ms","start":"2026-07-01T11:03:04.189786Z","end":"2026-07-01T11:03:04.576177Z","steps":["trace[62295467] 'process raft request' (duration: 275.025269ms)","trace[62295467] 'compare' (duration: 110.537859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:03:04.576462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.996663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-btwrr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-01T11:03:04.576542Z","caller":"traceutil/trace.go:171","msg":"trace[66174812] range","detail":"{range_begin:/registry/pods/kube-system/cilium-btwrr; range_end:; response_count:1; response_revision:635; }","duration":"216.114595ms","start":"2026-07-01T11:03:04.360407Z","end":"2026-07-01T11:03:04.576522Z","steps":["trace[66174812] 'agreement among raft nodes before linearized reading' (duration: 215.83446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:04.576406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:03:04.189757Z","time spent":"386.577498ms","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T11:03:19.719674Z","caller":"traceutil/trace.go:171","msg":"trace[643403042] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"154.518546ms","start":"2026-07-01T11:03:19.565125Z","end":"2026-07-01T11:03:19.719644Z","steps":["trace[643403042] 'read index received' (duration: 154.427875ms)","trace[643403042] 'applied index is now lower than readState.Index' (duration: 89.321µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:03:19.719774Z","caller":"traceutil/trace.go:171","msg":"trace[1714972296] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"252.504328ms","start":"2026-07-01T11:03:19.467237Z","end":"2026-07-01T11:03:19.719741Z","steps":["trace[1714972296] 'process raft request' (duration: 252.232193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:19.719877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.721601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:03:19.719961Z","caller":"traceutil/trace.go:171","msg":"trace[1690538814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"154.863193ms","start":"2026-07-01T11:03:19.565076Z","end":"2026-07-01T11:03:19.719939Z","steps":["trace[1690538814] 'agreement among raft nodes before linearized reading' (duration: 154.69105ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:51.650349Z","caller":"traceutil/trace.go:171","msg":"trace[1585157629] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"147.637787ms","start":"2026-07-01T11:03:51.502675Z","end":"2026-07-01T11:03:51.650313Z","steps":["trace[1585157629] 'process raft request' (duration: 147.445273ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:14.915848Z","caller":"traceutil/trace.go:171","msg":"trace[1706471543] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"114.119197ms","start":"2026-07-01T11:04:14.80169Z","end":"2026-07-01T11:04:14.91581Z","steps":["trace[1706471543] 'process raft request' (duration: 89.85233ms)","trace[1706471543] 'compare' (duration: 24.124834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:04:38.291761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.678326ms","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":"warn","ts":"2026-07-01T11:04:38.291834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.620815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53c83002-570d-4c5a-88a0-27d8b516214b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-01T11:04:38.291883Z","caller":"traceutil/trace.go:171","msg":"trace[374803479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"152.83485ms","start":"2026-07-01T11:04:38.139009Z","end":"2026-07-01T11:04:38.291844Z","steps":["trace[374803479] 'range keys from in-memory index tree' (duration: 152.484693ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:38.291928Z","caller":"traceutil/trace.go:171","msg":"trace[238601450] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53c83002-570d-4c5a-88a0-27d8b516214b; range_end:; response_count:1; response_revision:1859; }","duration":"448.785348ms","start":"2026-07-01T11:04:37.843121Z","end":"2026-07-01T11:04:38.291906Z","steps":["trace[238601450] 'range keys from in-memory index tree' (duration: 448.3823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:38.291761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.400571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-01T11:04:38.291969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:04:37.843104Z","time spent":"448.856259ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53c83002-570d-4c5a-88a0-27d8b516214b\" "} {"level":"info","ts":"2026-07-01T11:04:38.292041Z","caller":"traceutil/trace.go:171","msg":"trace[272634998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"407.705058ms","start":"2026-07-01T11:04:37.884292Z","end":"2026-07-01T11:04:38.291997Z","steps":["trace[272634998] 'range keys from in-memory index tree' (duration: 407.222838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:38.292135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:04:37.884267Z","time spent":"407.82996ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T11:04:38.702986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.070528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T11:04:38.703226Z","caller":"traceutil/trace.go:171","msg":"trace[1436063541] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"400.02217ms","start":"2026-07-01T11:04:38.303165Z","end":"2026-07-01T11:04:38.703187Z","steps":["trace[1436063541] 'process raft request' (duration: 399.898288ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:38.703305Z","caller":"traceutil/trace.go:171","msg":"trace[487855622] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"404.080764ms","start":"2026-07-01T11:04:38.29915Z","end":"2026-07-01T11:04:38.703231Z","steps":["trace[487855622] 'process raft request' (duration: 114.584366ms)","trace[487855622] 'compare' (duration: 288.972277ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:04:38.70347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:04:38.303142Z","time spent":"400.231724ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:04:38.703483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:04:38.299128Z","time spent":"404.260097ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:04:38.703135Z","caller":"traceutil/trace.go:171","msg":"trace[101066418] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1932; }","duration":"139.268632ms","start":"2026-07-01T11:04:38.563854Z","end":"2026-07-01T11:04:38.703123Z","steps":["trace[101066418] 'read index received' (duration: 28.401µs)","trace[101066418] 'applied index is now lower than readState.Index' (duration: 139.239621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:04:38.703686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.327468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53c83002-570d-4c5a-88a0-27d8b516214b\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-07-01T11:04:38.703716Z","caller":"traceutil/trace.go:171","msg":"trace[806581467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53c83002-570d-4c5a-88a0-27d8b516214b; range_end:; response_count:1; response_revision:1863; }","duration":"129.40391ms","start":"2026-07-01T11:04:38.574302Z","end":"2026-07-01T11:04:38.703706Z","steps":["trace[806581467] 'agreement among raft nodes before linearized reading' (duration: 129.345988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:38.703987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.072969ms","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-01T11:04:38.704049Z","caller":"traceutil/trace.go:171","msg":"trace[1840756067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1863; }","duration":"120.14929ms","start":"2026-07-01T11:04:38.583876Z","end":"2026-07-01T11:04:38.704025Z","steps":["trace[1840756067] 'agreement among raft nodes before linearized reading' (duration: 119.985227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:38.704169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.295532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:04:38.704238Z","caller":"traceutil/trace.go:171","msg":"trace[2041863063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"140.394524ms","start":"2026-07-01T11:04:38.563822Z","end":"2026-07-01T11:04:38.704216Z","steps":["trace[2041863063] 'agreement among raft nodes before linearized reading' (duration: 139.516786ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:55.000586Z","caller":"traceutil/trace.go:171","msg":"trace[1187287561] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2073; }","duration":"147.926228ms","start":"2026-07-01T11:04:54.852635Z","end":"2026-07-01T11:04:55.000561Z","steps":["trace[1187287561] 'read index received' (duration: 147.897038ms)","trace[1187287561] 'applied index is now lower than readState.Index' (duration: 28.28µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:04:55.00068Z","caller":"traceutil/trace.go:171","msg":"trace[650579314] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"156.30395ms","start":"2026-07-01T11:04:54.844341Z","end":"2026-07-01T11:04:55.000645Z","steps":["trace[650579314] 'process raft request' (duration: 156.088116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:55.00076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.110702ms","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-01T11:04:55.000789Z","caller":"traceutil/trace.go:171","msg":"trace[1685384003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1999; }","duration":"148.174234ms","start":"2026-07-01T11:04:54.852608Z","end":"2026-07-01T11:04:55.000782Z","steps":["trace[1685384003] 'agreement among raft nodes before linearized reading' (duration: 148.041751ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:55.109713Z","caller":"traceutil/trace.go:171","msg":"trace[441129337] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"175.007243ms","start":"2026-07-01T11:04:54.934677Z","end":"2026-07-01T11:04:55.109685Z","steps":["trace[441129337] 'process raft request' (duration: 174.919141ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:55.109869Z","caller":"traceutil/trace.go:171","msg":"trace[1960240150] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"185.145511ms","start":"2026-07-01T11:04:54.924588Z","end":"2026-07-01T11:04:55.109734Z","steps":["trace[1960240150] 'process raft request' (duration: 184.839114ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:55.110254Z","caller":"traceutil/trace.go:171","msg":"trace[569377752] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"103.56288ms","start":"2026-07-01T11:04:55.006662Z","end":"2026-07-01T11:04:55.110224Z","steps":["trace[569377752] 'process raft request' (duration: 103.436888ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:05:33.561294Z","caller":"traceutil/trace.go:171","msg":"trace[1167423189] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"229.766222ms","start":"2026-07-01T11:05:33.331499Z","end":"2026-07-01T11:05:33.561265Z","steps":["trace[1167423189] 'process raft request' (duration: 229.599449ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:05:33.717654Z","caller":"traceutil/trace.go:171","msg":"trace[318604593] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"373.748981ms","start":"2026-07-01T11:05:33.343867Z","end":"2026-07-01T11:05:33.717616Z","steps":["trace[318604593] 'process raft request' (duration: 373.647409ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:05:33.717701Z","caller":"traceutil/trace.go:171","msg":"trace[2001628075] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"380.272801ms","start":"2026-07-01T11:05:33.33735Z","end":"2026-07-01T11:05:33.717623Z","steps":["trace[2001628075] 'process raft request' (duration: 302.14924ms)","trace[2001628075] 'compare' (duration: 77.883216ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:05:33.717835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:05:33.337332Z","time spent":"380.430945ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:05:33.717859Z","caller":"traceutil/trace.go:171","msg":"trace[630972820] linearizableReadLoop","detail":"{readStateIndex:2294; appliedIndex:2291; }","duration":"343.012251ms","start":"2026-07-01T11:05:33.374827Z","end":"2026-07-01T11:05:33.71784Z","steps":["trace[630972820] 'read index received' (duration: 186.300319ms)","trace[630972820] 'applied index is now lower than readState.Index' (duration: 156.710392ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:05:33.717945Z","caller":"traceutil/trace.go:171","msg":"trace[1133805817] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"316.516415ms","start":"2026-07-01T11:05:33.401422Z","end":"2026-07-01T11:05:33.717938Z","steps":["trace[1133805817] 'process raft request' (duration: 316.138548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:33.71799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:05:33.401406Z","time spent":"316.561135ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:05:33.718035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:05:33.343846Z","time spent":"373.895134ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:05:33.718077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.131923ms","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:7"} {"level":"info","ts":"2026-07-01T11:05:33.718101Z","caller":"traceutil/trace.go:171","msg":"trace[211358159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2212; }","duration":"134.158714ms","start":"2026-07-01T11:05:33.583936Z","end":"2026-07-01T11:05:33.718094Z","steps":["trace[211358159] 'agreement among raft nodes before linearized reading' (duration: 134.104723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:33.718095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.267416ms","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-01T11:05:33.718133Z","caller":"traceutil/trace.go:171","msg":"trace[339933163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2212; }","duration":"343.348728ms","start":"2026-07-01T11:05:33.374774Z","end":"2026-07-01T11:05:33.718123Z","steps":["trace[339933163] 'agreement among raft nodes before linearized reading' (duration: 343.176544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:33.718164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:05:33.374754Z","time spent":"343.402958ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T11:05:33.718142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.088869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:05:33.718214Z","caller":"traceutil/trace.go:171","msg":"trace[1125651731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2212; }","duration":"154.201242ms","start":"2026-07-01T11:05:33.563993Z","end":"2026-07-01T11:05:33.718194Z","steps":["trace[1125651731] 'agreement among raft nodes before linearized reading' (duration: 154.053629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:33.718187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.86775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-01T11:05:33.718276Z","caller":"traceutil/trace.go:171","msg":"trace[1486933299] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2212; }","duration":"330.987792ms","start":"2026-07-01T11:05:33.387259Z","end":"2026-07-01T11:05:33.718247Z","steps":["trace[1486933299] 'agreement among raft nodes before linearized reading' (duration: 330.777938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:33.718301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.193828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-01T11:05:33.718317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:05:33.387251Z","time spent":"331.056973ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-01T11:05:33.718336Z","caller":"traceutil/trace.go:171","msg":"trace[1900181170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2212; }","duration":"182.273409ms","start":"2026-07-01T11:05:33.536052Z","end":"2026-07-01T11:05:33.718325Z","steps":["trace[1900181170] 'agreement among raft nodes before linearized reading' (duration: 182.160767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:33.718506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.984903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T11:05:33.718558Z","caller":"traceutil/trace.go:171","msg":"trace[2027232165] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:2212; }","duration":"120.058184ms","start":"2026-07-01T11:05:33.598484Z","end":"2026-07-01T11:05:33.718542Z","steps":["trace[2027232165] 'agreement among raft nodes before linearized reading' (duration: 119.631925ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:06:41.95851Z","caller":"traceutil/trace.go:171","msg":"trace[1230116133] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"164.498466ms","start":"2026-07-01T11:06:41.793982Z","end":"2026-07-01T11:06:41.958481Z","steps":["trace[1230116133] 'process raft request' (duration: 108.786449ms)","trace[1230116133] 'compare' (duration: 55.272288ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:11:03.55797Z","caller":"traceutil/trace.go:171","msg":"trace[570509670] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"155.326036ms","start":"2026-07-01T11:11:03.40262Z","end":"2026-07-01T11:11:03.557946Z","steps":["trace[570509670] 'process raft request' (duration: 70.721824ms)","trace[570509670] 'compare' (duration: 84.446659ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:11:03.558146Z","caller":"traceutil/trace.go:171","msg":"trace[701847886] linearizableReadLoop","detail":"{readStateIndex:5572; appliedIndex:5570; }","duration":"110.712968ms","start":"2026-07-01T11:11:03.447408Z","end":"2026-07-01T11:11:03.558121Z","steps":["trace[701847886] 'read index received' (duration: 25.921963ms)","trace[701847886] 'applied index is now lower than readState.Index' (duration: 84.788975ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:11:03.558218Z","caller":"traceutil/trace.go:171","msg":"trace[698820786] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"124.191856ms","start":"2026-07-01T11:11:03.433997Z","end":"2026-07-01T11:11:03.558189Z","steps":["trace[698820786] 'process raft request' (duration: 123.91382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:11:03.558386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.958963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T11:11:03.558413Z","caller":"traceutil/trace.go:171","msg":"trace[217944839] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5374; }","duration":"111.038265ms","start":"2026-07-01T11:11:03.447367Z","end":"2026-07-01T11:11:03.558405Z","steps":["trace[217944839] 'agreement among raft nodes before linearized reading' (duration: 110.877342ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:11:06.629049Z","caller":"traceutil/trace.go:171","msg":"trace[255777810] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"153.724483ms","start":"2026-07-01T11:11:06.475262Z","end":"2026-07-01T11:11:06.628986Z","steps":["trace[255777810] 'process raft request' (duration: 153.317305ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:11:15.630335Z","caller":"traceutil/trace.go:171","msg":"trace[571610616] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"142.460941ms","start":"2026-07-01T11:11:15.487845Z","end":"2026-07-01T11:11:15.630306Z","steps":["trace[571610616] 'process raft request' (duration: 142.265777ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:12:20.594694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-07-01T11:12:20.668899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"72.611977ms","hash":3923873738,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T11:12:20.668973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3923873738,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-07-01T11:17:12.438808Z","caller":"traceutil/trace.go:171","msg":"trace[1960059584] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"276.806089ms","start":"2026-07-01T11:17:12.161958Z","end":"2026-07-01T11:17:12.438764Z","steps":["trace[1960059584] 'process raft request' (duration: 276.489682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:17:12.438971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.383715ms","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-01T11:17:12.438955Z","caller":"traceutil/trace.go:171","msg":"trace[1077037047] linearizableReadLoop","detail":"{readStateIndex:8543; appliedIndex:8542; }","duration":"223.093909ms","start":"2026-07-01T11:17:12.215566Z","end":"2026-07-01T11:17:12.43866Z","steps":["trace[1077037047] 'read index received' (duration: 222.774122ms)","trace[1077037047] 'applied index is now lower than readState.Index' (duration: 315.977µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:12.439107Z","caller":"traceutil/trace.go:171","msg":"trace[995536400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8253; }","duration":"223.578759ms","start":"2026-07-01T11:17:12.215501Z","end":"2026-07-01T11:17:12.43908Z","steps":["trace[995536400] 'agreement among raft nodes before linearized reading' (duration: 223.258922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:17:12.439979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.41137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:17:12.440069Z","caller":"traceutil/trace.go:171","msg":"trace[1535954896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8253; }","duration":"200.540582ms","start":"2026-07-01T11:17:12.239509Z","end":"2026-07-01T11:17:12.44005Z","steps":["trace[1535954896] 'agreement among raft nodes before linearized reading' (duration: 200.380429ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:12.609328Z","caller":"traceutil/trace.go:171","msg":"trace[485607013] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"164.955608ms","start":"2026-07-01T11:17:12.444331Z","end":"2026-07-01T11:17:12.609287Z","steps":["trace[485607013] 'process raft request' (duration: 151.028286ms)","trace[485607013] 'compare' (duration: 13.734249ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:12.616541Z","caller":"traceutil/trace.go:171","msg":"trace[438607175] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"139.700266ms","start":"2026-07-01T11:17:12.4768Z","end":"2026-07-01T11:17:12.6165Z","steps":["trace[438607175] 'process raft request' (duration: 139.358579ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:12.616704Z","caller":"traceutil/trace.go:171","msg":"trace[1059403785] linearizableReadLoop","detail":"{readStateIndex:8546; appliedIndex:8543; }","duration":"117.317792ms","start":"2026-07-01T11:17:12.499355Z","end":"2026-07-01T11:17:12.616673Z","steps":["trace[1059403785] 'read index received' (duration: 96.014259ms)","trace[1059403785] 'applied index is now lower than readState.Index' (duration: 21.302433ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:12.616837Z","caller":"traceutil/trace.go:171","msg":"trace[1183152705] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"139.92665ms","start":"2026-07-01T11:17:12.476882Z","end":"2026-07-01T11:17:12.616809Z","steps":["trace[1183152705] 'process raft request' (duration: 139.462451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:17:12.616893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.519196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T11:17:12.616932Z","caller":"traceutil/trace.go:171","msg":"trace[407330294] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8256; }","duration":"117.658039ms","start":"2026-07-01T11:17:12.499261Z","end":"2026-07-01T11:17:12.616919Z","steps":["trace[407330294] 'agreement among raft nodes before linearized reading' (duration: 117.546376ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:20.601708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-07-01T11:17:20.702794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"98.98115ms","hash":148550236,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":23781376,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-01T11:17:20.702864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":148550236,"revision":6103,"compact-revision":2995} {"level":"warn","ts":"2026-07-01T11:17:23.898691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.305127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T11:17:23.898819Z","caller":"traceutil/trace.go:171","msg":"trace[1610631789] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"183.931613ms","start":"2026-07-01T11:17:23.71486Z","end":"2026-07-01T11:17:23.898792Z","steps":["trace[1610631789] 'process raft request' (duration: 45.415352ms)","trace[1610631789] 'compare' (duration: 138.001981ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:23.928063Z","caller":"traceutil/trace.go:171","msg":"trace[1508110976] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"210.146116ms","start":"2026-07-01T11:17:23.717889Z","end":"2026-07-01T11:17:23.928035Z","steps":["trace[1508110976] 'process raft request' (duration: 209.475783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:17:24.786508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.755522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:17:24.786596Z","caller":"traceutil/trace.go:171","msg":"trace[289854467] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8317; }","duration":"158.889984ms","start":"2026-07-01T11:17:24.627688Z","end":"2026-07-01T11:17:24.786578Z","steps":["trace[289854467] 'count revisions from in-memory index tree' (duration: 158.63745ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:24.786649Z","caller":"traceutil/trace.go:171","msg":"trace[969098718] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"109.207496ms","start":"2026-07-01T11:17:24.677423Z","end":"2026-07-01T11:17:24.78663Z","steps":["trace[969098718] 'process raft request' (duration: 82.614657ms)","trace[969098718] 'compare' (duration: 26.474897ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:24.79575Z","caller":"traceutil/trace.go:171","msg":"trace[1697352962] linearizableReadLoop","detail":"{readStateIndex:8614; appliedIndex:8612; }","duration":"117.645479ms","start":"2026-07-01T11:17:24.678063Z","end":"2026-07-01T11:17:24.795708Z","steps":["trace[1697352962] 'read index received' (duration: 82.070026ms)","trace[1697352962] 'applied index is now lower than readState.Index' (duration: 35.573713ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:24.795831Z","caller":"traceutil/trace.go:171","msg":"trace[929617526] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"117.891704ms","start":"2026-07-01T11:17:24.67792Z","end":"2026-07-01T11:17:24.795812Z","steps":["trace[929617526] 'process raft request' (duration: 117.564157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:17:24.796073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.969195ms","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-01T11:17:24.796126Z","caller":"traceutil/trace.go:171","msg":"trace[333452577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8319; }","duration":"118.091598ms","start":"2026-07-01T11:17:24.678021Z","end":"2026-07-01T11:17:24.796112Z","steps":["trace[333452577] 'agreement among raft nodes before linearized reading' (duration: 117.846203ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:25.980145Z","caller":"traceutil/trace.go:171","msg":"trace[1629447214] transaction","detail":"{read_only:false; response_revision:8326; number_of_response:1; }","duration":"122.183559ms","start":"2026-07-01T11:17:25.857926Z","end":"2026-07-01T11:17:25.98011Z","steps":["trace[1629447214] 'process raft request' (duration: 121.937395ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:49.820658Z","caller":"traceutil/trace.go:171","msg":"trace[178683625] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"101.684285ms","start":"2026-07-01T11:17:49.71894Z","end":"2026-07-01T11:17:49.820624Z","steps":["trace[178683625] 'process raft request' (duration: 101.169765ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:53.805212Z","caller":"traceutil/trace.go:171","msg":"trace[325226369] linearizableReadLoop","detail":"{readStateIndex:8828; appliedIndex:8827; }","duration":"136.611875ms","start":"2026-07-01T11:17:53.668578Z","end":"2026-07-01T11:17:53.80519Z","steps":["trace[325226369] 'read index received' (duration: 136.529833ms)","trace[325226369] 'applied index is now lower than readState.Index' (duration: 80.672µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:17:53.805596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.015224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-01T11:17:53.805638Z","caller":"traceutil/trace.go:171","msg":"trace[814607292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8525; }","duration":"137.095595ms","start":"2026-07-01T11:17:53.66853Z","end":"2026-07-01T11:17:53.805626Z","steps":["trace[814607292] 'agreement among raft nodes before linearized reading' (duration: 136.953232ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:05.865487Z","caller":"traceutil/trace.go:171","msg":"trace[1238592453] linearizableReadLoop","detail":"{readStateIndex:8941; appliedIndex:8940; }","duration":"120.451877ms","start":"2026-07-01T11:18:05.745008Z","end":"2026-07-01T11:18:05.86546Z","steps":["trace[1238592453] 'read index received' (duration: 104.709128ms)","trace[1238592453] 'applied index is now lower than readState.Index' (duration: 15.741639ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:05.865577Z","caller":"traceutil/trace.go:171","msg":"trace[891897044] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8634; }","duration":"120.781064ms","start":"2026-07-01T11:18:05.744767Z","end":"2026-07-01T11:18:05.865548Z","steps":["trace[891897044] 'process raft request' (duration: 105.008304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:05.865711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.688791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-84p9q\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-07-01T11:18:05.86581Z","caller":"traceutil/trace.go:171","msg":"trace[545974995] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-84p9q; range_end:; response_count:1; response_revision:8634; }","duration":"120.822614ms","start":"2026-07-01T11:18:05.744961Z","end":"2026-07-01T11:18:05.865784Z","steps":["trace[545974995] 'agreement among raft nodes before linearized reading' (duration: 120.59204ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:06.535675Z","caller":"traceutil/trace.go:171","msg":"trace[230839457] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"128.006929ms","start":"2026-07-01T11:18:06.407642Z","end":"2026-07-01T11:18:06.535649Z","steps":["trace[230839457] 'process raft request' (duration: 127.920618ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:07.301401Z","caller":"traceutil/trace.go:171","msg":"trace[1733369270] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"151.050298ms","start":"2026-07-01T11:18:07.150309Z","end":"2026-07-01T11:18:07.301359Z","steps":["trace[1733369270] 'process raft request' (duration: 150.772193ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:07.453529Z","caller":"traceutil/trace.go:171","msg":"trace[462143756] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"126.694233ms","start":"2026-07-01T11:18:07.326804Z","end":"2026-07-01T11:18:07.453498Z","steps":["trace[462143756] 'process raft request' (duration: 30.171083ms)","trace[462143756] 'compare' (duration: 96.293126ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:45.013946Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002469.wal"} {"level":"info","ts":"2026-07-01T11:19:20.724952Z","caller":"traceutil/trace.go:171","msg":"trace[1101296754] transaction","detail":"{read_only:false; response_revision:9374; number_of_response:1; }","duration":"134.691346ms","start":"2026-07-01T11:19:20.590232Z","end":"2026-07-01T11:19:20.724923Z","steps":["trace[1101296754] 'process raft request' (duration: 134.479482ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:19:59.617852Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:19:59.624826Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T11:19:59.624937Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T11:22:20.608023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8296} {"level":"info","ts":"2026-07-01T11:22:20.695155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8296,"took":"83.952229ms","hash":827941749,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":26406912,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-01T11:22:20.695231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":827941749,"revision":8296,"compact-revision":6103} {"level":"info","ts":"2026-07-01T11:23:04.050146Z","caller":"traceutil/trace.go:171","msg":"trace[1572384332] transaction","detail":"{read_only:false; response_revision:11225; number_of_response:1; }","duration":"146.63289ms","start":"2026-07-01T11:23:03.903481Z","end":"2026-07-01T11:23:04.050114Z","steps":["trace[1572384332] 'process raft request' (duration: 146.490877ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:27:20.616167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10789} {"level":"info","ts":"2026-07-01T11:27:20.695904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10789,"took":"77.393321ms","hash":3372580015,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":28057600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-01T11:27:20.695964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3372580015,"revision":10789,"compact-revision":8296} {"level":"info","ts":"2026-07-01T11:28:46.753638Z","caller":"traceutil/trace.go:171","msg":"trace[719461285] transaction","detail":"{read_only:false; response_revision:13568; number_of_response:1; }","duration":"122.872734ms","start":"2026-07-01T11:28:46.630735Z","end":"2026-07-01T11:28:46.753608Z","steps":["trace[719461285] 'process raft request' (duration: 122.626049ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:23.187124Z","caller":"traceutil/trace.go:171","msg":"trace[1467934229] transaction","detail":"{read_only:false; response_revision:13773; number_of_response:1; }","duration":"122.154529ms","start":"2026-07-01T11:29:23.064938Z","end":"2026-07-01T11:29:23.187093Z","steps":["trace[1467934229] 'process raft request' (duration: 121.966616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:45.180921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.028377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T11:29:45.18103Z","caller":"traceutil/trace.go:171","msg":"trace[1028919793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14085; }","duration":"157.199381ms","start":"2026-07-01T11:29:45.023806Z","end":"2026-07-01T11:29:45.181006Z","steps":["trace[1028919793] 'range keys from in-memory index tree' (duration: 156.724371ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:45.367868Z","caller":"traceutil/trace.go:171","msg":"trace[159371829] linearizableReadLoop","detail":"{readStateIndex:14570; appliedIndex:14569; }","duration":"253.037273ms","start":"2026-07-01T11:29:45.114799Z","end":"2026-07-01T11:29:45.367837Z","steps":["trace[159371829] 'read index received' (duration: 252.548174ms)","trace[159371829] 'applied index is now lower than readState.Index' (duration: 487.689µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:29:45.368005Z","caller":"traceutil/trace.go:171","msg":"trace[958047260] transaction","detail":"{read_only:false; response_revision:14086; number_of_response:1; }","duration":"323.372753ms","start":"2026-07-01T11:29:45.044561Z","end":"2026-07-01T11:29:45.367934Z","steps":["trace[958047260] 'process raft request' (duration: 322.821462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:45.368108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.289308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3e492fd-2769-4a27-ae54-5010d66cde33\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-07-01T11:29:45.368147Z","caller":"traceutil/trace.go:171","msg":"trace[350111563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3e492fd-2769-4a27-ae54-5010d66cde33; range_end:; response_count:1; response_revision:14086; }","duration":"253.38975ms","start":"2026-07-01T11:29:45.114745Z","end":"2026-07-01T11:29:45.368135Z","steps":["trace[350111563] 'agreement among raft nodes before linearized reading' (duration: 253.222757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:45.368255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:29:45.044521Z","time spent":"323.601787ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:29:45.399727Z","caller":"traceutil/trace.go:171","msg":"trace[181053449] transaction","detail":"{read_only:false; response_revision:14087; number_of_response:1; }","duration":"213.967838ms","start":"2026-07-01T11:29:45.185732Z","end":"2026-07-01T11:29:45.3997Z","steps":["trace[181053449] 'process raft request' (duration: 213.732864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:45.400667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.304491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:29:45.40087Z","caller":"traceutil/trace.go:171","msg":"trace[341274117] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:14088; }","duration":"122.628787ms","start":"2026-07-01T11:29:45.278219Z","end":"2026-07-01T11:29:45.400848Z","steps":["trace[341274117] 'agreement among raft nodes before linearized reading' (duration: 121.843292ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:32:20.622416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12961} {"level":"info","ts":"2026-07-01T11:32:20.699513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12961,"took":"72.897042ms","hash":1504836146,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":30810112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T11:32:20.699568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1504836146,"revision":12961,"compact-revision":10789} {"level":"info","ts":"2026-07-01T11:36:51.296959Z","caller":"traceutil/trace.go:171","msg":"trace[454597380] transaction","detail":"{read_only:false; response_revision:17442; number_of_response:1; }","duration":"156.711165ms","start":"2026-07-01T11:36:51.140219Z","end":"2026-07-01T11:36:51.29693Z","steps":["trace[454597380] 'process raft request' (duration: 126.663872ms)","trace[454597380] 'compare' (duration: 29.42491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:36:58.091582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.606062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:36:58.091645Z","caller":"traceutil/trace.go:171","msg":"trace[1603737302] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:17483; }","duration":"126.712804ms","start":"2026-07-01T11:36:57.964917Z","end":"2026-07-01T11:36:58.09163Z","steps":["trace[1603737302] 'count revisions from in-memory index tree' (duration: 126.53179ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:36:58.623393Z","caller":"traceutil/trace.go:171","msg":"trace[1112961854] transaction","detail":"{read_only:false; response_revision:17485; number_of_response:1; }","duration":"143.183429ms","start":"2026-07-01T11:36:58.480185Z","end":"2026-07-01T11:36:58.623368Z","steps":["trace[1112961854] 'process raft request' (duration: 142.691529ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:37:05.492427Z","caller":"traceutil/trace.go:171","msg":"trace[1526568032] transaction","detail":"{read_only:false; response_revision:17605; number_of_response:1; }","duration":"103.970779ms","start":"2026-07-01T11:37:05.388418Z","end":"2026-07-01T11:37:05.492389Z","steps":["trace[1526568032] 'process raft request' (duration: 103.744975ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:37:20.630276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15353} {"level":"info","ts":"2026-07-01T11:37:20.735547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15353,"took":"102.197664ms","hash":437322512,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":32563200,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-01T11:37:20.735631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":437322512,"revision":15353,"compact-revision":12961} {"level":"info","ts":"2026-07-01T11:40:44.77379Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:40:44.78145Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T11:40:44.781519Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-01T11:42:20.636186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17754} {"level":"info","ts":"2026-07-01T11:42:20.733106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17754,"took":"94.060576ms","hash":2077968711,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":33329152,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-01T11:42:20.733172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2077968711,"revision":17754,"compact-revision":15353} {"level":"warn","ts":"2026-07-01T11:42:29.297952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.757711ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-01T11:42:34.867016Z","caller":"traceutil/trace.go:171","msg":"trace[1482534442] transaction","detail":"{read_only:false; response_revision:20107; number_of_response:1; }","duration":"175.193014ms","start":"2026-07-01T11:42:34.691792Z","end":"2026-07-01T11:42:34.866985Z","steps":["trace[1482534442] 'process raft request' (duration: 175.02175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:42:35.08201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.447264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:42:35.082109Z","caller":"traceutil/trace.go:171","msg":"trace[740507755] transaction","detail":"{read_only:false; response_revision:20108; number_of_response:1; }","duration":"365.180666ms","start":"2026-07-01T11:42:34.71691Z","end":"2026-07-01T11:42:35.082091Z","steps":["trace[740507755] 'process raft request' (duration: 219.471016ms)","trace[740507755] 'compare' (duration: 145.097006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:42:35.082801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:42:34.716877Z","time spent":"365.274887ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:42:35.613585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.279978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:42:35.613813Z","caller":"traceutil/trace.go:171","msg":"trace[33609086] transaction","detail":"{read_only:false; response_revision:20110; number_of_response:1; }","duration":"234.505667ms","start":"2026-07-01T11:42:35.379267Z","end":"2026-07-01T11:42:35.613772Z","steps":["trace[33609086] 'process raft request' (duration: 93.944153ms)","trace[33609086] 'compare' (duration: 140.138395ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:42:35.614089Z","caller":"traceutil/trace.go:171","msg":"trace[2071584249] transaction","detail":"{read_only:false; response_revision:20112; number_of_response:1; }","duration":"233.718551ms","start":"2026-07-01T11:42:35.380358Z","end":"2026-07-01T11:42:35.614077Z","steps":["trace[2071584249] 'process raft request' (duration: 233.386694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:42:35.614279Z","caller":"traceutil/trace.go:171","msg":"trace[1554187194] transaction","detail":"{read_only:false; response_revision:20111; number_of_response:1; }","duration":"233.923996ms","start":"2026-07-01T11:42:35.380339Z","end":"2026-07-01T11:42:35.614263Z","steps":["trace[1554187194] 'process raft request' (duration: 233.363393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:46:19.879226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.439451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:46:19.87943Z","caller":"traceutil/trace.go:171","msg":"trace[963848126] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:22498; }","duration":"103.711387ms","start":"2026-07-01T11:46:19.775696Z","end":"2026-07-01T11:46:19.879407Z","steps":["trace[963848126] 'count revisions from in-memory index tree' (duration: 103.36207ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:46:20.18851Z","caller":"traceutil/trace.go:171","msg":"trace[1800735668] linearizableReadLoop","detail":"{readStateIndex:23251; appliedIndex:23250; }","duration":"153.042722ms","start":"2026-07-01T11:46:20.03544Z","end":"2026-07-01T11:46:20.188483Z","steps":["trace[1800735668] 'read index received' (duration: 152.92072ms)","trace[1800735668] 'applied index is now lower than readState.Index' (duration: 120.922µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:46:20.188714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.248947ms","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-01T11:46:20.188752Z","caller":"traceutil/trace.go:171","msg":"trace[606670206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22499; }","duration":"153.35975ms","start":"2026-07-01T11:46:20.035382Z","end":"2026-07-01T11:46:20.188742Z","steps":["trace[606670206] 'agreement among raft nodes before linearized reading' (duration: 153.204125ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:46:20.188972Z","caller":"traceutil/trace.go:171","msg":"trace[1470702902] transaction","detail":"{read_only:false; response_revision:22499; number_of_response:1; }","duration":"245.537896ms","start":"2026-07-01T11:46:19.943031Z","end":"2026-07-01T11:46:20.188569Z","steps":["trace[1470702902] 'process raft request' (duration: 245.22733ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:46:20.190477Z","caller":"traceutil/trace.go:171","msg":"trace[22466135] transaction","detail":"{read_only:false; response_revision:22500; number_of_response:1; }","duration":"136.865657ms","start":"2026-07-01T11:46:20.053574Z","end":"2026-07-01T11:46:20.190439Z","steps":["trace[22466135] 'process raft request' (duration: 136.628703ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:47:20.642549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20035} {"level":"info","ts":"2026-07-01T11:47:20.753899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20035,"took":"108.053347ms","hash":3493342832,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":42455040,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-01T11:47:20.753959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3493342832,"revision":20035,"compact-revision":17754} {"level":"info","ts":"2026-07-01T11:49:51.857496Z","caller":"traceutil/trace.go:171","msg":"trace[1604689690] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"103.342737ms","start":"2026-07-01T11:49:51.754133Z","end":"2026-07-01T11:49:51.857476Z","steps":["trace[1604689690] 'process raft request' (duration: 102.52456ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:53.278181Z","caller":"traceutil/trace.go:171","msg":"trace[144828819] linearizableReadLoop","detail":"{readStateIndex:25431; appliedIndex:25430; }","duration":"291.91849ms","start":"2026-07-01T11:49:52.986243Z","end":"2026-07-01T11:49:53.278162Z","steps":["trace[144828819] 'read index received' (duration: 291.668475ms)","trace[144828819] 'applied index is now lower than readState.Index' (duration: 249.275µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:53.278468Z","caller":"traceutil/trace.go:171","msg":"trace[798079327] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"309.755845ms","start":"2026-07-01T11:49:52.968704Z","end":"2026-07-01T11:49:53.27846Z","steps":["trace[798079327] 'process raft request' (duration: 309.274595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:53.278558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:52.968687Z","time spent":"309.807335ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:49:53.278736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.515412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:49:53.278765Z","caller":"traceutil/trace.go:171","msg":"trace[223259796] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24622; }","duration":"292.630935ms","start":"2026-07-01T11:49:52.986126Z","end":"2026-07-01T11:49:53.278757Z","steps":["trace[223259796] 'agreement among raft nodes before linearized reading' (duration: 292.577764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:53.289177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.952713ms","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-01T11:49:53.289237Z","caller":"traceutil/trace.go:171","msg":"trace[1982966303] 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:24623; }","duration":"165.093605ms","start":"2026-07-01T11:49:53.12413Z","end":"2026-07-01T11:49:53.289223Z","steps":["trace[1982966303] 'agreement among raft nodes before linearized reading' (duration: 164.957463ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:53.289523Z","caller":"traceutil/trace.go:171","msg":"trace[1097878670] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"252.66951ms","start":"2026-07-01T11:49:53.036844Z","end":"2026-07-01T11:49:53.289513Z","steps":["trace[1097878670] 'process raft request' (duration: 252.133689ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:57.523189Z","caller":"traceutil/trace.go:171","msg":"trace[1302786038] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"221.900403ms","start":"2026-07-01T11:49:57.301262Z","end":"2026-07-01T11:49:57.523163Z","steps":["trace[1302786038] 'process raft request' (duration: 202.801593ms)","trace[1302786038] 'compare' (duration: 18.907236ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:57.523189Z","caller":"traceutil/trace.go:171","msg":"trace[385521002] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25460; }","duration":"169.945704ms","start":"2026-07-01T11:49:57.353227Z","end":"2026-07-01T11:49:57.523173Z","steps":["trace[385521002] 'read index received' (duration: 30.746636ms)","trace[385521002] 'applied index is now lower than readState.Index' (duration: 139.198228ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:57.523498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.261261ms","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-01T11:49:57.523546Z","caller":"traceutil/trace.go:171","msg":"trace[1276346538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24653; }","duration":"170.357672ms","start":"2026-07-01T11:49:57.353178Z","end":"2026-07-01T11:49:57.523535Z","steps":["trace[1276346538] 'agreement among raft nodes before linearized reading' (duration: 170.052496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:57.787694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.504076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:57.787943Z","caller":"traceutil/trace.go:171","msg":"trace[1035580560] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"258.699414ms","start":"2026-07-01T11:49:57.529212Z","end":"2026-07-01T11:49:57.787912Z","steps":["trace[1035580560] 'process raft request' (duration: 111.82979ms)","trace[1035580560] 'compare' (duration: 146.275331ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:57.796426Z","caller":"traceutil/trace.go:171","msg":"trace[1558236905] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"245.315981ms","start":"2026-07-01T11:49:57.551074Z","end":"2026-07-01T11:49:57.79639Z","steps":["trace[1558236905] 'process raft request' (duration: 245.104807ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:57.796406Z","caller":"traceutil/trace.go:171","msg":"trace[731378846] linearizableReadLoop","detail":"{readStateIndex:25465; appliedIndex:25463; }","duration":"232.124901ms","start":"2026-07-01T11:49:57.564241Z","end":"2026-07-01T11:49:57.796366Z","steps":["trace[731378846] 'read index received' (duration: 76.876136ms)","trace[731378846] 'applied index is now lower than readState.Index' (duration: 155.247465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:57.79661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.244688ms","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-01T11:49:57.796636Z","caller":"traceutil/trace.go:171","msg":"trace[271024882] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24655; }","duration":"136.318039ms","start":"2026-07-01T11:49:57.660309Z","end":"2026-07-01T11:49:57.796627Z","steps":["trace[271024882] 'agreement among raft nodes before linearized reading' (duration: 136.254107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:57.796636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.370586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-01T11:49:57.796698Z","caller":"traceutil/trace.go:171","msg":"trace[918589080] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24655; }","duration":"232.470499ms","start":"2026-07-01T11:49:57.564211Z","end":"2026-07-01T11:49:57.796681Z","steps":["trace[918589080] 'agreement among raft nodes before linearized reading' (duration: 232.269765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:58.070711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.93631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:58.071047Z","caller":"traceutil/trace.go:171","msg":"trace[1525847613] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25465; }","duration":"159.582423ms","start":"2026-07-01T11:49:57.911446Z","end":"2026-07-01T11:49:58.071029Z","steps":["trace[1525847613] 'read index received' (duration: 23.978828ms)","trace[1525847613] 'applied index is now lower than readState.Index' (duration: 135.602465ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:58.071173Z","caller":"traceutil/trace.go:171","msg":"trace[1056562401] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"269.934793ms","start":"2026-07-01T11:49:57.801227Z","end":"2026-07-01T11:49:58.071162Z","steps":["trace[1056562401] 'process raft request' (duration: 134.250196ms)","trace[1056562401] 'compare' (duration: 134.303418ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:58.071314Z","caller":"traceutil/trace.go:171","msg":"trace[1818569374] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"181.064751ms","start":"2026-07-01T11:49:57.890203Z","end":"2026-07-01T11:49:58.071268Z","steps":["trace[1818569374] 'process raft request' (duration: 180.789225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:58.071616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.175734ms","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-01T11:49:58.071645Z","caller":"traceutil/trace.go:171","msg":"trace[847723147] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"269.686847ms","start":"2026-07-01T11:49:57.801949Z","end":"2026-07-01T11:49:58.071636Z","steps":["trace[847723147] 'process raft request' (duration: 268.882141ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:58.071659Z","caller":"traceutil/trace.go:171","msg":"trace[729053694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24659; }","duration":"160.262826ms","start":"2026-07-01T11:49:57.911385Z","end":"2026-07-01T11:49:58.071648Z","steps":["trace[729053694] 'agreement among raft nodes before linearized reading' (duration: 160.042062ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:58.071741Z","caller":"traceutil/trace.go:171","msg":"trace[1647564961] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"192.687668ms","start":"2026-07-01T11:49:57.879043Z","end":"2026-07-01T11:49:58.071731Z","steps":["trace[1647564961] 'process raft request' (duration: 191.910702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:58.071868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.005502ms","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-01T11:49:58.071904Z","caller":"traceutil/trace.go:171","msg":"trace[1948485958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24659; }","duration":"134.097043ms","start":"2026-07-01T11:49:57.937798Z","end":"2026-07-01T11:49:58.071895Z","steps":["trace[1948485958] 'agreement among raft nodes before linearized reading' (duration: 134.018022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:58.072082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.499401ms","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-01T11:49:58.07211Z","caller":"traceutil/trace.go:171","msg":"trace[456304791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24659; }","duration":"132.575722ms","start":"2026-07-01T11:49:57.939529Z","end":"2026-07-01T11:49:58.072104Z","steps":["trace[456304791] 'agreement among raft nodes before linearized reading' (duration: 132.499731ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:58.376077Z","caller":"traceutil/trace.go:171","msg":"trace[1371386747] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"299.74513ms","start":"2026-07-01T11:49:58.076306Z","end":"2026-07-01T11:49:58.376051Z","steps":["trace[1371386747] 'process raft request' (duration: 299.508694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:58.37748Z","caller":"traceutil/trace.go:171","msg":"trace[1835415013] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"300.371892ms","start":"2026-07-01T11:49:58.077073Z","end":"2026-07-01T11:49:58.377445Z","steps":["trace[1835415013] 'process raft request' (duration: 300.147838ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:58.37765Z","caller":"traceutil/trace.go:171","msg":"trace[1860112237] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"299.848542ms","start":"2026-07-01T11:49:58.077784Z","end":"2026-07-01T11:49:58.377633Z","steps":["trace[1860112237] 'process raft request' (duration: 299.613088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:58.377703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:58.077065Z","time spent":"300.514365ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:58.378167Z","caller":"traceutil/trace.go:171","msg":"trace[1882397536] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"247.144328ms","start":"2026-07-01T11:49:58.131007Z","end":"2026-07-01T11:49:58.378152Z","steps":["trace[1882397536] 'process raft request' (duration: 246.564386ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.162085Z","caller":"traceutil/trace.go:171","msg":"trace[481076656] linearizableReadLoop","detail":"{readStateIndex:25475; appliedIndex:25474; }","duration":"115.092176ms","start":"2026-07-01T11:49:59.046974Z","end":"2026-07-01T11:49:59.162066Z","steps":["trace[481076656] 'read index received' (duration: 114.973144ms)","trace[481076656] 'applied index is now lower than readState.Index' (duration: 118.252µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:59.162206Z","caller":"traceutil/trace.go:171","msg":"trace[461595382] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"133.163554ms","start":"2026-07-01T11:49:59.029021Z","end":"2026-07-01T11:49:59.162185Z","steps":["trace[461595382] 'process raft request' (duration: 132.918459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:59.162223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.232158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:59.162256Z","caller":"traceutil/trace.go:171","msg":"trace[84241514] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24665; }","duration":"115.319081ms","start":"2026-07-01T11:49:59.046927Z","end":"2026-07-01T11:49:59.162247Z","steps":["trace[84241514] 'agreement among raft nodes before linearized reading' (duration: 115.240919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:59.460684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.228101ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-01T11:49:59.46079Z","caller":"traceutil/trace.go:171","msg":"trace[1626057845] linearizableReadLoop","detail":"{readStateIndex:25476; appliedIndex:25475; }","duration":"298.631588ms","start":"2026-07-01T11:49:59.162141Z","end":"2026-07-01T11:49:59.460772Z","steps":["trace[1626057845] 'read index received' (duration: 101.218893ms)","trace[1626057845] 'applied index is now lower than readState.Index' (duration: 197.411265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:59.461154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.037079ms","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-01T11:49:59.461248Z","caller":"traceutil/trace.go:171","msg":"trace[1187489051] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24665; }","duration":"411.143891ms","start":"2026-07-01T11:49:59.050091Z","end":"2026-07-01T11:49:59.461235Z","steps":["trace[1187489051] 'agreement among raft nodes before linearized reading' (duration: 410.998548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:59.461288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:59.050039Z","time spent":"411.238463ms","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:49:59.598458Z","caller":"traceutil/trace.go:171","msg":"trace[476403909] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"254.369745ms","start":"2026-07-01T11:49:59.344057Z","end":"2026-07-01T11:49:59.598426Z","steps":["trace[476403909] 'process raft request' (duration: 253.991357ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.643125Z","caller":"traceutil/trace.go:171","msg":"trace[199723194] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25476; }","duration":"182.283655ms","start":"2026-07-01T11:49:59.460817Z","end":"2026-07-01T11:49:59.643101Z","steps":["trace[199723194] 'read index received' (duration: 137.430561ms)","trace[199723194] 'applied index is now lower than readState.Index' (duration: 44.852184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:59.643367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.11263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-01T11:49:59.643449Z","caller":"traceutil/trace.go:171","msg":"trace[2012953955] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24669; }","duration":"255.243083ms","start":"2026-07-01T11:49:59.388192Z","end":"2026-07-01T11:49:59.643435Z","steps":["trace[2012953955] 'agreement among raft nodes before linearized reading' (duration: 255.007348ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.64344Z","caller":"traceutil/trace.go:171","msg":"trace[394286475] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"270.979844ms","start":"2026-07-01T11:49:59.372411Z","end":"2026-07-01T11:49:59.643391Z","steps":["trace[394286475] 'process raft request' (duration: 270.436333ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.643471Z","caller":"traceutil/trace.go:171","msg":"trace[1152940032] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"114.589316ms","start":"2026-07-01T11:49:59.528846Z","end":"2026-07-01T11:49:59.643435Z","steps":["trace[1152940032] 'process raft request' (duration: 114.164418ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.643542Z","caller":"traceutil/trace.go:171","msg":"trace[521916849] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"113.624647ms","start":"2026-07-01T11:49:59.52988Z","end":"2026-07-01T11:49:59.643505Z","steps":["trace[521916849] 'process raft request' (duration: 113.176768ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.81836Z","caller":"traceutil/trace.go:171","msg":"trace[582357653] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"168.574426ms","start":"2026-07-01T11:49:59.649736Z","end":"2026-07-01T11:49:59.81831Z","steps":["trace[582357653] 'process raft request' (duration: 168.335862ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:59.941362Z","caller":"traceutil/trace.go:171","msg":"trace[912004639] linearizableReadLoop","detail":"{readStateIndex:25482; appliedIndex:25481; }","duration":"123.106249ms","start":"2026-07-01T11:49:59.818231Z","end":"2026-07-01T11:49:59.941338Z","steps":["trace[912004639] 'read index received' (duration: 91.833662ms)","trace[912004639] 'applied index is now lower than readState.Index' (duration: 31.271687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:59.941576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.997396ms","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-01T11:49:59.941548Z","caller":"traceutil/trace.go:171","msg":"trace[1287729499] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"139.78181ms","start":"2026-07-01T11:49:59.801731Z","end":"2026-07-01T11:49:59.941513Z","steps":["trace[1287729499] 'process raft request' (duration: 108.41066ms)","trace[1287729499] 'compare' (duration: 31.066954ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:59.941605Z","caller":"traceutil/trace.go:171","msg":"trace[2055171956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24671; }","duration":"148.126948ms","start":"2026-07-01T11:49:59.79347Z","end":"2026-07-01T11:49:59.941597Z","steps":["trace[2055171956] 'agreement among raft nodes before linearized reading' (duration: 147.950935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.182679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.661076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:00.182756Z","caller":"traceutil/trace.go:171","msg":"trace[1159788009] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24672; }","duration":"138.797ms","start":"2026-07-01T11:50:00.043939Z","end":"2026-07-01T11:50:00.182736Z","steps":["trace[1159788009] 'range keys from in-memory index tree' (duration: 138.570824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.182865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.300727ms","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-01T11:50:00.182983Z","caller":"traceutil/trace.go:171","msg":"trace[1370256430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24672; }","duration":"105.536091ms","start":"2026-07-01T11:50:00.077423Z","end":"2026-07-01T11:50:00.18296Z","steps":["trace[1370256430] 'range keys from in-memory index tree' (duration: 105.050151ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:00.183054Z","caller":"traceutil/trace.go:171","msg":"trace[1042143896] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"105.184165ms","start":"2026-07-01T11:50:00.07786Z","end":"2026-07-01T11:50:00.183044Z","steps":["trace[1042143896] 'process raft request' (duration: 101.115011ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:00.18312Z","caller":"traceutil/trace.go:171","msg":"trace[1281699019] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"104.080973ms","start":"2026-07-01T11:50:00.079011Z","end":"2026-07-01T11:50:00.183092Z","steps":["trace[1281699019] 'process raft request' (duration: 103.95666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.527513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.561831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:00.527783Z","caller":"traceutil/trace.go:171","msg":"trace[1519816511] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"342.296908ms","start":"2026-07-01T11:50:00.185427Z","end":"2026-07-01T11:50:00.527724Z","steps":["trace[1519816511] 'process raft request' (duration: 190.448242ms)","trace[1519816511] 'compare' (duration: 151.12382ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:00.527849Z","caller":"traceutil/trace.go:171","msg":"trace[445912858] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"339.691905ms","start":"2026-07-01T11:50:00.18813Z","end":"2026-07-01T11:50:00.527822Z","steps":["trace[445912858] 'process raft request' (duration: 339.504561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.527959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.541811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:00.527874Z","caller":"traceutil/trace.go:171","msg":"trace[202194542] linearizableReadLoop","detail":"{readStateIndex:25487; appliedIndex:25485; }","duration":"339.450129ms","start":"2026-07-01T11:50:00.18841Z","end":"2026-07-01T11:50:00.52786Z","steps":["trace[202194542] 'read index received' (duration: 187.531732ms)","trace[202194542] 'applied index is now lower than readState.Index' (duration: 151.917067ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:00.527821Z","caller":"traceutil/trace.go:171","msg":"trace[2000350949] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"144.241051ms","start":"2026-07-01T11:50:00.383555Z","end":"2026-07-01T11:50:00.527796Z","steps":["trace[2000350949] 'process raft request' (duration: 144.1712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.527996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.188103Z","time spent":"339.816248ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:00.528019Z","caller":"traceutil/trace.go:171","msg":"trace[2135436811] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24677; }","duration":"339.637893ms","start":"2026-07-01T11:50:00.188366Z","end":"2026-07-01T11:50:00.528004Z","steps":["trace[2135436811] 'agreement among raft nodes before linearized reading' (duration: 339.537401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.527967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.185297Z","time spent":"342.583604ms","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T11:50:00.528076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.188355Z","time spent":"339.707814ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-07-01T11:50:00.528214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.341506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-01T11:50:00.528308Z","caller":"traceutil/trace.go:171","msg":"trace[613828683] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24677; }","duration":"181.464739ms","start":"2026-07-01T11:50:00.346833Z","end":"2026-07-01T11:50:00.528298Z","steps":["trace[613828683] 'agreement among raft nodes before linearized reading' (duration: 181.274065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.528296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.488872ms","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-01T11:50:00.528356Z","caller":"traceutil/trace.go:171","msg":"trace[1219050546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24677; }","duration":"134.582203ms","start":"2026-07-01T11:50:00.39376Z","end":"2026-07-01T11:50:00.528342Z","steps":["trace[1219050546] 'agreement among raft nodes before linearized reading' (duration: 134.380279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.5283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.085798ms","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-01T11:50:00.528409Z","caller":"traceutil/trace.go:171","msg":"trace[922354476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24677; }","duration":"145.22644ms","start":"2026-07-01T11:50:00.383167Z","end":"2026-07-01T11:50:00.528393Z","steps":["trace[922354476] 'agreement among raft nodes before linearized reading' (duration: 144.961005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.528475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.284202ms","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-01T11:50:00.528525Z","caller":"traceutil/trace.go:171","msg":"trace[1605269046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24677; }","duration":"147.375094ms","start":"2026-07-01T11:50:00.381138Z","end":"2026-07-01T11:50:00.528513Z","steps":["trace[1605269046] 'agreement among raft nodes before linearized reading' (duration: 147.026007ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:00.896079Z","caller":"traceutil/trace.go:171","msg":"trace[1952813884] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"363.708144ms","start":"2026-07-01T11:50:00.532347Z","end":"2026-07-01T11:50:00.896055Z","steps":["trace[1952813884] 'process raft request' (duration: 363.464089ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:00.896404Z","caller":"traceutil/trace.go:171","msg":"trace[30070324] linearizableReadLoop","detail":"{readStateIndex:25493; appliedIndex:25489; }","duration":"307.58706ms","start":"2026-07-01T11:50:00.588798Z","end":"2026-07-01T11:50:00.896386Z","steps":["trace[30070324] 'read index received' (duration: 307.152541ms)","trace[30070324] 'applied index is now lower than readState.Index' (duration: 433.819µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:00.896413Z","caller":"traceutil/trace.go:171","msg":"trace[2121087954] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"364.0137ms","start":"2026-07-01T11:50:00.53237Z","end":"2026-07-01T11:50:00.896384Z","steps":["trace[2121087954] 'process raft request' (duration: 363.617552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.896816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.532351Z","time spent":"364.415428ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:00.896807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.532337Z","time spent":"364.325866ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:00.896492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.969568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:00.896982Z","caller":"traceutil/trace.go:171","msg":"trace[148469733] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:24682; }","duration":"363.485398ms","start":"2026-07-01T11:50:00.533482Z","end":"2026-07-01T11:50:00.896967Z","steps":["trace[148469733] 'agreement among raft nodes before linearized reading' (duration: 362.957278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.897022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.533474Z","time spent":"363.535819ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"warn","ts":"2026-07-01T11:50:00.896532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.958927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:00.897109Z","caller":"traceutil/trace.go:171","msg":"trace[1754925181] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24682; }","duration":"363.558581ms","start":"2026-07-01T11:50:00.533541Z","end":"2026-07-01T11:50:00.897099Z","steps":["trace[1754925181] 'agreement among raft nodes before linearized reading' (duration: 362.971698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.897137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.533531Z","time spent":"363.598672ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-07-01T11:50:00.896528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.107619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:00.897262Z","caller":"traceutil/trace.go:171","msg":"trace[142987474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24682; }","duration":"332.882396ms","start":"2026-07-01T11:50:00.564354Z","end":"2026-07-01T11:50:00.897237Z","steps":["trace[142987474] 'agreement among raft nodes before linearized reading' (duration: 332.109049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.897336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.564332Z","time spent":"332.980077ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T11:50:00.896568Z","caller":"traceutil/trace.go:171","msg":"trace[1230145832] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"363.039519ms","start":"2026-07-01T11:50:00.533521Z","end":"2026-07-01T11:50:00.89656Z","steps":["trace[1230145832] 'process raft request' (duration: 362.740013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.897432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.533368Z","time spent":"364.02449ms","remote":"127.0.0.1:48268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T11:50:00.896544Z","caller":"traceutil/trace.go:171","msg":"trace[1378724542] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"360.852096ms","start":"2026-07-01T11:50:00.535656Z","end":"2026-07-01T11:50:00.896508Z","steps":["trace[1378724542] 'process raft request' (duration: 360.685313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:00.897587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.535646Z","time spent":"361.911428ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:00.944853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.230964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:50:00.944934Z","caller":"traceutil/trace.go:171","msg":"trace[1880516091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24683; }","duration":"179.363737ms","start":"2026-07-01T11:50:00.765549Z","end":"2026-07-01T11:50:00.944913Z","steps":["trace[1880516091] 'agreement among raft nodes before linearized reading' (duration: 179.206423ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:01.204669Z","caller":"traceutil/trace.go:171","msg":"trace[2097329823] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25494; }","duration":"259.905057ms","start":"2026-07-01T11:50:00.944738Z","end":"2026-07-01T11:50:01.204643Z","steps":["trace[2097329823] 'read index received' (duration: 259.601361ms)","trace[2097329823] 'applied index is now lower than readState.Index' (duration: 302.186µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:01.204797Z","caller":"traceutil/trace.go:171","msg":"trace[942814545] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"305.315743ms","start":"2026-07-01T11:50:00.899445Z","end":"2026-07-01T11:50:01.20476Z","steps":["trace[942814545] 'process raft request' (duration: 305.035908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:01.204945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.899411Z","time spent":"305.463937ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T11:50:01.204982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.581519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:01.205023Z","caller":"traceutil/trace.go:171","msg":"trace[1206643937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24684; }","duration":"306.668001ms","start":"2026-07-01T11:50:00.898345Z","end":"2026-07-01T11:50:01.205013Z","steps":["trace[1206643937] 'agreement among raft nodes before linearized reading' (duration: 306.547758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:01.20506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:00.898328Z","time spent":"306.724501ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T11:50:01.526664Z","caller":"traceutil/trace.go:171","msg":"trace[183172715] linearizableReadLoop","detail":"{readStateIndex:25498; appliedIndex:25496; }","duration":"239.096914ms","start":"2026-07-01T11:50:01.287537Z","end":"2026-07-01T11:50:01.526634Z","steps":["trace[183172715] 'read index received' (duration: 238.800348ms)","trace[183172715] 'applied index is now lower than readState.Index' (duration: 295.166µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:01.526794Z","caller":"traceutil/trace.go:171","msg":"trace[437142682] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"315.187034ms","start":"2026-07-01T11:50:01.211586Z","end":"2026-07-01T11:50:01.526773Z","steps":["trace[437142682] 'process raft request' (duration: 314.97656ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:01.526804Z","caller":"traceutil/trace.go:171","msg":"trace[1895235124] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"316.836388ms","start":"2026-07-01T11:50:01.209895Z","end":"2026-07-01T11:50:01.526731Z","steps":["trace[1895235124] 'process raft request' (duration: 316.452181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:01.526942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:01.211547Z","time spent":"315.288107ms","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:01.526885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.342798ms","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-01T11:50:01.52701Z","caller":"traceutil/trace.go:171","msg":"trace[1239508325] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24687; }","duration":"239.513692ms","start":"2026-07-01T11:50:01.287475Z","end":"2026-07-01T11:50:01.526988Z","steps":["trace[1239508325] 'agreement among raft nodes before linearized reading' (duration: 239.279337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:01.527026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:01.209871Z","time spent":"317.063823ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5660,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:01.659524Z","caller":"traceutil/trace.go:171","msg":"trace[1292087074] linearizableReadLoop","detail":"{readStateIndex:25499; appliedIndex:25498; }","duration":"127.092991ms","start":"2026-07-01T11:50:01.532405Z","end":"2026-07-01T11:50:01.659498Z","steps":["trace[1292087074] 'read index received' (duration: 87.572866ms)","trace[1292087074] 'applied index is now lower than readState.Index' (duration: 39.519055ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:01.65963Z","caller":"traceutil/trace.go:171","msg":"trace[1672432715] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"127.912417ms","start":"2026-07-01T11:50:01.531692Z","end":"2026-07-01T11:50:01.659604Z","steps":["trace[1672432715] 'process raft request' (duration: 88.228519ms)","trace[1672432715] 'compare' (duration: 39.23922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:01.659795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.382977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-01T11:50:01.659841Z","caller":"traceutil/trace.go:171","msg":"trace[458423239] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24688; }","duration":"127.464649ms","start":"2026-07-01T11:50:01.532364Z","end":"2026-07-01T11:50:01.659829Z","steps":["trace[458423239] 'agreement among raft nodes before linearized reading' (duration: 127.247735ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:01.767631Z","caller":"traceutil/trace.go:171","msg":"trace[872472438] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"117.108177ms","start":"2026-07-01T11:50:01.650497Z","end":"2026-07-01T11:50:01.767605Z","steps":["trace[872472438] 'process raft request' (duration: 114.508474ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:01.767735Z","caller":"traceutil/trace.go:171","msg":"trace[364297444] linearizableReadLoop","detail":"{readStateIndex:25501; appliedIndex:25499; }","duration":"108.121923ms","start":"2026-07-01T11:50:01.659588Z","end":"2026-07-01T11:50:01.76771Z","steps":["trace[364297444] 'read index received' (duration: 105.396088ms)","trace[364297444] 'applied index is now lower than readState.Index' (duration: 2.723765ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:01.767811Z","caller":"traceutil/trace.go:171","msg":"trace[1298459264] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"110.229556ms","start":"2026-07-01T11:50:01.657566Z","end":"2026-07-01T11:50:01.767795Z","steps":["trace[1298459264] 'process raft request' (duration: 109.990501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:01.768001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.529936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29715110-gtx4s\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-07-01T11:50:01.768078Z","caller":"traceutil/trace.go:171","msg":"trace[685191247] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29715110-gtx4s; range_end:; response_count:1; response_revision:24690; }","duration":"221.658909ms","start":"2026-07-01T11:50:01.546402Z","end":"2026-07-01T11:50:01.76806Z","steps":["trace[685191247] 'agreement among raft nodes before linearized reading' (duration: 221.427764ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:01.978744Z","caller":"traceutil/trace.go:171","msg":"trace[963353929] linearizableReadLoop","detail":"{readStateIndex:25502; appliedIndex:25501; }","duration":"192.728599ms","start":"2026-07-01T11:50:01.785951Z","end":"2026-07-01T11:50:01.97868Z","steps":["trace[963353929] 'read index received' (duration: 117.624357ms)","trace[963353929] 'applied index is now lower than readState.Index' (duration: 75.103092ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:01.978795Z","caller":"traceutil/trace.go:171","msg":"trace[1167071074] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"201.012347ms","start":"2026-07-01T11:50:01.777748Z","end":"2026-07-01T11:50:01.97876Z","steps":["trace[1167071074] 'process raft request' (duration: 125.917526ms)","trace[1167071074] 'compare' (duration: 74.713973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:01.979226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.310746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-01T11:50:01.979266Z","caller":"traceutil/trace.go:171","msg":"trace[49931438] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24692; }","duration":"157.389878ms","start":"2026-07-01T11:50:01.821865Z","end":"2026-07-01T11:50:01.979255Z","steps":["trace[49931438] 'agreement among raft nodes before linearized reading' (duration: 157.170824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:01.980251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.2882ms","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-01T11:50:01.980325Z","caller":"traceutil/trace.go:171","msg":"trace[783026028] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24691; }","duration":"194.378472ms","start":"2026-07-01T11:50:01.785927Z","end":"2026-07-01T11:50:01.980305Z","steps":["trace[783026028] 'agreement among raft nodes before linearized reading' (duration: 192.973814ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:02.205957Z","caller":"traceutil/trace.go:171","msg":"trace[671167467] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"220.518436ms","start":"2026-07-01T11:50:01.985406Z","end":"2026-07-01T11:50:02.205924Z","steps":["trace[671167467] 'process raft request' (duration: 196.684439ms)","trace[671167467] 'compare' (duration: 22.959628ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:02.206061Z","caller":"traceutil/trace.go:171","msg":"trace[823362575] linearizableReadLoop","detail":"{readStateIndex:25504; appliedIndex:25503; }","duration":"164.700718ms","start":"2026-07-01T11:50:02.041326Z","end":"2026-07-01T11:50:02.206026Z","steps":["trace[823362575] 'read index received' (duration: 140.843181ms)","trace[823362575] 'applied index is now lower than readState.Index' (duration: 23.854887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:02.206318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.972763ms","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-01T11:50:02.206362Z","caller":"traceutil/trace.go:171","msg":"trace[2027719469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24695; }","duration":"165.032034ms","start":"2026-07-01T11:50:02.041318Z","end":"2026-07-01T11:50:02.20635Z","steps":["trace[2027719469] 'agreement among raft nodes before linearized reading' (duration: 164.81217ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:02.409159Z","caller":"traceutil/trace.go:171","msg":"trace[1530311817] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"119.505415ms","start":"2026-07-01T11:50:02.289605Z","end":"2026-07-01T11:50:02.409111Z","steps":["trace[1530311817] 'process raft request' (duration: 102.735213ms)","trace[1530311817] 'compare' (duration: 16.459986ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:02.541226Z","caller":"traceutil/trace.go:171","msg":"trace[1317139318] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"230.026298ms","start":"2026-07-01T11:50:02.311159Z","end":"2026-07-01T11:50:02.541185Z","steps":["trace[1317139318] 'process raft request' (duration: 229.726492ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:02.893669Z","caller":"traceutil/trace.go:171","msg":"trace[835225872] linearizableReadLoop","detail":"{readStateIndex:25512; appliedIndex:25509; }","duration":"333.314285ms","start":"2026-07-01T11:50:02.560317Z","end":"2026-07-01T11:50:02.893631Z","steps":["trace[835225872] 'read index received' (duration: 2.526792ms)","trace[835225872] 'applied index is now lower than readState.Index' (duration: 330.786053ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:02.893693Z","caller":"traceutil/trace.go:171","msg":"trace[481939280] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"347.084255ms","start":"2026-07-01T11:50:02.546565Z","end":"2026-07-01T11:50:02.893649Z","steps":["trace[481939280] 'process raft request' (duration: 346.760318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:02.893907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.514786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:02.89372Z","caller":"traceutil/trace.go:171","msg":"trace[157847109] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"343.349268ms","start":"2026-07-01T11:50:02.55035Z","end":"2026-07-01T11:50:02.893699Z","steps":["trace[157847109] 'process raft request' (duration: 343.201955ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:02.893974Z","caller":"traceutil/trace.go:171","msg":"trace[279444371] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24701; }","duration":"328.618498ms","start":"2026-07-01T11:50:02.565339Z","end":"2026-07-01T11:50:02.893957Z","steps":["trace[279444371] 'agreement among raft nodes before linearized reading' (duration: 328.518286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:02.893959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:02.54654Z","time spent":"347.31324ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:02.894021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:02.565323Z","time spent":"328.686319ms","remote":"127.0.0.1:48326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:50:02.894025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.507748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:50:02.894088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:02.550327Z","time spent":"343.660685ms","remote":"127.0.0.1:56424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2876,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T11:50:02.894141Z","caller":"traceutil/trace.go:171","msg":"trace[1735113178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24701; }","duration":"333.805524ms","start":"2026-07-01T11:50:02.560251Z","end":"2026-07-01T11:50:02.894056Z","steps":["trace[1735113178] 'agreement among raft nodes before linearized reading' (duration: 333.505468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:02.894142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.363912ms","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-01T11:50:02.894249Z","caller":"traceutil/trace.go:171","msg":"trace[124932700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24701; }","duration":"301.493885ms","start":"2026-07-01T11:50:02.592721Z","end":"2026-07-01T11:50:02.894215Z","steps":["trace[124932700] 'agreement among raft nodes before linearized reading' (duration: 301.266511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:02.894304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:02.592695Z","time spent":"301.599537ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T11:50:02.894181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:02.560229Z","time spent":"333.936866ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T11:50:02.89479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.488604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:02.894844Z","caller":"traceutil/trace.go:171","msg":"trace[1762618199] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24701; }","duration":"195.402094ms","start":"2026-07-01T11:50:02.699426Z","end":"2026-07-01T11:50:02.894828Z","steps":["trace[1762618199] 'agreement among raft nodes before linearized reading' (duration: 194.445403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:03.386786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.930834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:03.386877Z","caller":"traceutil/trace.go:171","msg":"trace[1596733227] linearizableReadLoop","detail":"{readStateIndex:25516; appliedIndex:25514; }","duration":"374.3726ms","start":"2026-07-01T11:50:03.01249Z","end":"2026-07-01T11:50:03.386862Z","steps":["trace[1596733227] 'read index received' (duration: 23.678362ms)","trace[1596733227] 'applied index is now lower than readState.Index' (duration: 350.693128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:03.386936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.456812ms","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-01T11:50:03.386969Z","caller":"traceutil/trace.go:171","msg":"trace[1146948454] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24705; }","duration":"374.551354ms","start":"2026-07-01T11:50:03.012408Z","end":"2026-07-01T11:50:03.386959Z","steps":["trace[1146948454] 'agreement among raft nodes before linearized reading' (duration: 374.495743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:03.387015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.012376Z","time spent":"374.621745ms","remote":"127.0.0.1:56472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:50:03.387208Z","caller":"traceutil/trace.go:171","msg":"trace[798864711] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"384.403205ms","start":"2026-07-01T11:50:03.002791Z","end":"2026-07-01T11:50:03.387194Z","steps":["trace[798864711] 'process raft request' (duration: 134.991801ms)","trace[798864711] 'compare' (duration: 248.829802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:03.387269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.002769Z","time spent":"384.470087ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:03.677159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.450772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:03.677822Z","caller":"traceutil/trace.go:171","msg":"trace[22751887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24705; }","duration":"113.136836ms","start":"2026-07-01T11:50:03.564655Z","end":"2026-07-01T11:50:03.677792Z","steps":["trace[22751887] 'range keys from in-memory index tree' (duration: 112.302199ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.431386Z","caller":"traceutil/trace.go:171","msg":"trace[1666658798] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"209.855548ms","start":"2026-07-01T11:50:06.221505Z","end":"2026-07-01T11:50:06.431361Z","steps":["trace[1666658798] 'process raft request' (duration: 209.675604ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.463804Z","caller":"traceutil/trace.go:171","msg":"trace[1342062749] linearizableReadLoop","detail":"{readStateIndex:25541; appliedIndex:25539; }","duration":"216.203008ms","start":"2026-07-01T11:50:06.247574Z","end":"2026-07-01T11:50:06.463777Z","steps":["trace[1342062749] 'read index received' (duration: 183.695725ms)","trace[1342062749] 'applied index is now lower than readState.Index' (duration: 32.506233ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:06.464114Z","caller":"traceutil/trace.go:171","msg":"trace[1868406607] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"222.083317ms","start":"2026-07-01T11:50:06.242Z","end":"2026-07-01T11:50:06.464083Z","steps":["trace[1868406607] 'process raft request' (duration: 221.623328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.464304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.708858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-01T11:50:06.464354Z","caller":"traceutil/trace.go:171","msg":"trace[96588127] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24729; }","duration":"216.80151ms","start":"2026-07-01T11:50:06.247538Z","end":"2026-07-01T11:50:06.464339Z","steps":["trace[96588127] 'agreement among raft nodes before linearized reading' (duration: 216.628167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.46458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.38652ms","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-01T11:50:06.464629Z","caller":"traceutil/trace.go:171","msg":"trace[463899386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24729; }","duration":"187.484032ms","start":"2026-07-01T11:50:06.277134Z","end":"2026-07-01T11:50:06.464618Z","steps":["trace[463899386] 'agreement among raft nodes before linearized reading' (duration: 187.319109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.464747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.367967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:06.46479Z","caller":"traceutil/trace.go:171","msg":"trace[2004633464] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24729; }","duration":"157.457719ms","start":"2026-07-01T11:50:06.307322Z","end":"2026-07-01T11:50:06.464779Z","steps":["trace[2004633464] 'agreement among raft nodes before linearized reading' (duration: 157.389448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.464797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.793437ms","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-01T11:50:06.464831Z","caller":"traceutil/trace.go:171","msg":"trace[1079624087] 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:24729; }","duration":"183.870208ms","start":"2026-07-01T11:50:06.280949Z","end":"2026-07-01T11:50:06.464819Z","steps":["trace[1079624087] 'agreement among raft nodes before linearized reading' (duration: 183.813117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.834402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.274389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:06.834522Z","caller":"traceutil/trace.go:171","msg":"trace[873967171] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"365.661734ms","start":"2026-07-01T11:50:06.46884Z","end":"2026-07-01T11:50:06.834502Z","steps":["trace[873967171] 'process raft request' (duration: 200.206022ms)","trace[873967171] 'compare' (duration: 165.110876ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:06.834581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.468813Z","time spent":"365.739455ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:06.899953Z","caller":"traceutil/trace.go:171","msg":"trace[813013168] linearizableReadLoop","detail":"{readStateIndex:25543; appliedIndex:25541; }","duration":"383.140669ms","start":"2026-07-01T11:50:06.51679Z","end":"2026-07-01T11:50:06.899931Z","steps":["trace[813013168] 'read index received' (duration: 152.256793ms)","trace[813013168] 'applied index is now lower than readState.Index' (duration: 230.882976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:06.900107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.291822ms","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-01T11:50:06.900145Z","caller":"traceutil/trace.go:171","msg":"trace[1384739733] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24733; }","duration":"383.375914ms","start":"2026-07-01T11:50:06.516757Z","end":"2026-07-01T11:50:06.900133Z","steps":["trace[1384739733] 'agreement among raft nodes before linearized reading' (duration: 383.256861ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.900162Z","caller":"traceutil/trace.go:171","msg":"trace[583415707] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"325.496415ms","start":"2026-07-01T11:50:06.574626Z","end":"2026-07-01T11:50:06.900123Z","steps":["trace[583415707] 'process raft request' (duration: 325.211649ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.900144Z","caller":"traceutil/trace.go:171","msg":"trace[1505769753] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"431.040145ms","start":"2026-07-01T11:50:06.469066Z","end":"2026-07-01T11:50:06.900106Z","steps":["trace[1505769753] 'process raft request' (duration: 430.631557ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.900221Z","caller":"traceutil/trace.go:171","msg":"trace[1513892286] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"231.771773ms","start":"2026-07-01T11:50:06.668439Z","end":"2026-07-01T11:50:06.900211Z","steps":["trace[1513892286] 'process raft request' (duration: 231.447567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.900296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.574589Z","time spent":"325.634808ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:06.900317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.469042Z","time spent":"431.192629ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:06.900181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.516694Z","time spent":"383.477486ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:50:07.300627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.996757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:07.300876Z","caller":"traceutil/trace.go:171","msg":"trace[1261171773] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"286.99141ms","start":"2026-07-01T11:50:07.013851Z","end":"2026-07-01T11:50:07.300842Z","steps":["trace[1261171773] 'process raft request' (duration: 137.677476ms)","trace[1261171773] 'compare' (duration: 148.863035ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:07.301303Z","caller":"traceutil/trace.go:171","msg":"trace[241674939] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"267.970103ms","start":"2026-07-01T11:50:07.033315Z","end":"2026-07-01T11:50:07.301285Z","steps":["trace[241674939] 'process raft request' (duration: 267.434271ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:07.301295Z","caller":"traceutil/trace.go:171","msg":"trace[419375156] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25546; }","duration":"253.200141ms","start":"2026-07-01T11:50:07.048071Z","end":"2026-07-01T11:50:07.301271Z","steps":["trace[419375156] 'read index received' (duration: 103.464049ms)","trace[419375156] 'applied index is now lower than readState.Index' (duration: 149.731372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:07.301398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.310714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:50:07.301583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.019907ms","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-01T11:50:07.301593Z","caller":"traceutil/trace.go:171","msg":"trace[894712170] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24736; }","duration":"253.506708ms","start":"2026-07-01T11:50:07.048063Z","end":"2026-07-01T11:50:07.30157Z","steps":["trace[894712170] 'agreement among raft nodes before linearized reading' (duration: 253.273784ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:07.301636Z","caller":"traceutil/trace.go:171","msg":"trace[2020325727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24736; }","duration":"252.107529ms","start":"2026-07-01T11:50:07.049514Z","end":"2026-07-01T11:50:07.301621Z","steps":["trace[2020325727] 'agreement among raft nodes before linearized reading' (duration: 251.927736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:07.301635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.454516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:50:07.301643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.068931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:07.301698Z","caller":"traceutil/trace.go:171","msg":"trace[622033898] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24736; }","duration":"226.557189ms","start":"2026-07-01T11:50:07.075124Z","end":"2026-07-01T11:50:07.301681Z","steps":["trace[622033898] 'agreement among raft nodes before linearized reading' (duration: 226.436776ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:07.30171Z","caller":"traceutil/trace.go:171","msg":"trace[2032866984] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24736; }","duration":"104.176134ms","start":"2026-07-01T11:50:07.197514Z","end":"2026-07-01T11:50:07.30169Z","steps":["trace[2032866984] 'agreement among raft nodes before linearized reading' (duration: 104.043171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:07.729446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.886869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:07.729519Z","caller":"traceutil/trace.go:171","msg":"trace[50364570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24737; }","duration":"161.004301ms","start":"2026-07-01T11:50:07.568498Z","end":"2026-07-01T11:50:07.729503Z","steps":["trace[50364570] 'range keys from in-memory index tree' (duration: 160.782007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:07.729739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.327003ms","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-01T11:50:07.729804Z","caller":"traceutil/trace.go:171","msg":"trace[1291623802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24737; }","duration":"326.443584ms","start":"2026-07-01T11:50:07.403344Z","end":"2026-07-01T11:50:07.729787Z","steps":["trace[1291623802] 'range keys from in-memory index tree' (duration: 326.155977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:07.729835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:07.403322Z","time spent":"326.506996ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T11:50:07.729949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.249593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:07.730034Z","caller":"traceutil/trace.go:171","msg":"trace[578990459] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24737; }","duration":"176.393766ms","start":"2026-07-01T11:50:07.553621Z","end":"2026-07-01T11:50:07.730015Z","steps":["trace[578990459] 'count revisions from in-memory index tree' (duration: 176.14301ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:10.837856Z","caller":"traceutil/trace.go:171","msg":"trace[1485321481] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"395.40223ms","start":"2026-07-01T11:50:10.442429Z","end":"2026-07-01T11:50:10.837831Z","steps":["trace[1485321481] 'process raft request' (duration: 395.269027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:10.838645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:10.442403Z","time spent":"395.511481ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:11.313566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.374389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:11.313667Z","caller":"traceutil/trace.go:171","msg":"trace[1401047603] linearizableReadLoop","detail":"{readStateIndex:25577; appliedIndex:25576; }","duration":"454.897072ms","start":"2026-07-01T11:50:10.858753Z","end":"2026-07-01T11:50:11.31365Z","steps":["trace[1401047603] 'read index received' (duration: 190.370639ms)","trace[1401047603] 'applied index is now lower than readState.Index' (duration: 264.525383ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:11.31403Z","caller":"traceutil/trace.go:171","msg":"trace[784375428] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"832.352936ms","start":"2026-07-01T11:50:10.481649Z","end":"2026-07-01T11:50:11.314002Z","steps":["trace[784375428] 'process raft request' (duration: 567.456886ms)","trace[784375428] 'compare' (duration: 264.240567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:11.314161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:10.481622Z","time spent":"832.467589ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:11.314204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.447254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-01T11:50:11.314304Z","caller":"traceutil/trace.go:171","msg":"trace[1017762103] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24764; }","duration":"455.683579ms","start":"2026-07-01T11:50:10.858591Z","end":"2026-07-01T11:50:11.314275Z","steps":["trace[1017762103] 'agreement among raft nodes before linearized reading' (duration: 455.399434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.314365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:10.858572Z","time spent":"455.779851ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-01T11:50:11.371341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.271216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:50:11.371364Z","caller":"traceutil/trace.go:171","msg":"trace[2008027509] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"458.329812ms","start":"2026-07-01T11:50:10.912999Z","end":"2026-07-01T11:50:11.371329Z","steps":["trace[2008027509] 'process raft request' (duration: 458.018026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.371423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.931155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-01T11:50:11.371451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.792644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:11.371457Z","caller":"traceutil/trace.go:171","msg":"trace[573995166] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24765; }","duration":"364.46661ms","start":"2026-07-01T11:50:11.006957Z","end":"2026-07-01T11:50:11.371424Z","steps":["trace[573995166] 'agreement among raft nodes before linearized reading' (duration: 364.248914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.3715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.351203ms","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-01T11:50:11.371512Z","caller":"traceutil/trace.go:171","msg":"trace[1418880564] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24765; }","duration":"278.904245ms","start":"2026-07-01T11:50:11.092591Z","end":"2026-07-01T11:50:11.371495Z","steps":["trace[1418880564] 'agreement among raft nodes before linearized reading' (duration: 278.772302ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:11.371532Z","caller":"traceutil/trace.go:171","msg":"trace[1796834150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24765; }","duration":"457.421755ms","start":"2026-07-01T11:50:10.914102Z","end":"2026-07-01T11:50:11.371524Z","steps":["trace[1796834150] 'agreement among raft nodes before linearized reading' (duration: 457.285161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.371533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:11.006938Z","time spent":"364.574643ms","remote":"127.0.0.1:34440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:50:11.371538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:10.912983Z","time spent":"458.461705ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:11.371557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.340605ms","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":"warn","ts":"2026-07-01T11:50:11.371551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:10.914082Z","time spent":"457.464725ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T11:50:11.371576Z","caller":"traceutil/trace.go:171","msg":"trace[1020286368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24765; }","duration":"328.036268ms","start":"2026-07-01T11:50:11.04344Z","end":"2026-07-01T11:50:11.371476Z","steps":["trace[1020286368] 'agreement among raft nodes before linearized reading' (duration: 327.763181ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:11.371692Z","caller":"traceutil/trace.go:171","msg":"trace[1335411111] 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:24765; }","duration":"460.499017ms","start":"2026-07-01T11:50:10.911135Z","end":"2026-07-01T11:50:11.371634Z","steps":["trace[1335411111] 'agreement among raft nodes before linearized reading' (duration: 460.294013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.371756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:10.911115Z","time spent":"460.6281ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-01T11:50:11.372859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:11.043423Z","time spent":"329.416535ms","remote":"127.0.0.1:48428","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":"info","ts":"2026-07-01T11:50:11.459702Z","caller":"traceutil/trace.go:171","msg":"trace[1127386047] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"141.964554ms","start":"2026-07-01T11:50:11.317713Z","end":"2026-07-01T11:50:11.459678Z","steps":["trace[1127386047] 'process raft request' (duration: 116.532446ms)","trace[1127386047] 'compare' (duration: 25.218634ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:11.459737Z","caller":"traceutil/trace.go:171","msg":"trace[1655216414] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"140.32976ms","start":"2026-07-01T11:50:11.319382Z","end":"2026-07-01T11:50:11.459712Z","steps":["trace[1655216414] 'process raft request' (duration: 140.203227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.459972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.49487ms","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-01T11:50:11.460498Z","caller":"traceutil/trace.go:171","msg":"trace[815906965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24769; }","duration":"104.076361ms","start":"2026-07-01T11:50:11.356407Z","end":"2026-07-01T11:50:11.460484Z","steps":["trace[815906965] 'agreement among raft nodes before linearized reading' (duration: 103.49127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.460051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.680598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-01T11:50:11.460681Z","caller":"traceutil/trace.go:171","msg":"trace[886205856] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24769; }","duration":"117.342972ms","start":"2026-07-01T11:50:11.343324Z","end":"2026-07-01T11:50:11.460667Z","steps":["trace[886205856] 'agreement among raft nodes before linearized reading' (duration: 116.597717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:11.460079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.81507ms","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-01T11:50:11.460908Z","caller":"traceutil/trace.go:171","msg":"trace[1433135924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24769; }","duration":"142.672647ms","start":"2026-07-01T11:50:11.31821Z","end":"2026-07-01T11:50:11.460883Z","steps":["trace[1433135924] 'agreement among raft nodes before linearized reading' (duration: 141.574155ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:13.196195Z","caller":"traceutil/trace.go:171","msg":"trace[857418257] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"115.449203ms","start":"2026-07-01T11:50:13.080726Z","end":"2026-07-01T11:50:13.196175Z","steps":["trace[857418257] 'process raft request' (duration: 115.31335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:18.708782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.279571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:18.708876Z","caller":"traceutil/trace.go:171","msg":"trace[1050186106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24894; }","duration":"145.428404ms","start":"2026-07-01T11:50:18.563428Z","end":"2026-07-01T11:50:18.708856Z","steps":["trace[1050186106] 'range keys from in-memory index tree' (duration: 145.112658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:18.708884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.179352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e6b0953c-d6fd-42a3-ab90-370ccca87969\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T11:50:18.708976Z","caller":"traceutil/trace.go:171","msg":"trace[496231100] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e6b0953c-d6fd-42a3-ab90-370ccca87969; range_end:; response_count:1; response_revision:24894; }","duration":"179.340085ms","start":"2026-07-01T11:50:18.529614Z","end":"2026-07-01T11:50:18.708954Z","steps":["trace[496231100] 'range keys from in-memory index tree' (duration: 179.008479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:18.708992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.143743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:18.709079Z","caller":"traceutil/trace.go:171","msg":"trace[783468001] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24894; }","duration":"210.293496ms","start":"2026-07-01T11:50:18.498765Z","end":"2026-07-01T11:50:18.709059Z","steps":["trace[783468001] 'count revisions from in-memory index tree' (duration: 210.045131ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:18.993176Z","caller":"traceutil/trace.go:171","msg":"trace[1545620546] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"131.492041ms","start":"2026-07-01T11:50:18.861653Z","end":"2026-07-01T11:50:18.993145Z","steps":["trace[1545620546] 'process raft request' (duration: 131.298947ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:19.293579Z","caller":"traceutil/trace.go:171","msg":"trace[1259523707] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"146.519707ms","start":"2026-07-01T11:50:19.147018Z","end":"2026-07-01T11:50:19.293538Z","steps":["trace[1259523707] 'process raft request' (duration: 146.274732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.752639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.715857ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-01T11:50:19.753124Z","caller":"traceutil/trace.go:171","msg":"trace[777522590] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"412.308695ms","start":"2026-07-01T11:50:19.340796Z","end":"2026-07-01T11:50:19.753104Z","steps":["trace[777522590] 'process raft request' (duration: 411.952027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.75332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:19.340774Z","time spent":"412.398547ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:19.755191Z","caller":"traceutil/trace.go:171","msg":"trace[181755827] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"355.812862ms","start":"2026-07-01T11:50:19.399347Z","end":"2026-07-01T11:50:19.75516Z","steps":["trace[181755827] 'process raft request' (duration: 353.747851ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:19.755228Z","caller":"traceutil/trace.go:171","msg":"trace[1009910296] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"106.783456ms","start":"2026-07-01T11:50:19.648421Z","end":"2026-07-01T11:50:19.755205Z","steps":["trace[1009910296] 'process raft request' (duration: 106.733915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.755349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:19.399314Z","time spent":"355.944955ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:19.755422Z","caller":"traceutil/trace.go:171","msg":"trace[1545390803] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"272.065376ms","start":"2026-07-01T11:50:19.483325Z","end":"2026-07-01T11:50:19.75539Z","steps":["trace[1545390803] 'process raft request' (duration: 271.77274ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:19.755467Z","caller":"traceutil/trace.go:171","msg":"trace[952500] linearizableReadLoop","detail":"{readStateIndex:25715; appliedIndex:25711; }","duration":"257.633351ms","start":"2026-07-01T11:50:19.497825Z","end":"2026-07-01T11:50:19.755458Z","steps":["trace[952500] 'read index received' (duration: 22.403476ms)","trace[952500] 'applied index is now lower than readState.Index' (duration: 235.228115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:19.755632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.807155ms","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-01T11:50:19.755654Z","caller":"traceutil/trace.go:171","msg":"trace[1471789424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24900; }","duration":"257.878806ms","start":"2026-07-01T11:50:19.497769Z","end":"2026-07-01T11:50:19.755648Z","steps":["trace[1471789424] 'agreement among raft nodes before linearized reading' (duration: 257.768004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.755702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.775525ms","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-01T11:50:19.755736Z","caller":"traceutil/trace.go:171","msg":"trace[1029977579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24900; }","duration":"257.860476ms","start":"2026-07-01T11:50:19.497865Z","end":"2026-07-01T11:50:19.755726Z","steps":["trace[1029977579] 'agreement among raft nodes before linearized reading' (duration: 257.722653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.755899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.531847ms","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-01T11:50:19.755914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.431512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-01T11:50:19.75593Z","caller":"traceutil/trace.go:171","msg":"trace[1683454640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24900; }","duration":"249.580209ms","start":"2026-07-01T11:50:19.506339Z","end":"2026-07-01T11:50:19.755919Z","steps":["trace[1683454640] 'agreement among raft nodes before linearized reading' (duration: 249.460415ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:19.755961Z","caller":"traceutil/trace.go:171","msg":"trace[246273582] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24900; }","duration":"217.522344ms","start":"2026-07-01T11:50:19.538423Z","end":"2026-07-01T11:50:19.755945Z","steps":["trace[246273582] 'agreement among raft nodes before linearized reading' (duration: 217.3479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.756112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.908937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-07-01T11:50:19.756142Z","caller":"traceutil/trace.go:171","msg":"trace[781829763] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24900; }","duration":"205.977709ms","start":"2026-07-01T11:50:19.550157Z","end":"2026-07-01T11:50:19.756134Z","steps":["trace[781829763] 'agreement among raft nodes before linearized reading' (duration: 205.887287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:19.756202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.273555ms","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-01T11:50:19.756231Z","caller":"traceutil/trace.go:171","msg":"trace[252763959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24900; }","duration":"258.359217ms","start":"2026-07-01T11:50:19.497863Z","end":"2026-07-01T11:50:19.756223Z","steps":["trace[252763959] 'agreement among raft nodes before linearized reading' (duration: 258.252245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:24.996913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.780197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:24.997031Z","caller":"traceutil/trace.go:171","msg":"trace[549217700] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24961; }","duration":"161.960691ms","start":"2026-07-01T11:50:24.835039Z","end":"2026-07-01T11:50:24.996999Z","steps":["trace[549217700] 'count revisions from in-memory index tree' (duration: 161.681315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:25.413186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.991428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:25.413253Z","caller":"traceutil/trace.go:171","msg":"trace[699301768] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24962; }","duration":"125.10175ms","start":"2026-07-01T11:50:25.288136Z","end":"2026-07-01T11:50:25.413238Z","steps":["trace[699301768] 'count revisions from in-memory index tree' (duration: 124.868536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:25.413361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.684866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"warn","ts":"2026-07-01T11:50:25.413373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.425624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-01T11:50:25.413436Z","caller":"traceutil/trace.go:171","msg":"trace[555515796] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24962; }","duration":"188.836508ms","start":"2026-07-01T11:50:25.224581Z","end":"2026-07-01T11:50:25.413418Z","steps":["trace[555515796] 'range keys from in-memory index tree' (duration: 188.501312ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:25.413444Z","caller":"traceutil/trace.go:171","msg":"trace[2045144268] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24962; }","duration":"146.612118ms","start":"2026-07-01T11:50:25.266815Z","end":"2026-07-01T11:50:25.413428Z","steps":["trace[2045144268] 'range keys from in-memory index tree' (duration: 146.245361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:28.621403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.912668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:28.621619Z","caller":"traceutil/trace.go:171","msg":"trace[1992378958] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25815; }","duration":"264.050042ms","start":"2026-07-01T11:50:28.357547Z","end":"2026-07-01T11:50:28.621597Z","steps":["trace[1992378958] 'read index received' (duration: 107.86227ms)","trace[1992378958] 'applied index is now lower than readState.Index' (duration: 156.184682ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:28.621641Z","caller":"traceutil/trace.go:171","msg":"trace[1038922840] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"411.788274ms","start":"2026-07-01T11:50:28.209826Z","end":"2026-07-01T11:50:28.621614Z","steps":["trace[1038922840] 'process raft request' (duration: 255.57755ms)","trace[1038922840] 'compare' (duration: 155.791615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:28.621777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:28.209801Z","time spent":"411.901066ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:28.621842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.295087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-01T11:50:28.621887Z","caller":"traceutil/trace.go:171","msg":"trace[1572579195] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24999; }","duration":"264.371759ms","start":"2026-07-01T11:50:28.357499Z","end":"2026-07-01T11:50:28.621871Z","steps":["trace[1572579195] 'agreement among raft nodes before linearized reading' (duration: 264.216816ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.092732Z","caller":"traceutil/trace.go:171","msg":"trace[1563505178] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"299.73663ms","start":"2026-07-01T11:50:29.792969Z","end":"2026-07-01T11:50:30.092706Z","steps":["trace[1563505178] 'process raft request' (duration: 299.655888ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.092789Z","caller":"traceutil/trace.go:171","msg":"trace[642414028] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"300.499675ms","start":"2026-07-01T11:50:29.792257Z","end":"2026-07-01T11:50:30.092757Z","steps":["trace[642414028] 'process raft request' (duration: 300.198359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.092927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:29.792218Z","time spent":"300.636298ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:30.092993Z","caller":"traceutil/trace.go:171","msg":"trace[1351413808] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"299.679918ms","start":"2026-07-01T11:50:29.793295Z","end":"2026-07-01T11:50:30.092975Z","steps":["trace[1351413808] 'process raft request' (duration: 299.370471ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.093134Z","caller":"traceutil/trace.go:171","msg":"trace[630281653] linearizableReadLoop","detail":"{readStateIndex:25823; appliedIndex:25819; }","duration":"297.869342ms","start":"2026-07-01T11:50:29.795243Z","end":"2026-07-01T11:50:30.093112Z","steps":["trace[630281653] 'read index received' (duration: 11.706208ms)","trace[630281653] 'applied index is now lower than readState.Index' (duration: 286.161954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:30.093291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.045006ms","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-01T11:50:30.093347Z","caller":"traceutil/trace.go:171","msg":"trace[1786721310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25005; }","duration":"298.139838ms","start":"2026-07-01T11:50:29.795195Z","end":"2026-07-01T11:50:30.093335Z","steps":["trace[1786721310] 'agreement among raft nodes before linearized reading' (duration: 297.996965ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.094982Z","caller":"traceutil/trace.go:171","msg":"trace[1026093216] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"155.837976ms","start":"2026-07-01T11:50:29.939129Z","end":"2026-07-01T11:50:30.094967Z","steps":["trace[1026093216] 'process raft request' (duration: 155.671293ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.095232Z","caller":"traceutil/trace.go:171","msg":"trace[932609383] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"147.335622ms","start":"2026-07-01T11:50:29.947884Z","end":"2026-07-01T11:50:30.095219Z","steps":["trace[932609383] 'process raft request' (duration: 147.037716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.095264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.779132ms","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-01T11:50:30.095312Z","caller":"traceutil/trace.go:171","msg":"trace[1569222940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25007; }","duration":"278.860854ms","start":"2026-07-01T11:50:29.816443Z","end":"2026-07-01T11:50:30.095304Z","steps":["trace[1569222940] 'agreement among raft nodes before linearized reading' (duration: 278.754732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.095324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.060548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-01T11:50:30.095381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.396821ms","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-01T11:50:30.095381Z","caller":"traceutil/trace.go:171","msg":"trace[136902284] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25007; }","duration":"278.124499ms","start":"2026-07-01T11:50:29.817239Z","end":"2026-07-01T11:50:30.095363Z","steps":["trace[136902284] 'agreement among raft nodes before linearized reading' (duration: 277.948666ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.09542Z","caller":"traceutil/trace.go:171","msg":"trace[349393784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25007; }","duration":"244.482573ms","start":"2026-07-01T11:50:29.850928Z","end":"2026-07-01T11:50:30.09541Z","steps":["trace[349393784] 'agreement among raft nodes before linearized reading' (duration: 244.31805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.095562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.017059ms","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-01T11:50:30.095661Z","caller":"traceutil/trace.go:171","msg":"trace[29292531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25007; }","duration":"285.148722ms","start":"2026-07-01T11:50:29.810501Z","end":"2026-07-01T11:50:30.09565Z","steps":["trace[29292531] 'agreement among raft nodes before linearized reading' (duration: 284.636302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.095676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.974344ms","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-01T11:50:30.095706Z","caller":"traceutil/trace.go:171","msg":"trace[365433397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25007; }","duration":"272.033795ms","start":"2026-07-01T11:50:29.823665Z","end":"2026-07-01T11:50:30.095699Z","steps":["trace[365433397] 'agreement among raft nodes before linearized reading' (duration: 271.501084ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:37.353222Z","caller":"traceutil/trace.go:171","msg":"trace[123435853] linearizableReadLoop","detail":"{readStateIndex:25883; appliedIndex:25882; }","duration":"179.015679ms","start":"2026-07-01T11:50:37.174168Z","end":"2026-07-01T11:50:37.353183Z","steps":["trace[123435853] 'read index received' (duration: 144.048187ms)","trace[123435853] 'applied index is now lower than readState.Index' (duration: 34.965882ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:37.353613Z","caller":"traceutil/trace.go:171","msg":"trace[137297071] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"198.055797ms","start":"2026-07-01T11:50:37.155539Z","end":"2026-07-01T11:50:37.353594Z","steps":["trace[137297071] 'process raft request' (duration: 162.631725ms)","trace[137297071] 'compare' (duration: 34.292489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:37.354093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.845946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:37.354164Z","caller":"traceutil/trace.go:171","msg":"trace[2132158430] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25064; }","duration":"179.977479ms","start":"2026-07-01T11:50:37.174158Z","end":"2026-07-01T11:50:37.354135Z","steps":["trace[2132158430] 'agreement among raft nodes before linearized reading' (duration: 179.813185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:50.228744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.116572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:50.228857Z","caller":"traceutil/trace.go:171","msg":"trace[905590866] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25164; }","duration":"261.305716ms","start":"2026-07-01T11:50:49.967531Z","end":"2026-07-01T11:50:50.228837Z","steps":["trace[905590866] 'count revisions from in-memory index tree' (duration: 260.962569ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.229688Z","caller":"traceutil/trace.go:171","msg":"trace[953727505] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"109.38158ms","start":"2026-07-01T11:50:50.120279Z","end":"2026-07-01T11:50:50.22966Z","steps":["trace[953727505] 'process raft request' (duration: 109.196426ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.517768Z","caller":"traceutil/trace.go:171","msg":"trace[920231611] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"267.82671ms","start":"2026-07-01T11:50:50.249918Z","end":"2026-07-01T11:50:50.517745Z","steps":["trace[920231611] 'process raft request' (duration: 267.787549ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.51788Z","caller":"traceutil/trace.go:171","msg":"trace[2094457598] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"341.287417ms","start":"2026-07-01T11:50:50.176573Z","end":"2026-07-01T11:50:50.51786Z","steps":["trace[2094457598] 'process raft request' (duration: 340.98609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:50.517992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:50.176537Z","time spent":"341.371228ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:50.518021Z","caller":"traceutil/trace.go:171","msg":"trace[2005409788] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"337.543701ms","start":"2026-07-01T11:50:50.180468Z","end":"2026-07-01T11:50:50.518011Z","steps":["trace[2005409788] 'process raft request' (duration: 337.154442ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.51805Z","caller":"traceutil/trace.go:171","msg":"trace[666438656] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"342.111234ms","start":"2026-07-01T11:50:50.175918Z","end":"2026-07-01T11:50:50.518029Z","steps":["trace[666438656] 'process raft request' (duration: 276.176879ms)","trace[666438656] 'compare' (duration: 65.007925ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:50.518037Z","caller":"traceutil/trace.go:171","msg":"trace[90729993] linearizableReadLoop","detail":"{readStateIndex:25991; appliedIndex:25986; }","duration":"315.44275ms","start":"2026-07-01T11:50:50.20257Z","end":"2026-07-01T11:50:50.518012Z","steps":["trace[90729993] 'read index received' (duration: 26.917819ms)","trace[90729993] 'applied index is now lower than readState.Index' (duration: 288.522071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:50.518114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:50.175888Z","time spent":"342.194816ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:50.518089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:50.180449Z","time spent":"337.596392ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:50.518121Z","caller":"traceutil/trace.go:171","msg":"trace[2122559039] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"333.992049ms","start":"2026-07-01T11:50:50.184088Z","end":"2026-07-01T11:50:50.51808Z","steps":["trace[2122559039] 'process raft request' (duration: 333.570899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:50.518267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.468377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-01T11:50:50.518278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.436165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-07-01T11:50:50.518278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:50.184078Z","time spent":"334.111141ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:50.518304Z","caller":"traceutil/trace.go:171","msg":"trace[1362602370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25170; }","duration":"278.538968ms","start":"2026-07-01T11:50:50.239756Z","end":"2026-07-01T11:50:50.518295Z","steps":["trace[1362602370] 'agreement among raft nodes before linearized reading' (duration: 278.430766ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.518311Z","caller":"traceutil/trace.go:171","msg":"trace[385273374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25170; }","duration":"302.520886ms","start":"2026-07-01T11:50:50.215779Z","end":"2026-07-01T11:50:50.518299Z","steps":["trace[385273374] 'agreement among raft nodes before linearized reading' (duration: 302.389233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:50.518322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.441573ms","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-01T11:50:50.518345Z","caller":"traceutil/trace.go:171","msg":"trace[288188639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25170; }","duration":"299.487905ms","start":"2026-07-01T11:50:50.21885Z","end":"2026-07-01T11:50:50.518338Z","steps":["trace[288188639] 'agreement among raft nodes before linearized reading' (duration: 299.417793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:50.518342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:50.215756Z","time spent":"302.579588ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T11:50:50.518339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.781497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-01T11:50:50.518339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.500817ms","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-01T11:50:50.518411Z","caller":"traceutil/trace.go:171","msg":"trace[2064384639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25170; }","duration":"315.894959ms","start":"2026-07-01T11:50:50.202498Z","end":"2026-07-01T11:50:50.518393Z","steps":["trace[2064384639] 'agreement among raft nodes before linearized reading' (duration: 315.655844ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.518424Z","caller":"traceutil/trace.go:171","msg":"trace[267436635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25170; }","duration":"278.63299ms","start":"2026-07-01T11:50:50.239771Z","end":"2026-07-01T11:50:50.518404Z","steps":["trace[267436635] 'agreement among raft nodes before linearized reading' (duration: 278.408506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:50.518411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.587769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-01T11:50:50.518466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:50.202485Z","time spent":"315.970801ms","remote":"127.0.0.1:48428","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":"info","ts":"2026-07-01T11:50:50.51853Z","caller":"traceutil/trace.go:171","msg":"trace[847193024] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25170; }","duration":"275.719801ms","start":"2026-07-01T11:50:50.242773Z","end":"2026-07-01T11:50:50.518493Z","steps":["trace[847193024] 'agreement among raft nodes before linearized reading' (duration: 275.409945ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.754649Z","caller":"traceutil/trace.go:171","msg":"trace[1847693780] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"231.627412ms","start":"2026-07-01T11:50:50.523003Z","end":"2026-07-01T11:50:50.754631Z","steps":["trace[1847693780] 'process raft request' (duration: 140.335551ms)","trace[1847693780] 'compare' (duration: 91.148838ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:50.75495Z","caller":"traceutil/trace.go:171","msg":"trace[1739416285] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"232.778705ms","start":"2026-07-01T11:50:50.522148Z","end":"2026-07-01T11:50:50.754927Z","steps":["trace[1739416285] 'process raft request' (duration: 232.450028ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.755165Z","caller":"traceutil/trace.go:171","msg":"trace[1873898905] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"232.03407ms","start":"2026-07-01T11:50:50.523123Z","end":"2026-07-01T11:50:50.755157Z","steps":["trace[1873898905] 'process raft request' (duration: 231.666092ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.755368Z","caller":"traceutil/trace.go:171","msg":"trace[380161591] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"232.090291ms","start":"2026-07-01T11:50:50.52324Z","end":"2026-07-01T11:50:50.75533Z","steps":["trace[380161591] 'process raft request' (duration: 231.879467ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.755666Z","caller":"traceutil/trace.go:171","msg":"trace[31711073] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"232.238323ms","start":"2026-07-01T11:50:50.523393Z","end":"2026-07-01T11:50:50.755631Z","steps":["trace[31711073] 'process raft request' (duration: 231.846175ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.755736Z","caller":"traceutil/trace.go:171","msg":"trace[1670100385] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"229.751493ms","start":"2026-07-01T11:50:50.525921Z","end":"2026-07-01T11:50:50.755673Z","steps":["trace[1670100385] 'process raft request' (duration: 229.515098ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:50.755512Z","caller":"traceutil/trace.go:171","msg":"trace[690702359] linearizableReadLoop","detail":"{readStateIndex:25998; appliedIndex:25992; }","duration":"193.451413ms","start":"2026-07-01T11:50:50.562041Z","end":"2026-07-01T11:50:50.755492Z","steps":["trace[690702359] 'read index received' (duration: 101.273604ms)","trace[690702359] 'applied index is now lower than readState.Index' (duration: 92.176729ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:50.755634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.574526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:50.755846Z","caller":"traceutil/trace.go:171","msg":"trace[2022392130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25176; }","duration":"193.846812ms","start":"2026-07-01T11:50:50.561985Z","end":"2026-07-01T11:50:50.755832Z","steps":["trace[2022392130] 'agreement among raft nodes before linearized reading' (duration: 193.569315ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:51.048231Z","caller":"traceutil/trace.go:171","msg":"trace[2113589486] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"192.80735ms","start":"2026-07-01T11:50:50.855403Z","end":"2026-07-01T11:50:51.04821Z","steps":["trace[2113589486] 'process raft request' (duration: 192.663408ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.160128Z","caller":"traceutil/trace.go:171","msg":"trace[2037784723] linearizableReadLoop","detail":"{readStateIndex:26002; appliedIndex:26001; }","duration":"126.337715ms","start":"2026-07-01T11:50:52.033767Z","end":"2026-07-01T11:50:52.160105Z","steps":["trace[2037784723] 'read index received' (duration: 27.117832ms)","trace[2037784723] 'applied index is now lower than readState.Index' (duration: 99.218853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:52.160263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.477558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:52.160293Z","caller":"traceutil/trace.go:171","msg":"trace[1918973206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25180; }","duration":"126.56956ms","start":"2026-07-01T11:50:52.033714Z","end":"2026-07-01T11:50:52.160284Z","steps":["trace[1918973206] 'agreement among raft nodes before linearized reading' (duration: 126.467448ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.160455Z","caller":"traceutil/trace.go:171","msg":"trace[1053962932] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"206.483779ms","start":"2026-07-01T11:50:51.953961Z","end":"2026-07-01T11:50:52.160445Z","steps":["trace[1053962932] 'process raft request' (duration: 106.98013ms)","trace[1053962932] 'compare' (duration: 98.905797ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:52.772197Z","caller":"traceutil/trace.go:171","msg":"trace[475233005] linearizableReadLoop","detail":"{readStateIndex:26007; appliedIndex:26002; }","duration":"209.894859ms","start":"2026-07-01T11:50:52.56229Z","end":"2026-07-01T11:50:52.772185Z","steps":["trace[475233005] 'read index received' (duration: 37.296971ms)","trace[475233005] 'applied index is now lower than readState.Index' (duration: 172.597248ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:52.772232Z","caller":"traceutil/trace.go:171","msg":"trace[1260884491] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"248.526245ms","start":"2026-07-01T11:50:52.523678Z","end":"2026-07-01T11:50:52.772205Z","steps":["trace[1260884491] 'process raft request' (duration: 248.310941ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.772281Z","caller":"traceutil/trace.go:171","msg":"trace[97849876] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"247.435913ms","start":"2026-07-01T11:50:52.524838Z","end":"2026-07-01T11:50:52.772274Z","steps":["trace[97849876] 'process raft request' (duration: 247.326191ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.772248Z","caller":"traceutil/trace.go:171","msg":"trace[1194827576] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"247.418843ms","start":"2026-07-01T11:50:52.5248Z","end":"2026-07-01T11:50:52.772219Z","steps":["trace[1194827576] 'process raft request' (duration: 247.343442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:52.772409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.108733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:52.772431Z","caller":"traceutil/trace.go:171","msg":"trace[216432462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25185; }","duration":"210.176864ms","start":"2026-07-01T11:50:52.562249Z","end":"2026-07-01T11:50:52.772425Z","steps":["trace[216432462] 'agreement among raft nodes before linearized reading' (duration: 210.114443ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.772442Z","caller":"traceutil/trace.go:171","msg":"trace[6007722] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"247.900932ms","start":"2026-07-01T11:50:52.52453Z","end":"2026-07-01T11:50:52.772431Z","steps":["trace[6007722] 'process raft request' (duration: 247.592806ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.919032Z","caller":"traceutil/trace.go:171","msg":"trace[1055439174] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"140.975694ms","start":"2026-07-01T11:50:52.778033Z","end":"2026-07-01T11:50:52.919008Z","steps":["trace[1055439174] 'process raft request' (duration: 140.231568ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.919246Z","caller":"traceutil/trace.go:171","msg":"trace[476719847] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"140.937423ms","start":"2026-07-01T11:50:52.778292Z","end":"2026-07-01T11:50:52.919229Z","steps":["trace[476719847] 'process raft request' (duration: 140.109576ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.919489Z","caller":"traceutil/trace.go:171","msg":"trace[133541275] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"141.137697ms","start":"2026-07-01T11:50:52.778339Z","end":"2026-07-01T11:50:52.919477Z","steps":["trace[133541275] 'process raft request' (duration: 140.100236ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.919624Z","caller":"traceutil/trace.go:171","msg":"trace[17896809] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"141.24246ms","start":"2026-07-01T11:50:52.778372Z","end":"2026-07-01T11:50:52.919614Z","steps":["trace[17896809] 'process raft request' (duration: 140.096777ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:52.919809Z","caller":"traceutil/trace.go:171","msg":"trace[634343926] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"141.387122ms","start":"2026-07-01T11:50:52.778405Z","end":"2026-07-01T11:50:52.919792Z","steps":["trace[634343926] 'process raft request' (duration: 140.094366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:51:14.482212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.746868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-01T11:51:14.482318Z","caller":"traceutil/trace.go:171","msg":"trace[1224866676] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25352; }","duration":"137.88895ms","start":"2026-07-01T11:51:14.344394Z","end":"2026-07-01T11:51:14.482283Z","steps":["trace[1224866676] 'range keys from in-memory index tree' (duration: 137.556534ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:14.837577Z","caller":"traceutil/trace.go:171","msg":"trace[1787634991] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"158.846168ms","start":"2026-07-01T11:51:14.678702Z","end":"2026-07-01T11:51:14.837548Z","steps":["trace[1787634991] 'process raft request' (duration: 158.583183ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:14.86036Z","caller":"traceutil/trace.go:171","msg":"trace[1775240258] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"173.635489ms","start":"2026-07-01T11:51:14.686696Z","end":"2026-07-01T11:51:14.860332Z","steps":["trace[1775240258] 'process raft request' (duration: 173.505437ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.027975Z","caller":"traceutil/trace.go:171","msg":"trace[2053916884] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"138.157376ms","start":"2026-07-01T11:51:14.889782Z","end":"2026-07-01T11:51:15.027939Z","steps":["trace[2053916884] 'process raft request' (duration: 71.582769ms)","trace[2053916884] 'compare' (duration: 66.035676ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:51:15.035686Z","caller":"traceutil/trace.go:171","msg":"trace[1661560352] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"145.431275ms","start":"2026-07-01T11:51:14.890238Z","end":"2026-07-01T11:51:15.035669Z","steps":["trace[1661560352] 'process raft request' (duration: 145.096598ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.035732Z","caller":"traceutil/trace.go:171","msg":"trace[336855122] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"145.376553ms","start":"2026-07-01T11:51:14.890328Z","end":"2026-07-01T11:51:15.035704Z","steps":["trace[336855122] 'process raft request' (duration: 145.242701ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.035767Z","caller":"traceutil/trace.go:171","msg":"trace[1399229656] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"145.334983ms","start":"2026-07-01T11:51:14.890384Z","end":"2026-07-01T11:51:15.035719Z","steps":["trace[1399229656] 'process raft request' (duration: 145.256761ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.035757Z","caller":"traceutil/trace.go:171","msg":"trace[73907399] linearizableReadLoop","detail":"{readStateIndex:26185; appliedIndex:26181; }","duration":"102.937429ms","start":"2026-07-01T11:51:14.932752Z","end":"2026-07-01T11:51:15.03569Z","steps":["trace[73907399] 'read index received' (duration: 28.630294ms)","trace[73907399] 'applied index is now lower than readState.Index' (duration: 74.306095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:51:15.035895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.138583ms","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-01T11:51:15.035949Z","caller":"traceutil/trace.go:171","msg":"trace[70183523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25358; }","duration":"103.250645ms","start":"2026-07-01T11:51:14.932687Z","end":"2026-07-01T11:51:15.035937Z","steps":["trace[70183523] 'agreement among raft nodes before linearized reading' (duration: 103.054381ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.264757Z","caller":"traceutil/trace.go:171","msg":"trace[2042586635] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"225.358343ms","start":"2026-07-01T11:51:15.039365Z","end":"2026-07-01T11:51:15.264724Z","steps":["trace[2042586635] 'process raft request' (duration: 225.147539ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.289219Z","caller":"traceutil/trace.go:171","msg":"trace[1053845449] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"247.366582ms","start":"2026-07-01T11:51:15.041823Z","end":"2026-07-01T11:51:15.289189Z","steps":["trace[1053845449] 'process raft request' (duration: 247.29026ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.289367Z","caller":"traceutil/trace.go:171","msg":"trace[543378156] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"248.73169ms","start":"2026-07-01T11:51:15.040575Z","end":"2026-07-01T11:51:15.289307Z","steps":["trace[543378156] 'process raft request' (duration: 248.358113ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.289479Z","caller":"traceutil/trace.go:171","msg":"trace[1054929823] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"247.166568ms","start":"2026-07-01T11:51:15.042301Z","end":"2026-07-01T11:51:15.289468Z","steps":["trace[1054929823] 'process raft request' (duration: 246.851242ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.289393Z","caller":"traceutil/trace.go:171","msg":"trace[1322453284] linearizableReadLoop","detail":"{readStateIndex:26188; appliedIndex:26185; }","duration":"248.427073ms","start":"2026-07-01T11:51:15.040945Z","end":"2026-07-01T11:51:15.289372Z","steps":["trace[1322453284] 'read index received' (duration: 223.631498ms)","trace[1322453284] 'applied index is now lower than readState.Index' (duration: 24.794275ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:51:15.289525Z","caller":"traceutil/trace.go:171","msg":"trace[548348727] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"248.560377ms","start":"2026-07-01T11:51:15.040823Z","end":"2026-07-01T11:51:15.289384Z","steps":["trace[548348727] 'process raft request' (duration: 248.24712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:51:15.289831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.870922ms","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-01T11:51:15.289913Z","caller":"traceutil/trace.go:171","msg":"trace[1120307049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25363; }","duration":"248.979174ms","start":"2026-07-01T11:51:15.040913Z","end":"2026-07-01T11:51:15.289892Z","steps":["trace[1120307049] 'agreement among raft nodes before linearized reading' (duration: 248.720969ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.448226Z","caller":"traceutil/trace.go:171","msg":"trace[341419872] linearizableReadLoop","detail":"{readStateIndex:26191; appliedIndex:26190; }","duration":"158.591773ms","start":"2026-07-01T11:51:15.289608Z","end":"2026-07-01T11:51:15.4482Z","steps":["trace[341419872] 'read index received' (duration: 128.241084ms)","trace[341419872] 'applied index is now lower than readState.Index' (duration: 30.349529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:51:15.448379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.259028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:51:15.448421Z","caller":"traceutil/trace.go:171","msg":"trace[1964860482] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25364; }","duration":"295.433491ms","start":"2026-07-01T11:51:15.152976Z","end":"2026-07-01T11:51:15.44841Z","steps":["trace[1964860482] 'agreement among raft nodes before linearized reading' (duration: 295.335819ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:15.448395Z","caller":"traceutil/trace.go:171","msg":"trace[1435497939] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"183.01909ms","start":"2026-07-01T11:51:15.265232Z","end":"2026-07-01T11:51:15.448251Z","steps":["trace[1435497939] 'process raft request' (duration: 152.60029ms)","trace[1435497939] 'compare' (duration: 30.171835ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:51:15.470174Z","caller":"traceutil/trace.go:171","msg":"trace[1122828613] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"175.60418ms","start":"2026-07-01T11:51:15.294538Z","end":"2026-07-01T11:51:15.470142Z","steps":["trace[1122828613] 'process raft request' (duration: 175.327384ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:20.306221Z","caller":"traceutil/trace.go:171","msg":"trace[22436921] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"161.720397ms","start":"2026-07-01T11:51:20.14444Z","end":"2026-07-01T11:51:20.306161Z","steps":["trace[22436921] 'process raft request' (duration: 161.460242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:51:20.79521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.056282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:51:20.795983Z","caller":"traceutil/trace.go:171","msg":"trace[412669271] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25394; }","duration":"132.869339ms","start":"2026-07-01T11:51:20.663067Z","end":"2026-07-01T11:51:20.795936Z","steps":["trace[412669271] 'count revisions from in-memory index tree' (duration: 131.820667ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:21.248301Z","caller":"traceutil/trace.go:171","msg":"trace[1946414945] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"201.537988ms","start":"2026-07-01T11:51:21.046733Z","end":"2026-07-01T11:51:21.248271Z","steps":["trace[1946414945] 'process raft request' (duration: 201.329134ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:21.262051Z","caller":"traceutil/trace.go:171","msg":"trace[1848947119] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"198.198739ms","start":"2026-07-01T11:51:21.063828Z","end":"2026-07-01T11:51:21.262027Z","steps":["trace[1848947119] 'process raft request' (duration: 197.961914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:21.262328Z","caller":"traceutil/trace.go:171","msg":"trace[192343552] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"194.613287ms","start":"2026-07-01T11:51:21.067698Z","end":"2026-07-01T11:51:21.262311Z","steps":["trace[192343552] 'process raft request' (duration: 194.474044ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:21.262352Z","caller":"traceutil/trace.go:171","msg":"trace[514360836] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"194.79744ms","start":"2026-07-01T11:51:21.067528Z","end":"2026-07-01T11:51:21.262325Z","steps":["trace[514360836] 'process raft request' (duration: 194.444133ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:51:37.974468Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006758.wal"} {"level":"info","ts":"2026-07-01T11:52:20.649624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23064} {"level":"info","ts":"2026-07-01T11:52:20.803148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23064,"took":"149.890055ms","hash":1387006298,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":36257792,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-01T11:52:20.803397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1387006298,"revision":23064,"compact-revision":20035} {"level":"info","ts":"2026-07-01T11:57:20.655548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26021} {"level":"info","ts":"2026-07-01T11:57:20.799022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26021,"took":"139.074728ms","hash":3703260076,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":34598912,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-01T11:57:20.799098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703260076,"revision":26021,"compact-revision":23064} {"level":"info","ts":"2026-07-01T11:58:11.372455Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:58:11.386206Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T11:58:11.38634Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T12:02:20.668326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28707} {"level":"info","ts":"2026-07-01T12:02:20.803902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28707,"took":"131.133805ms","hash":2463903988,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T12:02:20.80398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2463903988,"revision":28707,"compact-revision":26021} {"level":"info","ts":"2026-07-01T12:04:49.976683Z","caller":"traceutil/trace.go:171","msg":"trace[627488296] linearizableReadLoop","detail":"{readStateIndex:33155; appliedIndex:33153; }","duration":"425.286369ms","start":"2026-07-01T12:04:49.551363Z","end":"2026-07-01T12:04:49.976649Z","steps":["trace[627488296] 'read index received' (duration: 43.006251ms)","trace[627488296] 'applied index is now lower than readState.Index' (duration: 382.279038ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T12:04:49.976712Z","caller":"traceutil/trace.go:171","msg":"trace[20023991] transaction","detail":"{read_only:false; response_revision:32143; number_of_response:1; }","duration":"429.122957ms","start":"2026-07-01T12:04:49.547532Z","end":"2026-07-01T12:04:49.976655Z","steps":["trace[20023991] 'process raft request' (duration: 380.495122ms)","trace[20023991] 'compare' (duration: 48.478842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T12:04:49.976867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.54751Z","time spent":"429.27577ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T12:04:49.976979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.228228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-01T12:04:49.976989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.593225ms","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-01T12:04:49.977052Z","caller":"traceutil/trace.go:171","msg":"trace[1718870062] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32143; }","duration":"425.354821ms","start":"2026-07-01T12:04:49.551677Z","end":"2026-07-01T12:04:49.977032Z","steps":["trace[1718870062] 'agreement among raft nodes before linearized reading' (duration: 425.145546ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T12:04:49.977085Z","caller":"traceutil/trace.go:171","msg":"trace[1907884177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32143; }","duration":"425.700557ms","start":"2026-07-01T12:04:49.551359Z","end":"2026-07-01T12:04:49.97706Z","steps":["trace[1907884177] 'agreement among raft nodes before linearized reading' (duration: 425.429902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T12:04:49.977099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.551657Z","time spent":"425.427702ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-01T12:04:49.977068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.513767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T12:04:49.977165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.551332Z","time spent":"425.81664ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T12:04:49.977198Z","caller":"traceutil/trace.go:171","msg":"trace[1670961556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32143; }","duration":"413.65841ms","start":"2026-07-01T12:04:49.563496Z","end":"2026-07-01T12:04:49.977154Z","steps":["trace[1670961556] 'agreement among raft nodes before linearized reading' (duration: 413.473926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T12:04:49.977279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.56348Z","time spent":"413.786812ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T12:04:49.978282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.047878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:200 size:2185335"} {"level":"info","ts":"2026-07-01T12:04:49.978355Z","caller":"traceutil/trace.go:171","msg":"trace[942406827] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:200; response_revision:32143; }","duration":"118.13251ms","start":"2026-07-01T12:04:49.860204Z","end":"2026-07-01T12:04:49.978337Z","steps":["trace[942406827] 'agreement among raft nodes before linearized reading' (duration: 116.750881ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T12:07:20.675501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31022} {"level":"info","ts":"2026-07-01T12:07:20.808206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31022,"took":"128.439874ms","hash":1897684886,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T12:07:20.80828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897684886,"revision":31022,"compact-revision":28707} {"level":"info","ts":"2026-07-01T12:12:20.680557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33316} {"level":"info","ts":"2026-07-01T12:12:20.837207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33316,"took":"152.838182ms","hash":95944592,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":32088064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T12:12:20.837266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":95944592,"revision":33316,"compact-revision":31022} {"level":"info","ts":"2026-07-01T12:17:20.689826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35613} {"level":"info","ts":"2026-07-01T12:17:20.823854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35613,"took":"129.812122ms","hash":505871140,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T12:17:20.823927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":505871140,"revision":35613,"compact-revision":33316} {"level":"info","ts":"2026-07-01T12:19:24.395659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T12:19:24.400835Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T12:19:24.400905Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-01T12:22:20.697012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37837} {"level":"info","ts":"2026-07-01T12:22:20.822199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37837,"took":"120.9106ms","hash":292919354,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T12:22:20.822282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292919354,"revision":37837,"compact-revision":35613}