{"level":"warn","ts":"2026-05-18T00:41:30.681328Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:41:30.681437Z","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-05-18T00:41:30.681514Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:41:30.681526Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-18T00:41:30.681547Z","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-05-18T00:41:30.682281Z","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-05-18T00:41:30.68237Z","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-05-18T00:41:30.686656Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.988505ms"} {"level":"info","ts":"2026-05-18T00:41:30.694525Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-05-18T00:41:30.694637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T00:41:30.694667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-05-18T00:41:30.694684Z","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-05-18T00:41:30.694703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-05-18T00:41:30.694755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-05-18T00:41:30.703739Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T00:41:30.705396Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T00:41:30.70641Z","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-05-18T00:41:30.709773Z","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-05-18T00:41:30.710126Z","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-05-18T00:41:30.710157Z","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-05-18T00:41:30.71036Z","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-05-18T00:41:30.710388Z","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-05-18T00:41:30.71419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-05-18T00:41:30.716298Z","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-05-18T00:41:30.718469Z","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-05-18T00:41:30.718686Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-18T00:41:30.718782Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-18T00:41:30.718986Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T00:41:30.718976Z","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-05-18T00:41:31.397989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T00:41:31.398072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T00:41:31.398128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-05-18T00:41:31.398144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-05-18T00:41:31.398151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-18T00:41:31.398167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-05-18T00:41:31.398177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-18T00:41:31.405655Z","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-05-18T00:41:31.405674Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:41:31.405719Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:41:31.40569Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:31.406983Z","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-05-18T00:41:31.407215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:31.40727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:31.40816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-18T00:41:31.409213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-05-18T00:41:31.410035Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T00:41:31.410087Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T00:42:01.304611Z","caller":"traceutil/trace.go:171","msg":"trace[1628041489] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"103.130867ms","start":"2026-05-18T00:42:01.201444Z","end":"2026-05-18T00:42:01.304575Z","steps":["trace[1628041489] 'read index received' (duration: 102.953544ms)","trace[1628041489] 'applied index is now lower than readState.Index' (duration: 175.673µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:01.304729Z","caller":"traceutil/trace.go:171","msg":"trace[475874261] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"104.336351ms","start":"2026-05-18T00:42:01.200351Z","end":"2026-05-18T00:42:01.304687Z","steps":["trace[475874261] 'process raft request' (duration: 103.976754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:01.304839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.356322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-18T00:42:01.304942Z","caller":"traceutil/trace.go:171","msg":"trace[713674974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"103.492875ms","start":"2026-05-18T00:42:01.201429Z","end":"2026-05-18T00:42:01.304922Z","steps":["trace[713674974] 'agreement among raft nodes before linearized reading' (duration: 103.307921ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:08.229903Z","caller":"traceutil/trace.go:171","msg":"trace[115733406] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"156.247577ms","start":"2026-05-18T00:42:08.073592Z","end":"2026-05-18T00:42:08.22984Z","steps":["trace[115733406] 'process raft request' (duration: 155.154045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:08.718056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.361848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:08.718174Z","caller":"traceutil/trace.go:171","msg":"trace[669603392] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"140.060424ms","start":"2026-05-18T00:42:08.578096Z","end":"2026-05-18T00:42:08.718156Z","steps":["trace[669603392] 'read index received' (duration: 116.592µs)","trace[669603392] 'applied index is now lower than readState.Index' (duration: 139.942632ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:08.718265Z","caller":"traceutil/trace.go:171","msg":"trace[1504361260] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"428.756403ms","start":"2026-05-18T00:42:08.289451Z","end":"2026-05-18T00:42:08.718207Z","steps":["trace[1504361260] 'process raft request' (duration: 255.259512ms)","trace[1504361260] 'compare' (duration: 171.755236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:08.718378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.326809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ljdx8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-18T00:42:08.718407Z","caller":"traceutil/trace.go:171","msg":"trace[834931977] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ljdx8; range_end:; response_count:1; response_revision:620; }","duration":"140.478453ms","start":"2026-05-18T00:42:08.577919Z","end":"2026-05-18T00:42:08.718397Z","steps":["trace[834931977] 'agreement among raft nodes before linearized reading' (duration: 140.281909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:08.718461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:08.289411Z","time spent":"428.961127ms","remote":"127.0.0.1:44164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T00:43:35.120041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.364672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:43:35.120145Z","caller":"traceutil/trace.go:171","msg":"trace[69015652] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1838; }","duration":"199.544728ms","start":"2026-05-18T00:43:34.920578Z","end":"2026-05-18T00:43:35.120122Z","steps":["trace[69015652] 'count revisions from in-memory index tree' (duration: 199.296291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:35.120436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.668569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1f714c52-2219-495a-8130-2716a91f2275\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-18T00:43:35.120533Z","caller":"traceutil/trace.go:171","msg":"trace[1616624452] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1f714c52-2219-495a-8130-2716a91f2275; range_end:; response_count:1; response_revision:1838; }","duration":"163.784611ms","start":"2026-05-18T00:43:34.956727Z","end":"2026-05-18T00:43:35.120512Z","steps":["trace[1616624452] 'range keys from in-memory index tree' (duration: 163.442804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:35.38515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.514414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-05-18T00:43:35.38543Z","caller":"traceutil/trace.go:171","msg":"trace[492165286] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1840; }","duration":"162.697098ms","start":"2026-05-18T00:43:35.222564Z","end":"2026-05-18T00:43:35.385261Z","steps":["trace[492165286] 'range keys from in-memory index tree' (duration: 162.28698ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:35.534553Z","caller":"traceutil/trace.go:171","msg":"trace[1384732433] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"138.464739ms","start":"2026-05-18T00:43:35.396062Z","end":"2026-05-18T00:43:35.534527Z","steps":["trace[1384732433] 'process raft request' (duration: 138.220894ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:51.970958Z","caller":"traceutil/trace.go:171","msg":"trace[70817302] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"107.892631ms","start":"2026-05-18T00:43:51.86305Z","end":"2026-05-18T00:43:51.970943Z","steps":["trace[70817302] 'process raft request' (duration: 107.674536ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:51.970934Z","caller":"traceutil/trace.go:171","msg":"trace[1119863927] linearizableReadLoop","detail":"{readStateIndex:2047; appliedIndex:2046; }","duration":"103.610402ms","start":"2026-05-18T00:43:51.867301Z","end":"2026-05-18T00:43:51.970912Z","steps":["trace[1119863927] 'read index received' (duration: 103.307296ms)","trace[1119863927] 'applied index is now lower than readState.Index' (duration: 302.026µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:43:51.971143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.819597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-18T00:43:51.971214Z","caller":"traceutil/trace.go:171","msg":"trace[1904712891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1970; }","duration":"103.947339ms","start":"2026-05-18T00:43:51.86725Z","end":"2026-05-18T00:43:51.971197Z","steps":["trace[1904712891] 'agreement among raft nodes before linearized reading' (duration: 103.756325ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:52.09196Z","caller":"traceutil/trace.go:171","msg":"trace[160863138] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"115.881004ms","start":"2026-05-18T00:43:51.976052Z","end":"2026-05-18T00:43:52.091933Z","steps":["trace[160863138] 'process raft request' (duration: 55.131214ms)","trace[160863138] 'compare' (duration: 60.558196ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:43:52.092271Z","caller":"traceutil/trace.go:171","msg":"trace[1104139794] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"116.187581ms","start":"2026-05-18T00:43:51.976052Z","end":"2026-05-18T00:43:52.09224Z","steps":["trace[1104139794] 'process raft request' (duration: 115.835923ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:52.092389Z","caller":"traceutil/trace.go:171","msg":"trace[1699268186] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"115.497217ms","start":"2026-05-18T00:43:51.976867Z","end":"2026-05-18T00:43:52.092365Z","steps":["trace[1699268186] 'process raft request' (duration: 115.304593ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:52.092562Z","caller":"traceutil/trace.go:171","msg":"trace[1764399207] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"115.397635ms","start":"2026-05-18T00:43:51.977149Z","end":"2026-05-18T00:43:52.092547Z","steps":["trace[1764399207] 'process raft request' (duration: 115.133379ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:52.092724Z","caller":"traceutil/trace.go:171","msg":"trace[1961647613] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"113.459265ms","start":"2026-05-18T00:43:51.979243Z","end":"2026-05-18T00:43:52.092702Z","steps":["trace[1961647613] 'process raft request' (duration: 113.332332ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:43:52.092712Z","caller":"traceutil/trace.go:171","msg":"trace[78315205] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"115.125449ms","start":"2026-05-18T00:43:51.977549Z","end":"2026-05-18T00:43:52.092674Z","steps":["trace[78315205] 'process raft request' (duration: 114.911775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:44:25.726895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.727266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-18T00:44:25.727003Z","caller":"traceutil/trace.go:171","msg":"trace[19486147] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2167; }","duration":"140.892519ms","start":"2026-05-18T00:44:25.586084Z","end":"2026-05-18T00:44:25.726977Z","steps":["trace[19486147] 'range keys from in-memory index tree' (duration: 140.420759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:15.089741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.077151ms","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-05-18T00:45:15.089833Z","caller":"traceutil/trace.go:171","msg":"trace[1282564413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2607; }","duration":"112.234104ms","start":"2026-05-18T00:45:14.977581Z","end":"2026-05-18T00:45:15.089815Z","steps":["trace[1282564413] 'range keys from in-memory index tree' (duration: 111.829447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:39.678661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.988735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:49:39.678778Z","caller":"traceutil/trace.go:171","msg":"trace[1761102552] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4971; }","duration":"168.133288ms","start":"2026-05-18T00:49:39.510623Z","end":"2026-05-18T00:49:39.678756Z","steps":["trace[1761102552] 'count revisions from in-memory index tree' (duration: 167.915263ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:39.678835Z","caller":"traceutil/trace.go:171","msg":"trace[2146877138] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"131.364698ms","start":"2026-05-18T00:49:39.547447Z","end":"2026-05-18T00:49:39.678812Z","steps":["trace[2146877138] 'process raft request' (duration: 87.944585ms)","trace[2146877138] 'compare' (duration: 43.128967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:49:49.461137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.886109ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:49:49.461277Z","caller":"traceutil/trace.go:171","msg":"trace[1448997486] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5170; }","duration":"322.031922ms","start":"2026-05-18T00:49:49.139223Z","end":"2026-05-18T00:49:49.461255Z","steps":["trace[1448997486] 'range keys from in-memory index tree' (duration: 321.868449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:49.46131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.445836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:49:49.461422Z","caller":"traceutil/trace.go:171","msg":"trace[34775769] linearizableReadLoop","detail":"{readStateIndex:5367; appliedIndex:5365; }","duration":"507.837951ms","start":"2026-05-18T00:49:48.953564Z","end":"2026-05-18T00:49:49.461402Z","steps":["trace[34775769] 'read index received' (duration: 9.304906ms)","trace[34775769] 'applied index is now lower than readState.Index' (duration: 498.531635ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:49:49.461479Z","caller":"traceutil/trace.go:171","msg":"trace[723813523] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"523.359248ms","start":"2026-05-18T00:49:48.938106Z","end":"2026-05-18T00:49:49.461466Z","steps":["trace[723813523] 'process raft request' (duration: 103.694488ms)","trace[723813523] 'compare' (duration: 418.858883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:49:49.461572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:49:48.938089Z","time spent":"523.430049ms","remote":"127.0.0.1:43942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T00:49:49.461633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.060555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xmkwz\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-05-18T00:49:49.461724Z","caller":"traceutil/trace.go:171","msg":"trace[199767135] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-xmkwz; range_end:; response_count:1; response_revision:5171; }","duration":"508.166598ms","start":"2026-05-18T00:49:48.953519Z","end":"2026-05-18T00:49:49.461685Z","steps":["trace[199767135] 'agreement among raft nodes before linearized reading' (duration: 507.936663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:49.46178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:49:48.953504Z","time spent":"508.261169ms","remote":"127.0.0.1:43868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7840,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xmkwz\" "} {"level":"warn","ts":"2026-05-18T00:49:57.08556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.631267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:49:57.085814Z","caller":"traceutil/trace.go:171","msg":"trace[511097731] linearizableReadLoop","detail":"{readStateIndex:5545; appliedIndex:5543; }","duration":"316.218657ms","start":"2026-05-18T00:49:56.769573Z","end":"2026-05-18T00:49:57.085792Z","steps":["trace[511097731] 'read index received' (duration: 700.025µs)","trace[511097731] 'applied index is now lower than readState.Index' (duration: 315.517132ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:49:57.085977Z","caller":"traceutil/trace.go:171","msg":"trace[2126385147] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"376.030553ms","start":"2026-05-18T00:49:56.7099Z","end":"2026-05-18T00:49:57.085931Z","steps":["trace[2126385147] 'process raft request' (duration: 207.743243ms)","trace[2126385147] 'compare' (duration: 167.478454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:49:57.086053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:49:56.709887Z","time spent":"376.128495ms","remote":"127.0.0.1:43942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:49:57.086295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.688507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:49:57.086409Z","caller":"traceutil/trace.go:171","msg":"trace[296560750] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5344; }","duration":"316.87645ms","start":"2026-05-18T00:49:56.769511Z","end":"2026-05-18T00:49:57.086387Z","steps":["trace[296560750] 'agreement among raft nodes before linearized reading' (duration: 316.604064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:57.086443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.714393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65c67b46-c7ae-4045-85bf-0dd2b9e5c836\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-18T00:49:57.086515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:49:56.769469Z","time spent":"317.023633ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-18T00:49:57.086293Z","caller":"traceutil/trace.go:171","msg":"trace[686955077] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"373.187688ms","start":"2026-05-18T00:49:56.713036Z","end":"2026-05-18T00:49:57.086224Z","steps":["trace[686955077] 'process raft request' (duration: 372.652277ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:57.086541Z","caller":"traceutil/trace.go:171","msg":"trace[1453416250] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-65c67b46-c7ae-4045-85bf-0dd2b9e5c836; range_end:; response_count:1; response_revision:5344; }","duration":"275.903567ms","start":"2026-05-18T00:49:56.810618Z","end":"2026-05-18T00:49:57.086521Z","steps":["trace[1453416250] 'agreement among raft nodes before linearized reading' (duration: 275.520508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:49:57.086731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:49:56.713005Z","time spent":"373.611967ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:49:57.316283Z","caller":"traceutil/trace.go:171","msg":"trace[380977565] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5545; }","duration":"221.626009ms","start":"2026-05-18T00:49:57.094613Z","end":"2026-05-18T00:49:57.316239Z","steps":["trace[380977565] 'read index received' (duration: 131.53145ms)","trace[380977565] 'applied index is now lower than readState.Index' (duration: 90.092799ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:49:57.3163Z","caller":"traceutil/trace.go:171","msg":"trace[2089455315] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"222.476647ms","start":"2026-05-18T00:49:57.093788Z","end":"2026-05-18T00:49:57.316265Z","steps":["trace[2089455315] 'process raft request' (duration: 132.327187ms)","trace[2089455315] 'compare' (duration: 89.885335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:49:57.316547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.906335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17454"} {"level":"info","ts":"2026-05-18T00:49:57.316628Z","caller":"traceutil/trace.go:171","msg":"trace[2109285110] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5345; }","duration":"222.022027ms","start":"2026-05-18T00:49:57.094589Z","end":"2026-05-18T00:49:57.316611Z","steps":["trace[2109285110] 'agreement among raft nodes before linearized reading' (duration: 221.762932ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:51:31.522986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3049} {"level":"info","ts":"2026-05-18T00:51:31.614734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3049,"took":"89.189267ms","hash":1492594645,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T00:51:31.614823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1492594645,"revision":3049,"compact-revision":-1} {"level":"info","ts":"2026-05-18T00:56:05.02478Z","caller":"traceutil/trace.go:171","msg":"trace[1614396204] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"153.398157ms","start":"2026-05-18T00:56:04.871346Z","end":"2026-05-18T00:56:05.024744Z","steps":["trace[1614396204] 'process raft request' (duration: 151.264285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:16.085633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.897485ms","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-05-18T00:56:16.08573Z","caller":"traceutil/trace.go:171","msg":"trace[224818541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8249; }","duration":"136.038107ms","start":"2026-05-18T00:56:15.94967Z","end":"2026-05-18T00:56:16.085708Z","steps":["trace[224818541] 'agreement among raft nodes before linearized reading' (duration: 135.868324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:16.422882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.881285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-svw8b\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-18T00:56:16.42298Z","caller":"traceutil/trace.go:171","msg":"trace[151723482] range","detail":"{range_begin:/registry/pods/openstack/keepalived-svw8b; range_end:; response_count:1; response_revision:8251; }","duration":"108.015438ms","start":"2026-05-18T00:56:16.31494Z","end":"2026-05-18T00:56:16.422955Z","steps":["trace[151723482] 'range keys from in-memory index tree' (duration: 107.699461ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:31.532657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6163} {"level":"info","ts":"2026-05-18T00:56:31.63395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6163,"took":"99.022352ms","hash":114709778,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":23060480,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-18T00:56:31.634031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":114709778,"revision":6163,"compact-revision":3049} {"level":"info","ts":"2026-05-18T00:56:44.808218Z","caller":"traceutil/trace.go:171","msg":"trace[578090290] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"184.543568ms","start":"2026-05-18T00:56:44.623633Z","end":"2026-05-18T00:56:44.808176Z","steps":["trace[578090290] 'process raft request' (duration: 184.407625ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:45.0229Z","caller":"traceutil/trace.go:171","msg":"trace[1825690924] linearizableReadLoop","detail":"{readStateIndex:8755; appliedIndex:8754; }","duration":"313.900054ms","start":"2026-05-18T00:56:44.708971Z","end":"2026-05-18T00:56:45.022871Z","steps":["trace[1825690924] 'read index received' (duration: 99.628224ms)","trace[1825690924] 'applied index is now lower than readState.Index' (duration: 214.27094ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:45.023022Z","caller":"traceutil/trace.go:171","msg":"trace[125625926] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"316.105177ms","start":"2026-05-18T00:56:44.706906Z","end":"2026-05-18T00:56:45.023011Z","steps":["trace[125625926] 'process raft request' (duration: 314.620708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:45.023209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:56:44.706879Z","time spent":"316.170078ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:56:45.032431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.445601ms","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-05-18T00:56:45.032556Z","caller":"traceutil/trace.go:171","msg":"trace[588353296] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:8455; }","duration":"323.576124ms","start":"2026-05-18T00:56:44.708958Z","end":"2026-05-18T00:56:45.032534Z","steps":["trace[588353296] 'agreement among raft nodes before linearized reading' (duration: 323.291688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:45.032611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:56:44.708945Z","time spent":"323.655625ms","remote":"127.0.0.1:43716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-05-18T00:56:45.033066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.237716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:56:45.033138Z","caller":"traceutil/trace.go:171","msg":"trace[1216119144] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8455; }","duration":"224.308637ms","start":"2026-05-18T00:56:44.808812Z","end":"2026-05-18T00:56:45.033121Z","steps":["trace[1216119144] 'agreement among raft nodes before linearized reading' (duration: 224.207195ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:48.952181Z","caller":"traceutil/trace.go:171","msg":"trace[1572769080] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"123.207305ms","start":"2026-05-18T00:56:48.828947Z","end":"2026-05-18T00:56:48.952154Z","steps":["trace[1572769080] 'process raft request' (duration: 123.007101ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:58.572075Z","caller":"traceutil/trace.go:171","msg":"trace[955276159] linearizableReadLoop","detail":"{readStateIndex:8878; appliedIndex:8877; }","duration":"252.639962ms","start":"2026-05-18T00:56:58.31941Z","end":"2026-05-18T00:56:58.57205Z","steps":["trace[955276159] 'read index received' (duration: 252.478499ms)","trace[955276159] 'applied index is now lower than readState.Index' (duration: 160.363µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:58.572168Z","caller":"traceutil/trace.go:171","msg":"trace[1274539432] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"252.849797ms","start":"2026-05-18T00:56:58.319289Z","end":"2026-05-18T00:56:58.572139Z","steps":["trace[1274539432] 'process raft request' (duration: 252.597772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:58.572471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.024579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-18T00:56:58.572518Z","caller":"traceutil/trace.go:171","msg":"trace[1960695489] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8571; }","duration":"253.134672ms","start":"2026-05-18T00:56:58.319373Z","end":"2026-05-18T00:56:58.572507Z","steps":["trace[1960695489] 'agreement among raft nodes before linearized reading' (duration: 252.804375ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:58.590207Z","caller":"traceutil/trace.go:171","msg":"trace[839702238] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"270.218057ms","start":"2026-05-18T00:56:58.319951Z","end":"2026-05-18T00:56:58.590169Z","steps":["trace[839702238] 'process raft request' (duration: 270.029513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:58.590451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.89148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:1 size:598"} {"level":"info","ts":"2026-05-18T00:56:58.59049Z","caller":"traceutil/trace.go:171","msg":"trace[92229253] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:1; response_revision:8573; }","duration":"271.142095ms","start":"2026-05-18T00:56:58.319339Z","end":"2026-05-18T00:56:58.590481Z","steps":["trace[92229253] 'agreement among raft nodes before linearized reading' (duration: 271.004632ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:58.590557Z","caller":"traceutil/trace.go:171","msg":"trace[412605286] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"267.762969ms","start":"2026-05-18T00:56:58.322777Z","end":"2026-05-18T00:56:58.59054Z","steps":["trace[412605286] 'process raft request' (duration: 267.348841ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:57:44.699393Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244f.wal"} {"level":"info","ts":"2026-05-18T00:58:16.873656Z","caller":"traceutil/trace.go:171","msg":"trace[1432370204] transaction","detail":"{read_only:false; response_revision:9329; number_of_response:1; }","duration":"169.191694ms","start":"2026-05-18T00:58:16.704431Z","end":"2026-05-18T00:58:16.873623Z","steps":["trace[1432370204] 'process raft request' (duration: 168.976212ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:59:04.017606Z","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-05-18T00:59:04.023787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-18T00:59:04.023941Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-18T01:01:31.539783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8335} {"level":"info","ts":"2026-05-18T01:01:31.614532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8335,"took":"72.542575ms","hash":4260902772,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":26505216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-18T01:01:31.614616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4260902772,"revision":8335,"compact-revision":6163} {"level":"info","ts":"2026-05-18T01:02:00.134467Z","caller":"traceutil/trace.go:171","msg":"trace[2011934236] transaction","detail":"{read_only:false; response_revision:11175; number_of_response:1; }","duration":"105.603308ms","start":"2026-05-18T01:02:00.028798Z","end":"2026-05-18T01:02:00.134402Z","steps":["trace[2011934236] 'process raft request' (duration: 101.331674ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:06:31.547811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10990} {"level":"info","ts":"2026-05-18T01:06:31.625128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10990,"took":"74.623743ms","hash":1813492066,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":28246016,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-18T01:06:31.625205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1813492066,"revision":10990,"compact-revision":8335} {"level":"info","ts":"2026-05-18T01:08:26.253209Z","caller":"traceutil/trace.go:171","msg":"trace[1476603603] transaction","detail":"{read_only:false; response_revision:13792; number_of_response:1; }","duration":"151.264611ms","start":"2026-05-18T01:08:26.101912Z","end":"2026-05-18T01:08:26.253177Z","steps":["trace[1476603603] 'process raft request' (duration: 151.057597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:26.253408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.38243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T01:08:26.253512Z","caller":"traceutil/trace.go:171","msg":"trace[1971468703] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:13792; }","duration":"144.536383ms","start":"2026-05-18T01:08:26.108949Z","end":"2026-05-18T01:08:26.253485Z","steps":["trace[1971468703] 'agreement among raft nodes before linearized reading' (duration: 144.35106ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:08:26.253599Z","caller":"traceutil/trace.go:171","msg":"trace[222993387] linearizableReadLoop","detail":"{readStateIndex:14263; appliedIndex:14262; }","duration":"144.142836ms","start":"2026-05-18T01:08:26.108994Z","end":"2026-05-18T01:08:26.253137Z","steps":["trace[222993387] 'read index received' (duration: 143.913112ms)","trace[222993387] 'applied index is now lower than readState.Index' (duration: 228.814µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:08:26.254657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.525384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-18T01:08:26.254726Z","caller":"traceutil/trace.go:171","msg":"trace[1526904714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13793; }","duration":"144.629685ms","start":"2026-05-18T01:08:26.110081Z","end":"2026-05-18T01:08:26.254711Z","steps":["trace[1526904714] 'agreement among raft nodes before linearized reading' (duration: 144.416591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:26.254801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.311413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-18T01:08:26.254869Z","caller":"traceutil/trace.go:171","msg":"trace[1126139812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13793; }","duration":"143.415184ms","start":"2026-05-18T01:08:26.111439Z","end":"2026-05-18T01:08:26.254854Z","steps":["trace[1126139812] 'agreement among raft nodes before linearized reading' (duration: 143.1748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:26.254801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.285929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-18T01:08:26.25501Z","caller":"traceutil/trace.go:171","msg":"trace[2057712996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13793; }","duration":"144.501963ms","start":"2026-05-18T01:08:26.110486Z","end":"2026-05-18T01:08:26.254988Z","steps":["trace[2057712996] 'agreement among raft nodes before linearized reading' (duration: 144.150237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:34.485979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.091597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-18T01:08:34.486131Z","caller":"traceutil/trace.go:171","msg":"trace[1666962449] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13851; }","duration":"100.351812ms","start":"2026-05-18T01:08:34.385743Z","end":"2026-05-18T01:08:34.486095Z","steps":["trace[1666962449] 'range keys from in-memory index tree' (duration: 99.759762ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:08:34.799535Z","caller":"traceutil/trace.go:171","msg":"trace[1369947829] transaction","detail":"{read_only:false; response_revision:13852; number_of_response:1; }","duration":"295.252444ms","start":"2026-05-18T01:08:34.504257Z","end":"2026-05-18T01:08:34.799509Z","steps":["trace[1369947829] 'process raft request' (duration: 295.00541ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:08:34.799506Z","caller":"traceutil/trace.go:171","msg":"trace[563053239] linearizableReadLoop","detail":"{readStateIndex:14326; appliedIndex:14325; }","duration":"243.98878ms","start":"2026-05-18T01:08:34.555486Z","end":"2026-05-18T01:08:34.799475Z","steps":["trace[563053239] 'read index received' (duration: 243.805517ms)","trace[563053239] 'applied index is now lower than readState.Index' (duration: 181.813µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:08:34.799657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.133392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T01:08:34.799711Z","caller":"traceutil/trace.go:171","msg":"trace[525740107] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13852; }","duration":"244.271634ms","start":"2026-05-18T01:08:34.555427Z","end":"2026-05-18T01:08:34.799699Z","steps":["trace[525740107] 'agreement among raft nodes before linearized reading' (duration: 244.150302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:34.800643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.494306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-18T01:08:34.800707Z","caller":"traceutil/trace.go:171","msg":"trace[1368410200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13853; }","duration":"221.588097ms","start":"2026-05-18T01:08:34.579103Z","end":"2026-05-18T01:08:34.800691Z","steps":["trace[1368410200] 'agreement among raft nodes before linearized reading' (duration: 221.372694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:56.709585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.896183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T01:08:56.709788Z","caller":"traceutil/trace.go:171","msg":"trace[310904472] linearizableReadLoop","detail":"{readStateIndex:14644; appliedIndex:14642; }","duration":"316.202983ms","start":"2026-05-18T01:08:56.393568Z","end":"2026-05-18T01:08:56.709771Z","steps":["trace[310904472] 'read index received' (duration: 46.001536ms)","trace[310904472] 'applied index is now lower than readState.Index' (duration: 270.200456ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:08:56.709888Z","caller":"traceutil/trace.go:171","msg":"trace[2007295800] transaction","detail":"{read_only:false; response_revision:14156; number_of_response:1; }","duration":"392.217508ms","start":"2026-05-18T01:08:56.317659Z","end":"2026-05-18T01:08:56.709876Z","steps":["trace[2007295800] 'process raft request' (duration: 121.93792ms)","trace[2007295800] 'compare' (duration: 269.531779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:08:56.710024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:08:56.317634Z","time spent":"392.352329ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:08:56.71005Z","caller":"traceutil/trace.go:171","msg":"trace[1125631863] transaction","detail":"{read_only:false; response_revision:14157; number_of_response:1; }","duration":"340.269052ms","start":"2026-05-18T01:08:56.369752Z","end":"2026-05-18T01:08:56.710021Z","steps":["trace[1125631863] 'process raft request' (duration: 339.951009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:56.710066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.476835ms","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:5"} {"level":"info","ts":"2026-05-18T01:08:56.710154Z","caller":"traceutil/trace.go:171","msg":"trace[703763333] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14157; }","duration":"316.603077ms","start":"2026-05-18T01:08:56.39353Z","end":"2026-05-18T01:08:56.710133Z","steps":["trace[703763333] 'agreement among raft nodes before linearized reading' (duration: 316.465875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:56.710169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:08:56.369726Z","time spent":"340.372353ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:08:56.710167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.896991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-18T01:08:56.710195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.305011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19fed584-eb42-4ed7-9171-d0c6c8e85721\" ","response":"range_response_count:1 size:3636"} {"level":"warn","ts":"2026-05-18T01:08:56.7102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.797643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-05-18T01:08:56.71024Z","caller":"traceutil/trace.go:171","msg":"trace[1140449258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14157; }","duration":"292.972872ms","start":"2026-05-18T01:08:56.417246Z","end":"2026-05-18T01:08:56.710219Z","steps":["trace[1140449258] 'agreement among raft nodes before linearized reading' (duration: 292.779209ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:08:56.710256Z","caller":"traceutil/trace.go:171","msg":"trace[484795622] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19fed584-eb42-4ed7-9171-d0c6c8e85721; range_end:; response_count:1; response_revision:14157; }","duration":"272.396702ms","start":"2026-05-18T01:08:56.437845Z","end":"2026-05-18T01:08:56.710241Z","steps":["trace[484795622] 'agreement among raft nodes before linearized reading' (duration: 272.189809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:56.710272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.514082ms","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-05-18T01:08:56.710297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.37765ms","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-05-18T01:08:56.710284Z","caller":"traceutil/trace.go:171","msg":"trace[1807819729] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:14157; }","duration":"209.908215ms","start":"2026-05-18T01:08:56.50035Z","end":"2026-05-18T01:08:56.710258Z","steps":["trace[1807819729] 'agreement among raft nodes before linearized reading' (duration: 209.689823ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:08:56.710318Z","caller":"traceutil/trace.go:171","msg":"trace[159543088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14157; }","duration":"283.438051ms","start":"2026-05-18T01:08:56.426874Z","end":"2026-05-18T01:08:56.710312Z","steps":["trace[159543088] 'agreement among raft nodes before linearized reading' (duration: 283.375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:56.710202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:08:56.393518Z","time spent":"316.674198ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:08:56.710336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.342631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-18T01:08:56.710305Z","caller":"traceutil/trace.go:171","msg":"trace[25795408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14157; }","duration":"283.570662ms","start":"2026-05-18T01:08:56.426724Z","end":"2026-05-18T01:08:56.710295Z","steps":["trace[25795408] 'agreement among raft nodes before linearized reading' (duration: 283.444891ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:08:56.710376Z","caller":"traceutil/trace.go:171","msg":"trace[1787280987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14157; }","duration":"290.413461ms","start":"2026-05-18T01:08:56.419954Z","end":"2026-05-18T01:08:56.710367Z","steps":["trace[1787280987] 'agreement among raft nodes before linearized reading' (duration: 290.28835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:08:56.71053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.857595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T01:08:56.710623Z","caller":"traceutil/trace.go:171","msg":"trace[396809068] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:14157; }","duration":"222.961816ms","start":"2026-05-18T01:08:56.487645Z","end":"2026-05-18T01:08:56.710607Z","steps":["trace[396809068] 'agreement among raft nodes before linearized reading' (duration: 222.512981ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:09:49.026898Z","caller":"traceutil/trace.go:171","msg":"trace[952654943] transaction","detail":"{read_only:false; response_revision:14794; number_of_response:1; }","duration":"106.330389ms","start":"2026-05-18T01:09:48.920505Z","end":"2026-05-18T01:09:49.026836Z","steps":["trace[952654943] 'process raft request' (duration: 106.110926ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:10:21.489751Z","caller":"traceutil/trace.go:171","msg":"trace[1704533585] transaction","detail":"{read_only:false; response_revision:14968; number_of_response:1; }","duration":"121.757498ms","start":"2026-05-18T01:10:21.367939Z","end":"2026-05-18T01:10:21.489696Z","steps":["trace[1704533585] 'process raft request' (duration: 121.509975ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:11:31.555966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13064} {"level":"info","ts":"2026-05-18T01:11:31.640751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13064,"took":"81.58735ms","hash":983688368,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":30351360,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T01:11:31.640839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":983688368,"revision":13064,"compact-revision":10990} {"level":"info","ts":"2026-05-18T01:13:16.22468Z","caller":"traceutil/trace.go:171","msg":"trace[21271233] transaction","detail":"{read_only:false; response_revision:16393; number_of_response:1; }","duration":"104.615007ms","start":"2026-05-18T01:13:16.120033Z","end":"2026-05-18T01:13:16.224648Z","steps":["trace[21271233] 'process raft request' (duration: 95.808833ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:13:16.224831Z","caller":"traceutil/trace.go:171","msg":"trace[1233006584] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"104.683998ms","start":"2026-05-18T01:13:16.120123Z","end":"2026-05-18T01:13:16.224807Z","steps":["trace[1233006584] 'process raft request' (duration: 104.473455ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:14.957411Z","caller":"traceutil/trace.go:171","msg":"trace[747704152] transaction","detail":"{read_only:false; response_revision:17574; number_of_response:1; }","duration":"137.026304ms","start":"2026-05-18T01:16:14.820327Z","end":"2026-05-18T01:16:14.957354Z","steps":["trace[747704152] 'process raft request' (duration: 108.024611ms)","trace[747704152] 'compare' (duration: 28.737009ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:16:15.30143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.952509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-18T01:16:15.301537Z","caller":"traceutil/trace.go:171","msg":"trace[958711851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17574; }","duration":"233.122341ms","start":"2026-05-18T01:16:15.068388Z","end":"2026-05-18T01:16:15.30151Z","steps":["trace[958711851] 'range keys from in-memory index tree' (duration: 232.728676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:16:15.302008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.972279ms","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-05-18T01:16:15.30212Z","caller":"traceutil/trace.go:171","msg":"trace[622639254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17574; }","duration":"223.163352ms","start":"2026-05-18T01:16:15.078931Z","end":"2026-05-18T01:16:15.302094Z","steps":["trace[622639254] 'range keys from in-memory index tree' (duration: 222.734936ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.548719Z","caller":"traceutil/trace.go:171","msg":"trace[399462549] transaction","detail":"{read_only:false; response_revision:17575; number_of_response:1; }","duration":"242.803448ms","start":"2026-05-18T01:16:15.305883Z","end":"2026-05-18T01:16:15.548686Z","steps":["trace[399462549] 'process raft request' (duration: 242.600294ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.600873Z","caller":"traceutil/trace.go:171","msg":"trace[1120478282] transaction","detail":"{read_only:false; response_revision:17576; number_of_response:1; }","duration":"294.830894ms","start":"2026-05-18T01:16:15.306016Z","end":"2026-05-18T01:16:15.600847Z","steps":["trace[1120478282] 'process raft request' (duration: 294.652741ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.601187Z","caller":"traceutil/trace.go:171","msg":"trace[789389609] transaction","detail":"{read_only:false; response_revision:17577; number_of_response:1; }","duration":"294.456717ms","start":"2026-05-18T01:16:15.306685Z","end":"2026-05-18T01:16:15.601142Z","steps":["trace[789389609] 'process raft request' (duration: 294.123401ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.601323Z","caller":"traceutil/trace.go:171","msg":"trace[1290208281] linearizableReadLoop","detail":"{readStateIndex:18174; appliedIndex:18169; }","duration":"204.88186ms","start":"2026-05-18T01:16:15.39643Z","end":"2026-05-18T01:16:15.601311Z","steps":["trace[1290208281] 'read index received' (duration: 152.071531ms)","trace[1290208281] 'applied index is now lower than readState.Index' (duration: 52.809059ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:16:15.601416Z","caller":"traceutil/trace.go:171","msg":"trace[313641762] transaction","detail":"{read_only:false; response_revision:17579; number_of_response:1; }","duration":"291.472886ms","start":"2026-05-18T01:16:15.309904Z","end":"2026-05-18T01:16:15.601377Z","steps":["trace[313641762] 'process raft request' (duration: 291.224661ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.60121Z","caller":"traceutil/trace.go:171","msg":"trace[963976847] transaction","detail":"{read_only:false; response_revision:17578; number_of_response:1; }","duration":"293.801306ms","start":"2026-05-18T01:16:15.307371Z","end":"2026-05-18T01:16:15.601172Z","steps":["trace[963976847] 'process raft request' (duration: 293.645634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:16:15.601545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.101325ms","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-05-18T01:16:15.601651Z","caller":"traceutil/trace.go:171","msg":"trace[1146954793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17579; }","duration":"205.271537ms","start":"2026-05-18T01:16:15.396359Z","end":"2026-05-18T01:16:15.601631Z","steps":["trace[1146954793] 'agreement among raft nodes before linearized reading' (duration: 205.055963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:16:15.775236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.835554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/network.18b083f22dcb9d5a\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-05-18T01:16:15.775354Z","caller":"traceutil/trace.go:171","msg":"trace[2051447507] range","detail":"{range_begin:/registry/events/openstack/network.18b083f22dcb9d5a; range_end:; response_count:1; response_revision:17579; }","duration":"170.012047ms","start":"2026-05-18T01:16:15.605312Z","end":"2026-05-18T01:16:15.775324Z","steps":["trace[2051447507] 'range keys from in-memory index tree' (duration: 169.58514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:16:15.775249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.691658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-19fed584-eb42-4ed7-9171-d0c6c8e85721\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-05-18T01:16:15.775662Z","caller":"traceutil/trace.go:171","msg":"trace[2116121055] range","detail":"{range_begin:/registry/persistentvolumes/pvc-19fed584-eb42-4ed7-9171-d0c6c8e85721; range_end:; response_count:1; response_revision:17579; }","duration":"171.160767ms","start":"2026-05-18T01:16:15.604474Z","end":"2026-05-18T01:16:15.775635Z","steps":["trace[2116121055] 'range keys from in-memory index tree' (duration: 170.494446ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.893697Z","caller":"traceutil/trace.go:171","msg":"trace[86027935] linearizableReadLoop","detail":"{readStateIndex:18175; appliedIndex:18174; }","duration":"265.966282ms","start":"2026-05-18T01:16:15.6277Z","end":"2026-05-18T01:16:15.893666Z","steps":["trace[86027935] 'read index received' (duration: 265.828989ms)","trace[86027935] 'applied index is now lower than readState.Index' (duration: 136.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:16:15.893716Z","caller":"traceutil/trace.go:171","msg":"trace[1161279401] transaction","detail":"{read_only:false; response_revision:17580; number_of_response:1; }","duration":"287.726482ms","start":"2026-05-18T01:16:15.605957Z","end":"2026-05-18T01:16:15.893683Z","steps":["trace[1161279401] 'process raft request' (duration: 287.348906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:16:15.894101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.311497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5n7p8\" ","response":"range_response_count:1 size:20869"} {"level":"info","ts":"2026-05-18T01:16:15.894141Z","caller":"traceutil/trace.go:171","msg":"trace[853490399] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5n7p8; range_end:; response_count:1; response_revision:17580; }","duration":"266.45254ms","start":"2026-05-18T01:16:15.627678Z","end":"2026-05-18T01:16:15.89413Z","steps":["trace[853490399] 'agreement among raft nodes before linearized reading' (duration: 266.127605ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.91781Z","caller":"traceutil/trace.go:171","msg":"trace[303570638] transaction","detail":"{read_only:false; response_revision:17581; number_of_response:1; }","duration":"200.759812ms","start":"2026-05-18T01:16:15.717016Z","end":"2026-05-18T01:16:15.917776Z","steps":["trace[303570638] 'process raft request' (duration: 200.481497ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.918312Z","caller":"traceutil/trace.go:171","msg":"trace[1697196809] transaction","detail":"{read_only:false; response_revision:17583; number_of_response:1; }","duration":"102.939054ms","start":"2026-05-18T01:16:15.815354Z","end":"2026-05-18T01:16:15.918293Z","steps":["trace[1697196809] 'process raft request' (duration: 102.68227ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:15.918767Z","caller":"traceutil/trace.go:171","msg":"trace[665873605] transaction","detail":"{read_only:false; response_revision:17582; number_of_response:1; }","duration":"140.691967ms","start":"2026-05-18T01:16:15.778053Z","end":"2026-05-18T01:16:15.918745Z","steps":["trace[665873605] 'process raft request' (duration: 139.628379ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:31.563699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15421} {"level":"info","ts":"2026-05-18T01:16:31.663406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15421,"took":"95.497849ms","hash":1328490686,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":32059392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:16:31.663485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1328490686,"revision":15421,"compact-revision":13064} {"level":"info","ts":"2026-05-18T01:19:52.753043Z","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-05-18T01:19:52.7614Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-18T01:19:52.76151Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-18T01:21:31.576182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17823} {"level":"info","ts":"2026-05-18T01:21:31.675058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17823,"took":"95.073301ms","hash":1500436841,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":33873920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-18T01:21:31.675125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1500436841,"revision":17823,"compact-revision":15421} {"level":"info","ts":"2026-05-18T01:22:02.826225Z","caller":"traceutil/trace.go:171","msg":"trace[1989423455] transaction","detail":"{read_only:false; response_revision:20272; number_of_response:1; }","duration":"124.200517ms","start":"2026-05-18T01:22:02.701994Z","end":"2026-05-18T01:22:02.826195Z","steps":["trace[1989423455] 'process raft request' (duration: 124.027204ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:03.394727Z","caller":"traceutil/trace.go:171","msg":"trace[1903868801] transaction","detail":"{read_only:false; response_revision:20273; number_of_response:1; }","duration":"113.173961ms","start":"2026-05-18T01:22:03.281518Z","end":"2026-05-18T01:22:03.394692Z","steps":["trace[1903868801] 'process raft request' (duration: 112.936006ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:03.404526Z","caller":"traceutil/trace.go:171","msg":"trace[1050919530] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"122.377961ms","start":"2026-05-18T01:22:03.282133Z","end":"2026-05-18T01:22:03.404511Z","steps":["trace[1050919530] 'process raft request' (duration: 122.286489ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:03.667494Z","caller":"traceutil/trace.go:171","msg":"trace[2136786052] transaction","detail":"{read_only:false; response_revision:20276; number_of_response:1; }","duration":"207.517159ms","start":"2026-05-18T01:22:03.459949Z","end":"2026-05-18T01:22:03.667467Z","steps":["trace[2136786052] 'process raft request' (duration: 207.345716ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:03.696524Z","caller":"traceutil/trace.go:171","msg":"trace[373413726] transaction","detail":"{read_only:false; response_revision:20278; number_of_response:1; }","duration":"235.884599ms","start":"2026-05-18T01:22:03.460611Z","end":"2026-05-18T01:22:03.696495Z","steps":["trace[373413726] 'process raft request' (duration: 235.751186ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:03.696586Z","caller":"traceutil/trace.go:171","msg":"trace[1770668731] transaction","detail":"{read_only:false; response_revision:20279; number_of_response:1; }","duration":"233.220766ms","start":"2026-05-18T01:22:03.463341Z","end":"2026-05-18T01:22:03.696562Z","steps":["trace[1770668731] 'process raft request' (duration: 233.100304ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:03.696762Z","caller":"traceutil/trace.go:171","msg":"trace[88720046] transaction","detail":"{read_only:false; response_revision:20277; number_of_response:1; }","duration":"236.041412ms","start":"2026-05-18T01:22:03.460577Z","end":"2026-05-18T01:22:03.696618Z","steps":["trace[88720046] 'process raft request' (duration: 235.667324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:22:03.976205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.572051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:22:03.976332Z","caller":"traceutil/trace.go:171","msg":"trace[318929732] transaction","detail":"{read_only:false; response_revision:20280; number_of_response:1; }","duration":"224.917421ms","start":"2026-05-18T01:22:03.751392Z","end":"2026-05-18T01:22:03.976309Z","steps":["trace[318929732] 'process raft request' (duration: 89.136456ms)","trace[318929732] 'compare' (duration: 135.353967ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:22:04.074026Z","caller":"traceutil/trace.go:171","msg":"trace[465463111] transaction","detail":"{read_only:false; response_revision:20281; number_of_response:1; }","duration":"145.529947ms","start":"2026-05-18T01:22:03.928466Z","end":"2026-05-18T01:22:04.073996Z","steps":["trace[465463111] 'process raft request' (duration: 145.348184ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:22:04.093298Z","caller":"traceutil/trace.go:171","msg":"trace[1428704628] transaction","detail":"{read_only:false; response_revision:20282; number_of_response:1; }","duration":"161.397199ms","start":"2026-05-18T01:22:03.931876Z","end":"2026-05-18T01:22:04.093273Z","steps":["trace[1428704628] 'process raft request' (duration: 160.796938ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:26:31.583819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20113} {"level":"info","ts":"2026-05-18T01:26:31.699653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20113,"took":"111.26839ms","hash":3160205140,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":40501248,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-18T01:26:31.699738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3160205140,"revision":20113,"compact-revision":17823} {"level":"info","ts":"2026-05-18T01:29:40.079746Z","caller":"traceutil/trace.go:171","msg":"trace[1923019398] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"161.74881ms","start":"2026-05-18T01:29:39.917959Z","end":"2026-05-18T01:29:40.079707Z","steps":["trace[1923019398] 'process raft request' (duration: 132.023603ms)","trace[1923019398] 'compare' (duration: 29.130658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:40.344564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.241067ms","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-05-18T01:29:40.34465Z","caller":"traceutil/trace.go:171","msg":"trace[319272417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24905; }","duration":"162.398002ms","start":"2026-05-18T01:29:40.182234Z","end":"2026-05-18T01:29:40.344632Z","steps":["trace[319272417] 'range keys from in-memory index tree' (duration: 162.045109ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:40.344975Z","caller":"traceutil/trace.go:171","msg":"trace[180557500] linearizableReadLoop","detail":"{readStateIndex:25724; appliedIndex:25723; }","duration":"161.900326ms","start":"2026-05-18T01:29:40.183041Z","end":"2026-05-18T01:29:40.344941Z","steps":["trace[180557500] 'read index received' (duration: 133.081669ms)","trace[180557500] 'applied index is now lower than readState.Index' (duration: 28.817117ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:40.345035Z","caller":"traceutil/trace.go:171","msg":"trace[55319458] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"162.464914ms","start":"2026-05-18T01:29:40.182529Z","end":"2026-05-18T01:29:40.344994Z","steps":["trace[55319458] 'process raft request' (duration: 133.523663ms)","trace[55319458] 'compare' (duration: 28.751755ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:40.345485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.406432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-18T01:29:40.345603Z","caller":"traceutil/trace.go:171","msg":"trace[1734934170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24906; }","duration":"162.574408ms","start":"2026-05-18T01:29:40.183005Z","end":"2026-05-18T01:29:40.345579Z","steps":["trace[1734934170] 'agreement among raft nodes before linearized reading' (duration: 162.054751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:40.597446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.802919ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-18T01:29:40.597707Z","caller":"traceutil/trace.go:171","msg":"trace[1551792665] linearizableReadLoop","detail":"{readStateIndex:25725; appliedIndex:25724; }","duration":"252.601048ms","start":"2026-05-18T01:29:40.345085Z","end":"2026-05-18T01:29:40.597686Z","steps":["trace[1551792665] 'read index received' (duration: 96.617023ms)","trace[1551792665] 'applied index is now lower than readState.Index' (duration: 155.983275ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:40.598169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.240842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-05-18T01:29:40.598203Z","caller":"traceutil/trace.go:171","msg":"trace[1757206885] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24906; }","duration":"264.334315ms","start":"2026-05-18T01:29:40.333858Z","end":"2026-05-18T01:29:40.598193Z","steps":["trace[1757206885] 'agreement among raft nodes before linearized reading' (duration: 264.17722ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:40.598358Z","caller":"traceutil/trace.go:171","msg":"trace[1814399674] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"248.834321ms","start":"2026-05-18T01:29:40.349494Z","end":"2026-05-18T01:29:40.598329Z","steps":["trace[1814399674] 'process raft request' (duration: 248.074105ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:40.614523Z","caller":"traceutil/trace.go:171","msg":"trace[653915920] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"264.333266ms","start":"2026-05-18T01:29:40.35016Z","end":"2026-05-18T01:29:40.614493Z","steps":["trace[653915920] 'process raft request' (duration: 248.149277ms)","trace[653915920] 'compare' (duration: 15.838307ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:40.614908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.039477ms","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-05-18T01:29:40.614957Z","caller":"traceutil/trace.go:171","msg":"trace[747183958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24909; }","duration":"236.14547ms","start":"2026-05-18T01:29:40.378796Z","end":"2026-05-18T01:29:40.614941Z","steps":["trace[747183958] 'agreement among raft nodes before linearized reading' (duration: 235.973094ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:40.746827Z","caller":"traceutil/trace.go:171","msg":"trace[386982612] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"127.284192ms","start":"2026-05-18T01:29:40.619511Z","end":"2026-05-18T01:29:40.746796Z","steps":["trace[386982612] 'process raft request' (duration: 127.084965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:44.960805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.433479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-18T01:29:44.960898Z","caller":"traceutil/trace.go:171","msg":"trace[413045983] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:24947; }","duration":"135.603744ms","start":"2026-05-18T01:29:44.825272Z","end":"2026-05-18T01:29:44.960876Z","steps":["trace[413045983] 'range keys from in-memory index tree' (duration: 135.226582ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:45.094892Z","caller":"traceutil/trace.go:171","msg":"trace[1796606802] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"225.437267ms","start":"2026-05-18T01:29:44.869431Z","end":"2026-05-18T01:29:45.094869Z","steps":["trace[1796606802] 'process raft request' (duration: 225.303593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.311272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:44.962052Z","time spent":"349.20783ms","remote":"127.0.0.1:43716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-18T01:29:45.311479Z","caller":"traceutil/trace.go:171","msg":"trace[1145104152] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"130.346176ms","start":"2026-05-18T01:29:45.1811Z","end":"2026-05-18T01:29:45.311446Z","steps":["trace[1145104152] 'process raft request' (duration: 130.17469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.311934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.626204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:45.312057Z","caller":"traceutil/trace.go:171","msg":"trace[761264113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24949; }","duration":"274.816251ms","start":"2026-05-18T01:29:45.037215Z","end":"2026-05-18T01:29:45.312031Z","steps":["trace[761264113] 'agreement among raft nodes before linearized reading' (duration: 274.594994ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:45.31215Z","caller":"traceutil/trace.go:171","msg":"trace[372079086] linearizableReadLoop","detail":"{readStateIndex:25768; appliedIndex:25766; }","duration":"274.189209ms","start":"2026-05-18T01:29:45.037286Z","end":"2026-05-18T01:29:45.311475Z","steps":["trace[372079086] 'read index received' (duration: 57.548389ms)","trace[372079086] 'applied index is now lower than readState.Index' (duration: 216.63795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:45.575963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.00077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:45.57622Z","caller":"traceutil/trace.go:171","msg":"trace[1201839084] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"323.276592ms","start":"2026-05-18T01:29:45.252923Z","end":"2026-05-18T01:29:45.5762Z","steps":["trace[1201839084] 'process raft request' (duration: 183.937322ms)","trace[1201839084] 'compare' (duration: 138.397719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:45.576301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:45.252903Z","time spent":"323.365155ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:45.576793Z","caller":"traceutil/trace.go:171","msg":"trace[1451605065] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"263.857709ms","start":"2026-05-18T01:29:45.312923Z","end":"2026-05-18T01:29:45.57678Z","steps":["trace[1451605065] 'process raft request' (duration: 263.795707ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:45.576983Z","caller":"traceutil/trace.go:171","msg":"trace[287660074] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25769; }","duration":"264.752829ms","start":"2026-05-18T01:29:45.312201Z","end":"2026-05-18T01:29:45.576954Z","steps":["trace[287660074] 'read index received' (duration: 124.626441ms)","trace[287660074] 'applied index is now lower than readState.Index' (duration: 140.125298ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:45.576987Z","caller":"traceutil/trace.go:171","msg":"trace[1873992768] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"321.660038ms","start":"2026-05-18T01:29:45.255183Z","end":"2026-05-18T01:29:45.576843Z","steps":["trace[1873992768] 'process raft request' (duration: 320.917053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.577198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.350663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:29:45.57724Z","caller":"traceutil/trace.go:171","msg":"trace[1808464410] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:24952; }","duration":"346.443597ms","start":"2026-05-18T01:29:45.230786Z","end":"2026-05-18T01:29:45.57723Z","steps":["trace[1808464410] 'agreement among raft nodes before linearized reading' (duration: 346.320743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.577238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.864719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:29:45.577274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:45.230765Z","time spent":"346.500939ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":54,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:29:45.577317Z","caller":"traceutil/trace.go:171","msg":"trace[857468277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24952; }","duration":"263.948952ms","start":"2026-05-18T01:29:45.313345Z","end":"2026-05-18T01:29:45.577294Z","steps":["trace[857468277] 'agreement among raft nodes before linearized reading' (duration: 263.821168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.5773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:45.255171Z","time spent":"321.943007ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:29:45.577577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.833478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-18T01:29:45.577606Z","caller":"traceutil/trace.go:171","msg":"trace[1821486371] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24952; }","duration":"353.910921ms","start":"2026-05-18T01:29:45.223689Z","end":"2026-05-18T01:29:45.5776Z","steps":["trace[1821486371] 'agreement among raft nodes before linearized reading' (duration: 353.800578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.577627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:45.223658Z","time spent":"353.962143ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-18T01:29:45.57776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.629194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-18T01:29:45.577775Z","caller":"traceutil/trace.go:171","msg":"trace[2080345473] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24952; }","duration":"506.685566ms","start":"2026-05-18T01:29:45.071085Z","end":"2026-05-18T01:29:45.577771Z","steps":["trace[2080345473] 'agreement among raft nodes before linearized reading' (duration: 506.611984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.577791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:45.071063Z","time spent":"506.723838ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7556,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-05-18T01:29:45.578207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.537442ms","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-05-18T01:29:45.578297Z","caller":"traceutil/trace.go:171","msg":"trace[288062084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24952; }","duration":"184.686327ms","start":"2026-05-18T01:29:45.393593Z","end":"2026-05-18T01:29:45.57828Z","steps":["trace[288062084] 'agreement among raft nodes before linearized reading' (duration: 184.519581ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:45.80433Z","caller":"traceutil/trace.go:171","msg":"trace[213784269] linearizableReadLoop","detail":"{readStateIndex:25774; appliedIndex:25773; }","duration":"114.020842ms","start":"2026-05-18T01:29:45.690279Z","end":"2026-05-18T01:29:45.8043Z","steps":["trace[213784269] 'read index received' (duration: 113.654479ms)","trace[213784269] 'applied index is now lower than readState.Index' (duration: 365.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:45.804731Z","caller":"traceutil/trace.go:171","msg":"trace[391863247] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"119.402045ms","start":"2026-05-18T01:29:45.685298Z","end":"2026-05-18T01:29:45.8047Z","steps":["trace[391863247] 'process raft request' (duration: 118.709691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:45.804928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.600613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-05-18T01:29:45.805046Z","caller":"traceutil/trace.go:171","msg":"trace[2039774934] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24954; }","duration":"114.801879ms","start":"2026-05-18T01:29:45.690222Z","end":"2026-05-18T01:29:45.805024Z","steps":["trace[2039774934] 'agreement among raft nodes before linearized reading' (duration: 114.351674ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:46.257205Z","caller":"traceutil/trace.go:171","msg":"trace[842776329] linearizableReadLoop","detail":"{readStateIndex:25778; appliedIndex:25777; }","duration":"155.425656ms","start":"2026-05-18T01:29:46.101754Z","end":"2026-05-18T01:29:46.257179Z","steps":["trace[842776329] 'read index received' (duration: 155.169557ms)","trace[842776329] 'applied index is now lower than readState.Index' (duration: 255.049µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:46.257285Z","caller":"traceutil/trace.go:171","msg":"trace[679496326] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"170.128184ms","start":"2026-05-18T01:29:46.087113Z","end":"2026-05-18T01:29:46.257241Z","steps":["trace[679496326] 'process raft request' (duration: 169.885836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.257399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.59181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:46.257546Z","caller":"traceutil/trace.go:171","msg":"trace[914598409] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24958; }","duration":"121.708453ms","start":"2026-05-18T01:29:46.135717Z","end":"2026-05-18T01:29:46.257426Z","steps":["trace[914598409] 'agreement among raft nodes before linearized reading' (duration: 121.61038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.257562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.766117ms","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-05-18T01:29:46.257669Z","caller":"traceutil/trace.go:171","msg":"trace[802131598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24958; }","duration":"155.952123ms","start":"2026-05-18T01:29:46.101686Z","end":"2026-05-18T01:29:46.257639Z","steps":["trace[802131598] 'agreement among raft nodes before linearized reading' (duration: 155.613062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.489741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.762753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:46.489851Z","caller":"traceutil/trace.go:171","msg":"trace[1536351482] linearizableReadLoop","detail":"{readStateIndex:25779; appliedIndex:25778; }","duration":"222.88063ms","start":"2026-05-18T01:29:46.266953Z","end":"2026-05-18T01:29:46.489834Z","steps":["trace[1536351482] 'read index received' (duration: 49.874599ms)","trace[1536351482] 'applied index is now lower than readState.Index' (duration: 173.001691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:46.490057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.115229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-05-18T01:29:46.490083Z","caller":"traceutil/trace.go:171","msg":"trace[2043198218] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24959; }","duration":"223.14197ms","start":"2026-05-18T01:29:46.266933Z","end":"2026-05-18T01:29:46.490075Z","steps":["trace[2043198218] 'agreement among raft nodes before linearized reading' (duration: 222.986544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.490144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.586581ms","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-05-18T01:29:46.490221Z","caller":"traceutil/trace.go:171","msg":"trace[834115202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24959; }","duration":"119.729186ms","start":"2026-05-18T01:29:46.370475Z","end":"2026-05-18T01:29:46.490204Z","steps":["trace[834115202] 'agreement among raft nodes before linearized reading' (duration: 119.504308ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:46.490152Z","caller":"traceutil/trace.go:171","msg":"trace[1790578159] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"226.99254ms","start":"2026-05-18T01:29:46.262925Z","end":"2026-05-18T01:29:46.489918Z","steps":["trace[1790578159] 'process raft request' (duration: 53.960498ms)","trace[1790578159] 'compare' (duration: 172.618378ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:46.887473Z","caller":"traceutil/trace.go:171","msg":"trace[2138333178] linearizableReadLoop","detail":"{readStateIndex:25781; appliedIndex:25780; }","duration":"246.301094ms","start":"2026-05-18T01:29:46.641137Z","end":"2026-05-18T01:29:46.887438Z","steps":["trace[2138333178] 'read index received' (duration: 246.191291ms)","trace[2138333178] 'applied index is now lower than readState.Index' (duration: 108.123µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:46.887716Z","caller":"traceutil/trace.go:171","msg":"trace[901425087] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"249.567885ms","start":"2026-05-18T01:29:46.63805Z","end":"2026-05-18T01:29:46.887618Z","steps":["trace[901425087] 'process raft request' (duration: 249.152301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.887927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.769231ms","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-05-18T01:29:46.888014Z","caller":"traceutil/trace.go:171","msg":"trace[204696379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24961; }","duration":"246.914785ms","start":"2026-05-18T01:29:46.64108Z","end":"2026-05-18T01:29:46.887995Z","steps":["trace[204696379] 'agreement among raft nodes before linearized reading' (duration: 246.720889ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:46.900094Z","caller":"traceutil/trace.go:171","msg":"trace[1647591354] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"174.367639ms","start":"2026-05-18T01:29:46.725699Z","end":"2026-05-18T01:29:46.900066Z","steps":["trace[1647591354] 'process raft request' (duration: 174.132461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.900109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.693815ms","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-05-18T01:29:46.900202Z","caller":"traceutil/trace.go:171","msg":"trace[2035704328] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24962; }","duration":"191.822859ms","start":"2026-05-18T01:29:46.708353Z","end":"2026-05-18T01:29:46.900176Z","steps":["trace[2035704328] 'agreement among raft nodes before linearized reading' (duration: 191.651164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:46.900356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.423268ms","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-05-18T01:29:46.90047Z","caller":"traceutil/trace.go:171","msg":"trace[795373254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24962; }","duration":"122.596923ms","start":"2026-05-18T01:29:46.777847Z","end":"2026-05-18T01:29:46.900444Z","steps":["trace[795373254] 'agreement among raft nodes before linearized reading' (duration: 122.315324ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.058355Z","caller":"traceutil/trace.go:171","msg":"trace[1719774698] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25782; }","duration":"153.795631ms","start":"2026-05-18T01:29:46.904532Z","end":"2026-05-18T01:29:47.058328Z","steps":["trace[1719774698] 'read index received' (duration: 5.975683ms)","trace[1719774698] 'applied index is now lower than readState.Index' (duration: 147.818468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:47.058563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.554117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:47.058604Z","caller":"traceutil/trace.go:171","msg":"trace[1787415779] 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:24964; }","duration":"144.680351ms","start":"2026-05-18T01:29:46.913908Z","end":"2026-05-18T01:29:47.058589Z","steps":["trace[1787415779] 'agreement among raft nodes before linearized reading' (duration: 144.579527ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.058486Z","caller":"traceutil/trace.go:171","msg":"trace[2072749090] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"153.924595ms","start":"2026-05-18T01:29:46.904488Z","end":"2026-05-18T01:29:47.058412Z","steps":["trace[2072749090] 'process raft request' (duration: 136.05991ms)","trace[2072749090] 'compare' (duration: 17.319636ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:47.058663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.099981ms","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-05-18T01:29:47.058808Z","caller":"traceutil/trace.go:171","msg":"trace[96150597] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24964; }","duration":"154.264846ms","start":"2026-05-18T01:29:46.904508Z","end":"2026-05-18T01:29:47.058773Z","steps":["trace[96150597] 'agreement among raft nodes before linearized reading' (duration: 153.918584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:47.344742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.628511ms","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-05-18T01:29:47.344858Z","caller":"traceutil/trace.go:171","msg":"trace[1550397072] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24965; }","duration":"183.790606ms","start":"2026-05-18T01:29:47.161042Z","end":"2026-05-18T01:29:47.344832Z","steps":["trace[1550397072] 'range keys from in-memory index tree' (duration: 183.358592ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.488304Z","caller":"traceutil/trace.go:171","msg":"trace[1322100189] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"170.050531ms","start":"2026-05-18T01:29:47.318235Z","end":"2026-05-18T01:29:47.488286Z","steps":["trace[1322100189] 'process raft request' (duration: 169.74092ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.488233Z","caller":"traceutil/trace.go:171","msg":"trace[1591083969] linearizableReadLoop","detail":"{readStateIndex:25786; appliedIndex:25785; }","duration":"141.619088ms","start":"2026-05-18T01:29:47.346583Z","end":"2026-05-18T01:29:47.488202Z","steps":["trace[1591083969] 'read index received' (duration: 141.358819ms)","trace[1591083969] 'applied index is now lower than readState.Index' (duration: 259.309µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:47.488763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.165426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-05-18T01:29:47.48881Z","caller":"traceutil/trace.go:171","msg":"trace[1961474066] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24966; }","duration":"142.28501ms","start":"2026-05-18T01:29:47.346512Z","end":"2026-05-18T01:29:47.488797Z","steps":["trace[1961474066] 'agreement among raft nodes before linearized reading' (duration: 142.060413ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.501838Z","caller":"traceutil/trace.go:171","msg":"trace[369448020] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"152.89612ms","start":"2026-05-18T01:29:47.348918Z","end":"2026-05-18T01:29:47.501814Z","steps":["trace[369448020] 'process raft request' (duration: 152.684013ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.689862Z","caller":"traceutil/trace.go:171","msg":"trace[1218042903] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"107.43981ms","start":"2026-05-18T01:29:47.582392Z","end":"2026-05-18T01:29:47.689832Z","steps":["trace[1218042903] 'process raft request' (duration: 106.358643ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.701737Z","caller":"traceutil/trace.go:171","msg":"trace[2058891584] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"118.457613ms","start":"2026-05-18T01:29:47.583256Z","end":"2026-05-18T01:29:47.701713Z","steps":["trace[2058891584] 'process raft request' (duration: 118.328939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:47.988982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.143088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:47.989078Z","caller":"traceutil/trace.go:171","msg":"trace[689885543] linearizableReadLoop","detail":"{readStateIndex:25790; appliedIndex:25789; }","duration":"130.578774ms","start":"2026-05-18T01:29:47.858483Z","end":"2026-05-18T01:29:47.989062Z","steps":["trace[689885543] 'read index received' (duration: 179.226µs)","trace[689885543] 'applied index is now lower than readState.Index' (duration: 130.398478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:47.989154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.678816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:47.989193Z","caller":"traceutil/trace.go:171","msg":"trace[1819026807] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24970; }","duration":"130.740969ms","start":"2026-05-18T01:29:47.85843Z","end":"2026-05-18T01:29:47.989171Z","steps":["trace[1819026807] 'agreement among raft nodes before linearized reading' (duration: 130.682427ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:47.989373Z","caller":"traceutil/trace.go:171","msg":"trace[1517977621] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"178.655982ms","start":"2026-05-18T01:29:47.810708Z","end":"2026-05-18T01:29:47.989364Z","steps":["trace[1517977621] 'process raft request' (duration: 48.035167ms)","trace[1517977621] 'compare' (duration: 130.032805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:48.31925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.306817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:29:48.319382Z","caller":"traceutil/trace.go:171","msg":"trace[1447014645] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"333.878732ms","start":"2026-05-18T01:29:47.98548Z","end":"2026-05-18T01:29:48.319358Z","steps":["trace[1447014645] 'process raft request' (duration: 192.347858ms)","trace[1447014645] 'compare' (duration: 140.876412ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:48.319465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:47.985458Z","time spent":"333.960764ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:48.322563Z","caller":"traceutil/trace.go:171","msg":"trace[1875342775] linearizableReadLoop","detail":"{readStateIndex:25792; appliedIndex:25790; }","duration":"284.380784ms","start":"2026-05-18T01:29:48.038143Z","end":"2026-05-18T01:29:48.322524Z","steps":["trace[1875342775] 'read index received' (duration: 139.702663ms)","trace[1875342775] 'applied index is now lower than readState.Index' (duration: 144.676341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:48.322719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.55784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:48.322697Z","caller":"traceutil/trace.go:171","msg":"trace[418804084] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"331.667426ms","start":"2026-05-18T01:29:47.990999Z","end":"2026-05-18T01:29:48.322666Z","steps":["trace[418804084] 'process raft request' (duration: 331.253892ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:48.322756Z","caller":"traceutil/trace.go:171","msg":"trace[1633494191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24972; }","duration":"284.659033ms","start":"2026-05-18T01:29:48.038086Z","end":"2026-05-18T01:29:48.322745Z","steps":["trace[1633494191] 'agreement among raft nodes before linearized reading' (duration: 284.56364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:48.322901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:47.990983Z","time spent":"331.79691ms","remote":"127.0.0.1:43930","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-05-18T01:29:48.899364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.022574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:48.899557Z","caller":"traceutil/trace.go:171","msg":"trace[656229950] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24975; }","duration":"117.311825ms","start":"2026-05-18T01:29:48.78222Z","end":"2026-05-18T01:29:48.899532Z","steps":["trace[656229950] 'count revisions from in-memory index tree' (duration: 116.800277ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:49.194718Z","caller":"traceutil/trace.go:171","msg":"trace[925479966] linearizableReadLoop","detail":"{readStateIndex:25797; appliedIndex:25796; }","duration":"280.146492ms","start":"2026-05-18T01:29:48.914525Z","end":"2026-05-18T01:29:49.194672Z","steps":["trace[925479966] 'read index received' (duration: 203.897478ms)","trace[925479966] 'applied index is now lower than readState.Index' (duration: 76.247874ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:49.194826Z","caller":"traceutil/trace.go:171","msg":"trace[966425216] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"286.210246ms","start":"2026-05-18T01:29:48.908606Z","end":"2026-05-18T01:29:49.194816Z","steps":["trace[966425216] 'process raft request' (duration: 209.794776ms)","trace[966425216] 'compare' (duration: 75.931274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:49.194964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.429921ms","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-05-18T01:29:49.19503Z","caller":"traceutil/trace.go:171","msg":"trace[885855930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24977; }","duration":"280.511164ms","start":"2026-05-18T01:29:48.914506Z","end":"2026-05-18T01:29:49.195017Z","steps":["trace[885855930] 'agreement among raft nodes before linearized reading' (duration: 280.329208ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:49.272928Z","caller":"traceutil/trace.go:171","msg":"trace[996162976] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"159.007108ms","start":"2026-05-18T01:29:49.113888Z","end":"2026-05-18T01:29:49.272895Z","steps":["trace[996162976] 'process raft request' (duration: 158.852523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:49.27308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.935729ms","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":"warn","ts":"2026-05-18T01:29:49.273161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.362725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:29:49.273177Z","caller":"traceutil/trace.go:171","msg":"trace[1209511310] 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:24978; }","duration":"343.091634ms","start":"2026-05-18T01:29:48.930062Z","end":"2026-05-18T01:29:49.273154Z","steps":["trace[1209511310] 'agreement among raft nodes before linearized reading' (duration: 342.841716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:49.273206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.140362ms","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":"warn","ts":"2026-05-18T01:29:49.273213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.716025ms","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-05-18T01:29:49.27319Z","caller":"traceutil/trace.go:171","msg":"trace[1120206993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24978; }","duration":"230.442188ms","start":"2026-05-18T01:29:49.04274Z","end":"2026-05-18T01:29:49.273182Z","steps":["trace[1120206993] 'agreement among raft nodes before linearized reading' (duration: 230.375766ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:49.273272Z","caller":"traceutil/trace.go:171","msg":"trace[1308429378] 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:24978; }","duration":"208.265337ms","start":"2026-05-18T01:29:49.06499Z","end":"2026-05-18T01:29:49.273255Z","steps":["trace[1308429378] 'agreement among raft nodes before linearized reading' (duration: 208.138623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:29:49.273237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:48.930029Z","time spent":"343.196618ms","remote":"127.0.0.1:57036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:29:49.273286Z","caller":"traceutil/trace.go:171","msg":"trace[1933809387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24978; }","duration":"209.821389ms","start":"2026-05-18T01:29:49.063444Z","end":"2026-05-18T01:29:49.273266Z","steps":["trace[1933809387] 'agreement among raft nodes before linearized reading' (duration: 209.489508ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:49.512198Z","caller":"traceutil/trace.go:171","msg":"trace[1348925692] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"313.074377ms","start":"2026-05-18T01:29:49.199087Z","end":"2026-05-18T01:29:49.512161Z","steps":["trace[1348925692] 'process raft request' (duration: 216.267557ms)","trace[1348925692] 'compare' (duration: 96.428817ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:49.512386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:49.199069Z","time spent":"313.219992ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:29:49.524122Z","caller":"traceutil/trace.go:171","msg":"trace[1926179857] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"246.199901ms","start":"2026-05-18T01:29:49.277885Z","end":"2026-05-18T01:29:49.524085Z","steps":["trace[1926179857] 'process raft request' (duration: 245.941333ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:49.895267Z","caller":"traceutil/trace.go:171","msg":"trace[103142645] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"199.345813ms","start":"2026-05-18T01:29:49.695893Z","end":"2026-05-18T01:29:49.895238Z","steps":["trace[103142645] 'process raft request' (duration: 199.159847ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:49.995435Z","caller":"traceutil/trace.go:171","msg":"trace[600224371] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"287.946556ms","start":"2026-05-18T01:29:49.707448Z","end":"2026-05-18T01:29:49.995395Z","steps":["trace[600224371] 'process raft request' (duration: 286.661712ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:29:59.565088Z","caller":"traceutil/trace.go:171","msg":"trace[1748040168] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"247.203225ms","start":"2026-05-18T01:29:59.317854Z","end":"2026-05-18T01:29:59.565057Z","steps":["trace[1748040168] 'process raft request' (duration: 234.168863ms)","trace[1748040168] 'compare' (duration: 12.564625ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:29:59.565185Z","caller":"traceutil/trace.go:171","msg":"trace[1051149880] linearizableReadLoop","detail":"{readStateIndex:25871; appliedIndex:25870; }","duration":"173.652013ms","start":"2026-05-18T01:29:59.391508Z","end":"2026-05-18T01:29:59.56516Z","steps":["trace[1051149880] 'read index received' (duration: 160.544928ms)","trace[1051149880] 'applied index is now lower than readState.Index' (duration: 13.104185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:29:59.565341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.811319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:29:59.565365Z","caller":"traceutil/trace.go:171","msg":"trace[1793706985] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25050; }","duration":"173.893051ms","start":"2026-05-18T01:29:59.391464Z","end":"2026-05-18T01:29:59.565357Z","steps":["trace[1793706985] 'agreement among raft nodes before linearized reading' (duration: 173.787828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.112079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.715394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:30:00.112209Z","caller":"traceutil/trace.go:171","msg":"trace[2131640638] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"538.553776ms","start":"2026-05-18T01:29:59.573634Z","end":"2026-05-18T01:30:00.112187Z","steps":["trace[2131640638] 'process raft request' (duration: 99.127788ms)","trace[2131640638] 'compare' (duration: 438.315099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:00.112274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:59.573595Z","time spent":"538.647808ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:00.288714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954777824515004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T01:30:00.62236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.649084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:30:00.622561Z","caller":"traceutil/trace.go:171","msg":"trace[607139800] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"1.047497649s","start":"2026-05-18T01:29:59.575041Z","end":"2026-05-18T01:30:00.622539Z","steps":["trace[607139800] 'process raft request' (duration: 780.566716ms)","trace[607139800] 'compare' (duration: 266.478787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:00.62266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:59.57502Z","time spent":"1.047588332s","remote":"127.0.0.1:43930","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-05-18T01:30:00.62347Z","caller":"traceutil/trace.go:171","msg":"trace[378289479] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"693.341599ms","start":"2026-05-18T01:29:59.930078Z","end":"2026-05-18T01:30:00.623419Z","steps":["trace[378289479] 'process raft request' (duration: 692.406788ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:00.623525Z","caller":"traceutil/trace.go:171","msg":"trace[1687292395] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"591.086606ms","start":"2026-05-18T01:30:00.032385Z","end":"2026-05-18T01:30:00.623472Z","steps":["trace[1687292395] 'process raft request' (duration: 590.951011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.623682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:59.930052Z","time spent":"693.513915ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:00.623713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.032363Z","time spent":"591.2388ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:00.623749Z","caller":"traceutil/trace.go:171","msg":"trace[609741350] linearizableReadLoop","detail":"{readStateIndex:25878; appliedIndex:25873; }","duration":"835.255988ms","start":"2026-05-18T01:29:59.788439Z","end":"2026-05-18T01:30:00.623695Z","steps":["trace[609741350] 'read index received' (duration: 567.293441ms)","trace[609741350] 'applied index is now lower than readState.Index' (duration: 267.961147ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:00.623871Z","caller":"traceutil/trace.go:171","msg":"trace[1030558610] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"518.674383ms","start":"2026-05-18T01:30:00.105184Z","end":"2026-05-18T01:30:00.623859Z","steps":["trace[1030558610] 'process raft request' (duration: 518.368792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.623897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.463026ms","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":"warn","ts":"2026-05-18T01:30:00.623923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.105166Z","time spent":"518.731845ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T01:30:00.623937Z","caller":"traceutil/trace.go:171","msg":"trace[567677762] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25056; }","duration":"835.550229ms","start":"2026-05-18T01:29:59.788373Z","end":"2026-05-18T01:30:00.623924Z","steps":["trace[567677762] 'agreement among raft nodes before linearized reading' (duration: 835.486187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.623973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:59.788346Z","time spent":"835.618942ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:30:00.624127Z","caller":"traceutil/trace.go:171","msg":"trace[1766217340] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"580.800747ms","start":"2026-05-18T01:30:00.042844Z","end":"2026-05-18T01:30:00.623645Z","steps":["trace[1766217340] 'process raft request' (duration: 580.580609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.624247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.042816Z","time spent":"581.369797ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:00.968858Z","caller":"traceutil/trace.go:171","msg":"trace[1695372746] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"588.748465ms","start":"2026-05-18T01:30:00.380074Z","end":"2026-05-18T01:30:00.968823Z","steps":["trace[1695372746] 'process raft request' (duration: 588.696164ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:00.968874Z","caller":"traceutil/trace.go:171","msg":"trace[1191639323] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25880; }","duration":"345.010487ms","start":"2026-05-18T01:30:00.623833Z","end":"2026-05-18T01:30:00.968844Z","steps":["trace[1191639323] 'read index received' (duration: 344.997427ms)","trace[1191639323] 'applied index is now lower than readState.Index' (duration: 11.03µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:00.969096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.390885ms","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":"warn","ts":"2026-05-18T01:30:00.969095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.380044Z","time spent":"588.8945ms","remote":"127.0.0.1:43930","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-05-18T01:30:00.969146Z","caller":"traceutil/trace.go:171","msg":"trace[1208438338] 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:25058; }","duration":"357.498798ms","start":"2026-05-18T01:30:00.611633Z","end":"2026-05-18T01:30:00.969132Z","steps":["trace[1208438338] 'agreement among raft nodes before linearized reading' (duration: 357.351765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.969185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.611598Z","time spent":"357.57296ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:00.969444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.071696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:00.969483Z","caller":"traceutil/trace.go:171","msg":"trace[1085137413] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25058; }","duration":"629.153668ms","start":"2026-05-18T01:30:00.34032Z","end":"2026-05-18T01:30:00.969474Z","steps":["trace[1085137413] 'agreement among raft nodes before linearized reading' (duration: 629.089336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.969514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.340298Z","time spent":"629.20819ms","remote":"127.0.0.1:56956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:30:00.969522Z","caller":"traceutil/trace.go:171","msg":"trace[1318259508] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"590.587691ms","start":"2026-05-18T01:30:00.378921Z","end":"2026-05-18T01:30:00.969509Z","steps":["trace[1318259508] 'process raft request' (duration: 589.67757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.969587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.378903Z","time spent":"590.647773ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:00.969672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.68237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:00.969702Z","caller":"traceutil/trace.go:171","msg":"trace[1777349947] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25058; }","duration":"721.756493ms","start":"2026-05-18T01:30:00.247936Z","end":"2026-05-18T01:30:00.969692Z","steps":["trace[1777349947] 'agreement among raft nodes before linearized reading' (duration: 721.702962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.969731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.247915Z","time spent":"721.808434ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:00.970031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111888856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:00.970124Z","caller":"traceutil/trace.go:171","msg":"trace[1252854746] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25058; }","duration":"1.11202174s","start":"2026-05-18T01:29:59.858084Z","end":"2026-05-18T01:30:00.970106Z","steps":["trace[1252854746] 'agreement among raft nodes before linearized reading' (duration: 1.111901567s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.970195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:29:59.858057Z","time spent":"1.112125633s","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-18T01:30:00.970548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.151814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:00.970601Z","caller":"traceutil/trace.go:171","msg":"trace[1978326924] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25058; }","duration":"961.257987ms","start":"2026-05-18T01:30:00.009331Z","end":"2026-05-18T01:30:00.970589Z","steps":["trace[1978326924] 'agreement among raft nodes before linearized reading' (duration: 961.168765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.970635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.009309Z","time spent":"961.316508ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:00.970837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.719212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:00.970874Z","caller":"traceutil/trace.go:171","msg":"trace[1025063893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25058; }","duration":"928.791104ms","start":"2026-05-18T01:30:00.042072Z","end":"2026-05-18T01:30:00.970863Z","steps":["trace[1025063893] 'agreement among raft nodes before linearized reading' (duration: 928.741002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.970899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.042055Z","time spent":"928.839315ms","remote":"127.0.0.1:43706","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-05-18T01:30:00.970554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.158395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:30:00.970969Z","caller":"traceutil/trace.go:171","msg":"trace[414144912] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25058; }","duration":"907.631307ms","start":"2026-05-18T01:30:00.063323Z","end":"2026-05-18T01:30:00.970954Z","steps":["trace[414144912] 'agreement among raft nodes before linearized reading' (duration: 907.173786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:00.971086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.063281Z","time spent":"907.7563ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:01.357793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.62907Z","time spent":"728.696724ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-18T01:30:01.357887Z","caller":"traceutil/trace.go:171","msg":"trace[2041675916] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"725.059469ms","start":"2026-05-18T01:30:00.632796Z","end":"2026-05-18T01:30:01.357855Z","steps":["trace[2041675916] 'process raft request' (duration: 724.862154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.358133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.632777Z","time spent":"725.253454ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:01.372852Z","caller":"traceutil/trace.go:171","msg":"trace[1493475855] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"515.341624ms","start":"2026-05-18T01:30:00.857465Z","end":"2026-05-18T01:30:01.372807Z","steps":["trace[1493475855] 'process raft request' (duration: 515.16465ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:01.372895Z","caller":"traceutil/trace.go:171","msg":"trace[159259402] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"427.040438ms","start":"2026-05-18T01:30:00.945822Z","end":"2026-05-18T01:30:01.372862Z","steps":["trace[159259402] 'process raft request' (duration: 426.922415ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:01.372934Z","caller":"traceutil/trace.go:171","msg":"trace[1855733280] linearizableReadLoop","detail":"{readStateIndex:25885; appliedIndex:25880; }","duration":"403.962082ms","start":"2026-05-18T01:30:00.968952Z","end":"2026-05-18T01:30:01.372914Z","steps":["trace[1855733280] 'read index received' (duration: 310.746612ms)","trace[1855733280] 'applied index is now lower than readState.Index' (duration: 93.21386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:01.373079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.857435Z","time spent":"515.533589ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:01.373081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.945788Z","time spent":"427.17552ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:01.373222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.130913ms","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-05-18T01:30:01.373274Z","caller":"traceutil/trace.go:171","msg":"trace[1154177878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25062; }","duration":"703.186924ms","start":"2026-05-18T01:30:00.670075Z","end":"2026-05-18T01:30:01.373262Z","steps":["trace[1154177878] 'agreement among raft nodes before linearized reading' (duration: 702.976239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.373309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.67002Z","time spent":"703.280287ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T01:30:01.373281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.345534ms","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":"warn","ts":"2026-05-18T01:30:01.373377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.739455ms","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-05-18T01:30:01.373441Z","caller":"traceutil/trace.go:171","msg":"trace[239790441] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25062; }","duration":"251.855408ms","start":"2026-05-18T01:30:01.121569Z","end":"2026-05-18T01:30:01.373424Z","steps":["trace[239790441] 'agreement among raft nodes before linearized reading' (duration: 251.736145ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:01.373431Z","caller":"traceutil/trace.go:171","msg":"trace[42249531] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:25062; }","duration":"743.492507ms","start":"2026-05-18T01:30:00.629881Z","end":"2026-05-18T01:30:01.373373Z","steps":["trace[42249531] 'agreement among raft nodes before linearized reading' (duration: 743.319633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.373517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.939051ms","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-05-18T01:30:01.37356Z","caller":"traceutil/trace.go:171","msg":"trace[1372454486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25062; }","duration":"705.050053ms","start":"2026-05-18T01:30:00.6685Z","end":"2026-05-18T01:30:01.37355Z","steps":["trace[1372454486] 'agreement among raft nodes before linearized reading' (duration: 704.952301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.373588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.668473Z","time spent":"705.108105ms","remote":"127.0.0.1:43930","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-05-18T01:30:01.373581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.130592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:30:01.373559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.629865Z","time spent":"743.642061ms","remote":"127.0.0.1:43822","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":"info","ts":"2026-05-18T01:30:01.373633Z","caller":"traceutil/trace.go:171","msg":"trace[205884773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25062; }","duration":"217.211393ms","start":"2026-05-18T01:30:01.156409Z","end":"2026-05-18T01:30:01.37362Z","steps":["trace[205884773] 'agreement among raft nodes before linearized reading' (duration: 217.138061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.373694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.5452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:01.373732Z","caller":"traceutil/trace.go:171","msg":"trace[84627641] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25062; }","duration":"396.628681ms","start":"2026-05-18T01:30:00.977087Z","end":"2026-05-18T01:30:01.373716Z","steps":["trace[84627641] 'agreement among raft nodes before linearized reading' (duration: 396.57805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.373765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.977061Z","time spent":"396.696993ms","remote":"127.0.0.1:43752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"warn","ts":"2026-05-18T01:30:01.373869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.286719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274815"} {"level":"info","ts":"2026-05-18T01:30:01.373914Z","caller":"traceutil/trace.go:171","msg":"trace[276921998] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25062; }","duration":"369.389121ms","start":"2026-05-18T01:30:01.004515Z","end":"2026-05-18T01:30:01.373904Z","steps":["trace[276921998] 'agreement among raft nodes before linearized reading' (duration: 368.849868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.373981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.004486Z","time spent":"369.485703ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274838,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-05-18T01:30:01.37334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.155616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:30:01.374203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.93515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-18T01:30:01.374259Z","caller":"traceutil/trace.go:171","msg":"trace[2145191442] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25062; }","duration":"725.012971ms","start":"2026-05-18T01:30:00.649229Z","end":"2026-05-18T01:30:01.374242Z","steps":["trace[2145191442] 'agreement among raft nodes before linearized reading' (duration: 724.793266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.3743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.649219Z","time spent":"725.070933ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-18T01:30:01.375481Z","caller":"traceutil/trace.go:171","msg":"trace[1208200466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25062; }","duration":"401.972115ms","start":"2026-05-18T01:30:00.972144Z","end":"2026-05-18T01:30:01.374116Z","steps":["trace[1208200466] 'agreement among raft nodes before linearized reading' (duration: 401.140066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.375747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:00.972127Z","time spent":"403.483261ms","remote":"127.0.0.1:43710","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-05-18T01:30:01.919428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954777824515023,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T01:30:01.946823Z","caller":"traceutil/trace.go:171","msg":"trace[2046502733] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"567.996894ms","start":"2026-05-18T01:30:01.378785Z","end":"2026-05-18T01:30:01.946782Z","steps":["trace[2046502733] 'process raft request' (duration: 567.703067ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:01.946897Z","caller":"traceutil/trace.go:171","msg":"trace[1842065530] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"567.202966ms","start":"2026-05-18T01:30:01.379635Z","end":"2026-05-18T01:30:01.946838Z","steps":["trace[1842065530] 'process raft request' (duration: 567.029972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.947009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.378765Z","time spent":"568.162578ms","remote":"127.0.0.1:43868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T01:30:01.947032Z","caller":"traceutil/trace.go:171","msg":"trace[2019635840] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"567.001661ms","start":"2026-05-18T01:30:01.380001Z","end":"2026-05-18T01:30:01.947003Z","steps":["trace[2019635840] 'process raft request' (duration: 566.729875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.947268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.379974Z","time spent":"567.154045ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:01.947544Z","caller":"traceutil/trace.go:171","msg":"trace[1536980319] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"567.059132ms","start":"2026-05-18T01:30:01.380467Z","end":"2026-05-18T01:30:01.947526Z","steps":["trace[1536980319] 'process raft request' (duration: 566.304274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.947619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.380456Z","time spent":"567.127123ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:01.947525Z","caller":"traceutil/trace.go:171","msg":"trace[1593041153] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"566.951949ms","start":"2026-05-18T01:30:01.380541Z","end":"2026-05-18T01:30:01.947493Z","steps":["trace[1593041153] 'process raft request' (duration: 566.267013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:01.94777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.380533Z","time spent":"567.188054ms","remote":"127.0.0.1:43930","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-05-18T01:30:01.949383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.379595Z","time spent":"567.3646ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:02.288139Z","caller":"traceutil/trace.go:171","msg":"trace[753853576] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"713.103637ms","start":"2026-05-18T01:30:01.574998Z","end":"2026-05-18T01:30:02.288102Z","steps":["trace[753853576] 'process raft request' (duration: 712.812921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.288369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.574965Z","time spent":"713.289662ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:02.288375Z","caller":"traceutil/trace.go:171","msg":"trace[682755151] linearizableReadLoop","detail":"{readStateIndex:25894; appliedIndex:25887; }","duration":"869.302917ms","start":"2026-05-18T01:30:01.419027Z","end":"2026-05-18T01:30:02.28833Z","steps":["trace[682755151] 'read index received' (duration: 527.608808ms)","trace[682755151] 'applied index is now lower than readState.Index' (duration: 341.690589ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:02.288552Z","caller":"traceutil/trace.go:171","msg":"trace[301319184] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"711.125261ms","start":"2026-05-18T01:30:01.577402Z","end":"2026-05-18T01:30:02.288527Z","steps":["trace[301319184] 'process raft request' (duration: 710.624009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.288776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.577379Z","time spent":"711.266705ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:02.288871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.819389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-05-18T01:30:02.288928Z","caller":"traceutil/trace.go:171","msg":"trace[630286091] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25070; }","duration":"869.921452ms","start":"2026-05-18T01:30:01.418986Z","end":"2026-05-18T01:30:02.288907Z","steps":["trace[630286091] 'agreement among raft nodes before linearized reading' (duration: 869.710457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.28897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.418972Z","time spent":"869.989533ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1674,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-18T01:30:02.290437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.379691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-05-18T01:30:02.290566Z","caller":"traceutil/trace.go:171","msg":"trace[527604407] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25073; }","duration":"867.502354ms","start":"2026-05-18T01:30:01.422994Z","end":"2026-05-18T01:30:02.290497Z","steps":["trace[527604407] 'agreement among raft nodes before linearized reading' (duration: 867.256978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.290675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.422977Z","time spent":"867.680359ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1674,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-18T01:30:02.291008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.335688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:02.291117Z","caller":"traceutil/trace.go:171","msg":"trace[1242006901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25073; }","duration":"255.463401ms","start":"2026-05-18T01:30:02.035638Z","end":"2026-05-18T01:30:02.291102Z","steps":["trace[1242006901] 'agreement among raft nodes before linearized reading' (duration: 255.315888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.291342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.070758ms","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-05-18T01:30:02.291426Z","caller":"traceutil/trace.go:171","msg":"trace[2113551744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25073; }","duration":"175.19172ms","start":"2026-05-18T01:30:02.116206Z","end":"2026-05-18T01:30:02.291398Z","steps":["trace[2113551744] 'agreement among raft nodes before linearized reading' (duration: 174.227697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.291504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.63322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:02.291558Z","caller":"traceutil/trace.go:171","msg":"trace[590001930] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25073; }","duration":"285.727901ms","start":"2026-05-18T01:30:02.005812Z","end":"2026-05-18T01:30:02.29154Z","steps":["trace[590001930] 'agreement among raft nodes before linearized reading' (duration: 285.64259ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:02.291649Z","caller":"traceutil/trace.go:171","msg":"trace[51631419] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"337.430041ms","start":"2026-05-18T01:30:01.9542Z","end":"2026-05-18T01:30:02.29163Z","steps":["trace[51631419] 'process raft request' (duration: 335.766022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.29298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.954178Z","time spent":"338.76519ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T01:30:02.291948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.644171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:02.293617Z","caller":"traceutil/trace.go:171","msg":"trace[678054130] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25073; }","duration":"454.393441ms","start":"2026-05-18T01:30:01.839209Z","end":"2026-05-18T01:30:02.293602Z","steps":["trace[678054130] 'agreement among raft nodes before linearized reading' (duration: 452.641971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.293663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.839189Z","time spent":"454.458532ms","remote":"127.0.0.1:43896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:02.292025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.876474ms","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-05-18T01:30:02.298451Z","caller":"traceutil/trace.go:171","msg":"trace[189238149] 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:25073; }","duration":"493.334634ms","start":"2026-05-18T01:30:01.805084Z","end":"2026-05-18T01:30:02.298419Z","steps":["trace[189238149] 'agreement among raft nodes before linearized reading' (duration: 486.874274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.298538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.805067Z","time spent":"493.431845ms","remote":"127.0.0.1:42968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:02.29218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.857055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:30:02.298754Z","caller":"traceutil/trace.go:171","msg":"trace[1153315944] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25073; }","duration":"645.493159ms","start":"2026-05-18T01:30:01.653248Z","end":"2026-05-18T01:30:02.298741Z","steps":["trace[1153315944] 'agreement among raft nodes before linearized reading' (duration: 638.817104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.298784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.653224Z","time spent":"645.550141ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:30:02.292389Z","caller":"traceutil/trace.go:171","msg":"trace[1254302135] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"338.045264ms","start":"2026-05-18T01:30:01.954327Z","end":"2026-05-18T01:30:02.292373Z","steps":["trace[1254302135] 'process raft request' (duration: 335.756441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.299095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.954318Z","time spent":"344.682729ms","remote":"127.0.0.1:43868","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-05-18T01:30:02.292568Z","caller":"traceutil/trace.go:171","msg":"trace[81809737] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"338.223989ms","start":"2026-05-18T01:30:01.954328Z","end":"2026-05-18T01:30:02.292552Z","steps":["trace[81809737] 'process raft request' (duration: 335.846733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:02.299339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:01.954274Z","time spent":"345.039437ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:10.302769Z","caller":"traceutil/trace.go:171","msg":"trace[1715446680] linearizableReadLoop","detail":"{readStateIndex:26053; appliedIndex:26050; }","duration":"283.210494ms","start":"2026-05-18T01:30:10.019534Z","end":"2026-05-18T01:30:10.302744Z","steps":["trace[1715446680] 'read index received' (duration: 40.08655ms)","trace[1715446680] 'applied index is now lower than readState.Index' (duration: 243.122664ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:10.303021Z","caller":"traceutil/trace.go:171","msg":"trace[960054313] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"302.847158ms","start":"2026-05-18T01:30:10.000123Z","end":"2026-05-18T01:30:10.30297Z","steps":["trace[960054313] 'process raft request' (duration: 302.559262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.303081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.518381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:30:10.303124Z","caller":"traceutil/trace.go:171","msg":"trace[1154702588] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25227; }","duration":"283.620774ms","start":"2026-05-18T01:30:10.019491Z","end":"2026-05-18T01:30:10.303112Z","steps":["trace[1154702588] 'agreement among raft nodes before linearized reading' (duration: 283.526412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.303276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:10.000113Z","time spent":"303.007222ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:30:10.303559Z","caller":"traceutil/trace.go:171","msg":"trace[1348243075] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"303.495814ms","start":"2026-05-18T01:30:10.000048Z","end":"2026-05-18T01:30:10.303544Z","steps":["trace[1348243075] 'process raft request' (duration: 296.888251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.303623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:10.000037Z","time spent":"303.550345ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:10.606232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.753711ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-18T01:30:10.606493Z","caller":"traceutil/trace.go:171","msg":"trace[255257216] linearizableReadLoop","detail":"{readStateIndex:26054; appliedIndex:26053; }","duration":"303.628886ms","start":"2026-05-18T01:30:10.302845Z","end":"2026-05-18T01:30:10.606474Z","steps":["trace[255257216] 'read index received' (duration: 43.586539ms)","trace[255257216] 'applied index is now lower than readState.Index' (duration: 260.041147ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:10.607298Z","caller":"traceutil/trace.go:171","msg":"trace[2137417465] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"290.998674ms","start":"2026-05-18T01:30:10.316254Z","end":"2026-05-18T01:30:10.607253Z","steps":["trace[2137417465] 'process raft request' (duration: 290.107293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.795554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.673512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:10.795662Z","caller":"traceutil/trace.go:171","msg":"trace[372484089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25228; }","duration":"550.876417ms","start":"2026-05-18T01:30:10.244758Z","end":"2026-05-18T01:30:10.795634Z","steps":["trace[372484089] 'agreement among raft nodes before linearized reading' (duration: 362.268056ms)","trace[372484089] 'range keys from in-memory index tree' (duration: 188.373865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:10.795704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:10.244735Z","time spent":"550.960519ms","remote":"127.0.0.1:43706","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-05-18T01:30:10.795854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:30:10.796054Z","caller":"traceutil/trace.go:171","msg":"trace[1378959435] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"128.778345ms","start":"2026-05-18T01:30:10.667264Z","end":"2026-05-18T01:30:10.796042Z","steps":["trace[1378959435] 'process raft request' (duration: 128.703243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.796185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.025671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:30:10.796233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.679252ms","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-05-18T01:30:10.796077Z","caller":"traceutil/trace.go:171","msg":"trace[115404113] linearizableReadLoop","detail":"{readStateIndex:26056; appliedIndex:26054; }","duration":"189.531483ms","start":"2026-05-18T01:30:10.60653Z","end":"2026-05-18T01:30:10.796061Z","steps":["trace[115404113] 'read index received' (duration: 458.271µs)","trace[115404113] 'applied index is now lower than readState.Index' (duration: 189.071642ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:10.796277Z","caller":"traceutil/trace.go:171","msg":"trace[920916671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25230; }","duration":"465.757525ms","start":"2026-05-18T01:30:10.33051Z","end":"2026-05-18T01:30:10.796268Z","steps":["trace[920916671] 'agreement among raft nodes before linearized reading' (duration: 465.595151ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:10.796027Z","caller":"traceutil/trace.go:171","msg":"trace[1352145972] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"478.721775ms","start":"2026-05-18T01:30:10.317238Z","end":"2026-05-18T01:30:10.79596Z","steps":["trace[1352145972] 'process raft request' (duration: 289.98087ms)","trace[1352145972] 'compare' (duration: 188.318244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:10.796307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:10.330498Z","time spent":"465.802165ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T01:30:10.796299Z","caller":"traceutil/trace.go:171","msg":"trace[27312296] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25230; }","duration":"422.171055ms","start":"2026-05-18T01:30:10.374094Z","end":"2026-05-18T01:30:10.796265Z","steps":["trace[27312296] 'agreement among raft nodes before linearized reading' (duration: 421.998691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.796369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:10.374082Z","time spent":"422.274317ms","remote":"127.0.0.1:51282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:30:10.796552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:10.31722Z","time spent":"479.153654ms","remote":"127.0.0.1:43930","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-05-18T01:30:10.798744Z","caller":"traceutil/trace.go:171","msg":"trace[1646011682] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"122.321025ms","start":"2026-05-18T01:30:10.676377Z","end":"2026-05-18T01:30:10.798698Z","steps":["trace[1646011682] 'process raft request' (duration: 122.11302ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:10.798768Z","caller":"traceutil/trace.go:171","msg":"trace[193071054] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"112.115138ms","start":"2026-05-18T01:30:10.68659Z","end":"2026-05-18T01:30:10.798705Z","steps":["trace[193071054] 'process raft request' (duration: 112.062897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:10.798842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.399513ms","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-05-18T01:30:10.798892Z","caller":"traceutil/trace.go:171","msg":"trace[7272778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25232; }","duration":"108.493394ms","start":"2026-05-18T01:30:10.690389Z","end":"2026-05-18T01:30:10.798882Z","steps":["trace[7272778] 'agreement among raft nodes before linearized reading' (duration: 108.3113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:13.162307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.819623ms","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-05-18T01:30:13.162387Z","caller":"traceutil/trace.go:171","msg":"trace[1665594553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25257; }","duration":"175.972898ms","start":"2026-05-18T01:30:12.986393Z","end":"2026-05-18T01:30:13.162366Z","steps":["trace[1665594553] 'range keys from in-memory index tree' (duration: 175.587998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:13.162757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.166784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:30:13.162947Z","caller":"traceutil/trace.go:171","msg":"trace[776293698] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"152.2907ms","start":"2026-05-18T01:30:13.010634Z","end":"2026-05-18T01:30:13.162924Z","steps":["trace[776293698] 'process raft request' (duration: 47.863479ms)","trace[776293698] 'compare' (duration: 103.616372ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:13.204534Z","caller":"traceutil/trace.go:171","msg":"trace[1850426904] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"190.804062ms","start":"2026-05-18T01:30:13.013708Z","end":"2026-05-18T01:30:13.204512Z","steps":["trace[1850426904] 'process raft request' (duration: 190.435164ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:13.204916Z","caller":"traceutil/trace.go:171","msg":"trace[1324389149] linearizableReadLoop","detail":"{readStateIndex:26086; appliedIndex:26084; }","duration":"167.648925ms","start":"2026-05-18T01:30:13.036647Z","end":"2026-05-18T01:30:13.204296Z","steps":["trace[1324389149] 'read index received' (duration: 21.884177ms)","trace[1324389149] 'applied index is now lower than readState.Index' (duration: 145.763558ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:13.205078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.428755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:13.205145Z","caller":"traceutil/trace.go:171","msg":"trace[1733239812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25259; }","duration":"168.498836ms","start":"2026-05-18T01:30:13.036627Z","end":"2026-05-18T01:30:13.205125Z","steps":["trace[1733239812] 'agreement among raft nodes before linearized reading' (duration: 168.343303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:13.205651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.920308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:13.205703Z","caller":"traceutil/trace.go:171","msg":"trace[1793196754] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25259; }","duration":"116.120792ms","start":"2026-05-18T01:30:13.089568Z","end":"2026-05-18T01:30:13.205689Z","steps":["trace[1793196754] 'agreement among raft nodes before linearized reading' (duration: 115.453827ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:26.325717Z","caller":"traceutil/trace.go:171","msg":"trace[1302687527] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"184.063436ms","start":"2026-05-18T01:30:26.141624Z","end":"2026-05-18T01:30:26.325687Z","steps":["trace[1302687527] 'process raft request' (duration: 183.885172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:26.485534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.369776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-18T01:30:26.485609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.849093ms","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-05-18T01:30:26.485644Z","caller":"traceutil/trace.go:171","msg":"trace[1391408658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25367; }","duration":"106.540291ms","start":"2026-05-18T01:30:26.37908Z","end":"2026-05-18T01:30:26.48562Z","steps":["trace[1391408658] 'range keys from in-memory index tree' (duration: 106.147331ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:26.485709Z","caller":"traceutil/trace.go:171","msg":"trace[1446761747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25367; }","duration":"103.996677ms","start":"2026-05-18T01:30:26.381684Z","end":"2026-05-18T01:30:26.485681Z","steps":["trace[1446761747] 'range keys from in-memory index tree' (duration: 103.674289ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:26.796472Z","caller":"traceutil/trace.go:171","msg":"trace[244126929] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"128.734945ms","start":"2026-05-18T01:30:26.667706Z","end":"2026-05-18T01:30:26.796441Z","steps":["trace[244126929] 'process raft request' (duration: 128.53509ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:26.956944Z","caller":"traceutil/trace.go:171","msg":"trace[329513189] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"100.90029ms","start":"2026-05-18T01:30:26.855988Z","end":"2026-05-18T01:30:26.956888Z","steps":["trace[329513189] 'process raft request' (duration: 86.575631ms)","trace[329513189] 'compare' (duration: 13.942399ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:27.211285Z","caller":"traceutil/trace.go:171","msg":"trace[351771100] linearizableReadLoop","detail":"{readStateIndex:26205; appliedIndex:26201; }","duration":"286.981887ms","start":"2026-05-18T01:30:26.924275Z","end":"2026-05-18T01:30:27.211257Z","steps":["trace[351771100] 'read index received' (duration: 18.385189ms)","trace[351771100] 'applied index is now lower than readState.Index' (duration: 268.595638ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:27.211543Z","caller":"traceutil/trace.go:171","msg":"trace[1716981206] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"349.918228ms","start":"2026-05-18T01:30:26.861592Z","end":"2026-05-18T01:30:27.21151Z","steps":["trace[1716981206] 'process raft request' (duration: 349.622671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.211643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.359816ms","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-05-18T01:30:27.211682Z","caller":"traceutil/trace.go:171","msg":"trace[358185277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25375; }","duration":"287.451639ms","start":"2026-05-18T01:30:26.92422Z","end":"2026-05-18T01:30:27.211671Z","steps":["trace[358185277] 'agreement among raft nodes before linearized reading' (duration: 287.297284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.211725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:26.861582Z","time spent":"350.040301ms","remote":"127.0.0.1:43930","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-05-18T01:30:27.211917Z","caller":"traceutil/trace.go:171","msg":"trace[1400533008] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"351.804947ms","start":"2026-05-18T01:30:26.860087Z","end":"2026-05-18T01:30:27.211892Z","steps":["trace[1400533008] 'process raft request' (duration: 263.770438ms)","trace[1400533008] 'compare' (duration: 87.173896ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:27.212005Z","caller":"traceutil/trace.go:171","msg":"trace[1318521710] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"351.043408ms","start":"2026-05-18T01:30:26.860949Z","end":"2026-05-18T01:30:27.211993Z","steps":["trace[1318521710] 'process raft request' (duration: 350.221267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.212012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:26.860047Z","time spent":"351.920079ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:27.212077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:26.860916Z","time spent":"351.12167ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:27.276598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.437553ms","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-05-18T01:30:27.276596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.473122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:27.276658Z","caller":"traceutil/trace.go:171","msg":"trace[757062957] 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:25376; }","duration":"117.505215ms","start":"2026-05-18T01:30:27.159138Z","end":"2026-05-18T01:30:27.276643Z","steps":["trace[757062957] 'agreement among raft nodes before linearized reading' (duration: 117.391972ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:27.27667Z","caller":"traceutil/trace.go:171","msg":"trace[2027019252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25376; }","duration":"241.584334ms","start":"2026-05-18T01:30:27.035067Z","end":"2026-05-18T01:30:27.276651Z","steps":["trace[2027019252] 'agreement among raft nodes before linearized reading' (duration: 241.481401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.276882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.559201ms","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-05-18T01:30:27.277035Z","caller":"traceutil/trace.go:171","msg":"trace[1354621284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25376; }","duration":"325.768476ms","start":"2026-05-18T01:30:26.951242Z","end":"2026-05-18T01:30:27.277011Z","steps":["trace[1354621284] 'agreement among raft nodes before linearized reading' (duration: 325.361636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.277144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:26.951197Z","time spent":"325.93391ms","remote":"127.0.0.1:43930","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":"info","ts":"2026-05-18T01:30:27.42384Z","caller":"traceutil/trace.go:171","msg":"trace[1841569248] linearizableReadLoop","detail":"{readStateIndex:26208; appliedIndex:26206; }","duration":"147.291748ms","start":"2026-05-18T01:30:27.276516Z","end":"2026-05-18T01:30:27.423808Z","steps":["trace[1841569248] 'read index received' (duration: 147.102293ms)","trace[1841569248] 'applied index is now lower than readState.Index' (duration: 187.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:27.423968Z","caller":"traceutil/trace.go:171","msg":"trace[250233068] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"206.703902ms","start":"2026-05-18T01:30:27.217218Z","end":"2026-05-18T01:30:27.423922Z","steps":["trace[250233068] 'process raft request' (duration: 206.326143ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:27.424004Z","caller":"traceutil/trace.go:171","msg":"trace[19562694] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"173.463402ms","start":"2026-05-18T01:30:27.250527Z","end":"2026-05-18T01:30:27.42399Z","steps":["trace[19562694] 'process raft request' (duration: 173.222856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.424236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.222631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:27.424313Z","caller":"traceutil/trace.go:171","msg":"trace[1376418592] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25378; }","duration":"148.406897ms","start":"2026-05-18T01:30:27.275887Z","end":"2026-05-18T01:30:27.424294Z","steps":["trace[1376418592] 'agreement among raft nodes before linearized reading' (duration: 148.185621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.431607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.15595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:27.43166Z","caller":"traceutil/trace.go:171","msg":"trace[381774171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25379; }","duration":"154.210602ms","start":"2026-05-18T01:30:27.277437Z","end":"2026-05-18T01:30:27.431648Z","steps":["trace[381774171] 'agreement among raft nodes before linearized reading' (duration: 154.103069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.431962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.111024ms","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-05-18T01:30:27.432006Z","caller":"traceutil/trace.go:171","msg":"trace[903917781] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"150.428787ms","start":"2026-05-18T01:30:27.281559Z","end":"2026-05-18T01:30:27.431987Z","steps":["trace[903917781] 'process raft request' (duration: 149.835442ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:27.432065Z","caller":"traceutil/trace.go:171","msg":"trace[1461270298] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25379; }","duration":"149.261197ms","start":"2026-05-18T01:30:27.282781Z","end":"2026-05-18T01:30:27.432042Z","steps":["trace[1461270298] 'agreement among raft nodes before linearized reading' (duration: 148.908269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:27.715494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.481368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:30:27.716127Z","caller":"traceutil/trace.go:171","msg":"trace[866683950] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"244.519348ms","start":"2026-05-18T01:30:27.471562Z","end":"2026-05-18T01:30:27.71607Z","steps":["trace[866683950] 'process raft request' (duration: 107.27298ms)","trace[866683950] 'compare' (duration: 135.793211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:27.993834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.607332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10853"} {"level":"info","ts":"2026-05-18T01:30:27.993972Z","caller":"traceutil/trace.go:171","msg":"trace[1898990566] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25380; }","duration":"166.768256ms","start":"2026-05-18T01:30:27.827149Z","end":"2026-05-18T01:30:27.993917Z","steps":["trace[1898990566] 'range keys from in-memory index tree' (duration: 166.399996ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:33.338554Z","caller":"traceutil/trace.go:171","msg":"trace[1485673726] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"100.838569ms","start":"2026-05-18T01:30:33.237683Z","end":"2026-05-18T01:30:33.338522Z","steps":["trace[1485673726] 'process raft request' (duration: 100.166191ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:33.338668Z","caller":"traceutil/trace.go:171","msg":"trace[1992642920] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"100.595002ms","start":"2026-05-18T01:30:33.238028Z","end":"2026-05-18T01:30:33.338623Z","steps":["trace[1992642920] 'process raft request' (duration: 99.954195ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:33.55903Z","caller":"traceutil/trace.go:171","msg":"trace[927327137] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"166.94784ms","start":"2026-05-18T01:30:33.392056Z","end":"2026-05-18T01:30:33.559004Z","steps":["trace[927327137] 'process raft request' (duration: 166.776475ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:33.583202Z","caller":"traceutil/trace.go:171","msg":"trace[825194436] linearizableReadLoop","detail":"{readStateIndex:26263; appliedIndex:26261; }","duration":"132.12436ms","start":"2026-05-18T01:30:33.451048Z","end":"2026-05-18T01:30:33.583173Z","steps":["trace[825194436] 'read index received' (duration: 107.767181ms)","trace[825194436] 'applied index is now lower than readState.Index' (duration: 24.355499ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:33.583253Z","caller":"traceutil/trace.go:171","msg":"trace[1291365317] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"133.302549ms","start":"2026-05-18T01:30:33.449903Z","end":"2026-05-18T01:30:33.583205Z","steps":["trace[1291365317] 'process raft request' (duration: 133.099064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:33.583597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.560866ms","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-05-18T01:30:33.583646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.57015ms","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-05-18T01:30:33.583667Z","caller":"traceutil/trace.go:171","msg":"trace[1906187812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25432; }","duration":"125.72091ms","start":"2026-05-18T01:30:33.457926Z","end":"2026-05-18T01:30:33.583647Z","steps":["trace[1906187812] 'agreement among raft nodes before linearized reading' (duration: 125.462364ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:33.583702Z","caller":"traceutil/trace.go:171","msg":"trace[783330871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25432; }","duration":"132.681853ms","start":"2026-05-18T01:30:33.451005Z","end":"2026-05-18T01:30:33.583687Z","steps":["trace[783330871] 'agreement among raft nodes before linearized reading' (duration: 132.331295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:34.793804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.156615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-18T01:30:34.793874Z","caller":"traceutil/trace.go:171","msg":"trace[1548374429] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25439; }","duration":"173.283748ms","start":"2026-05-18T01:30:34.620572Z","end":"2026-05-18T01:30:34.793855Z","steps":["trace[1548374429] 'range keys from in-memory index tree' (duration: 173.00004ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:35.18036Z","caller":"traceutil/trace.go:171","msg":"trace[816523374] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"183.663666ms","start":"2026-05-18T01:30:34.996666Z","end":"2026-05-18T01:30:35.18033Z","steps":["trace[816523374] 'process raft request' (duration: 183.472501ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:35.180859Z","caller":"traceutil/trace.go:171","msg":"trace[164829000] linearizableReadLoop","detail":"{readStateIndex:26274; appliedIndex:26274; }","duration":"144.752685ms","start":"2026-05-18T01:30:35.036094Z","end":"2026-05-18T01:30:35.180847Z","steps":["trace[164829000] 'read index received' (duration: 144.731704ms)","trace[164829000] 'applied index is now lower than readState.Index' (duration: 19.651µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:35.18095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.839497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:35.180974Z","caller":"traceutil/trace.go:171","msg":"trace[505360899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25442; }","duration":"144.902029ms","start":"2026-05-18T01:30:35.036064Z","end":"2026-05-18T01:30:35.180967Z","steps":["trace[505360899] 'agreement among raft nodes before linearized reading' (duration: 144.820857ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:39.029373Z","caller":"traceutil/trace.go:171","msg":"trace[1744350454] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"137.217637ms","start":"2026-05-18T01:30:38.89212Z","end":"2026-05-18T01:30:39.029338Z","steps":["trace[1744350454] 'process raft request' (duration: 137.003052ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:41.16588Z","caller":"traceutil/trace.go:171","msg":"trace[1564679300] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26314; }","duration":"126.444257ms","start":"2026-05-18T01:30:41.039412Z","end":"2026-05-18T01:30:41.165857Z","steps":["trace[1564679300] 'read index received' (duration: 126.266513ms)","trace[1564679300] 'applied index is now lower than readState.Index' (duration: 176.724µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:41.165954Z","caller":"traceutil/trace.go:171","msg":"trace[655676742] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"128.756566ms","start":"2026-05-18T01:30:41.037155Z","end":"2026-05-18T01:30:41.165912Z","steps":["trace[655676742] 'process raft request' (duration: 128.52342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:41.16616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.712024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:41.166286Z","caller":"traceutil/trace.go:171","msg":"trace[2109971676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25482; }","duration":"126.96236ms","start":"2026-05-18T01:30:41.039293Z","end":"2026-05-18T01:30:41.166256Z","steps":["trace[2109971676] 'agreement among raft nodes before linearized reading' (duration: 126.711294ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:41.174298Z","caller":"traceutil/trace.go:171","msg":"trace[727921990] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"107.974847ms","start":"2026-05-18T01:30:41.06629Z","end":"2026-05-18T01:30:41.174264Z","steps":["trace[727921990] 'process raft request' (duration: 107.735541ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:56.65374Z","caller":"traceutil/trace.go:171","msg":"trace[1720928593] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"115.010233ms","start":"2026-05-18T01:30:56.538699Z","end":"2026-05-18T01:30:56.65371Z","steps":["trace[1720928593] 'process raft request' (duration: 114.740996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:56.953055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.136072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:30:56.953402Z","caller":"traceutil/trace.go:171","msg":"trace[1940209410] linearizableReadLoop","detail":"{readStateIndex:26449; appliedIndex:26446; }","duration":"275.373757ms","start":"2026-05-18T01:30:56.677986Z","end":"2026-05-18T01:30:56.95336Z","steps":["trace[1940209410] 'read index received' (duration: 51.825705ms)","trace[1940209410] 'applied index is now lower than readState.Index' (duration: 223.546732ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:56.953495Z","caller":"traceutil/trace.go:171","msg":"trace[601877046] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"295.220932ms","start":"2026-05-18T01:30:56.658179Z","end":"2026-05-18T01:30:56.9534Z","steps":["trace[601877046] 'process raft request' (duration: 71.614229ms)","trace[601877046] 'compare' (duration: 222.730102ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:56.953693Z","caller":"traceutil/trace.go:171","msg":"trace[894470790] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"293.10199ms","start":"2026-05-18T01:30:56.660556Z","end":"2026-05-18T01:30:56.953658Z","steps":["trace[894470790] 'process raft request' (duration: 292.759981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:56.953629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.638174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-05-18T01:30:56.953775Z","caller":"traceutil/trace.go:171","msg":"trace[1024246196] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"294.845324ms","start":"2026-05-18T01:30:56.658851Z","end":"2026-05-18T01:30:56.953696Z","steps":["trace[1024246196] 'process raft request' (duration: 294.347271ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:56.953848Z","caller":"traceutil/trace.go:171","msg":"trace[97329362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25613; }","duration":"275.861668ms","start":"2026-05-18T01:30:56.677911Z","end":"2026-05-18T01:30:56.953773Z","steps":["trace[97329362] 'agreement among raft nodes before linearized reading' (duration: 275.53431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:57.322099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.616892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:57.322213Z","caller":"traceutil/trace.go:171","msg":"trace[1160629160] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25613; }","duration":"118.820087ms","start":"2026-05-18T01:30:57.203368Z","end":"2026-05-18T01:30:57.322188Z","steps":["trace[1160629160] 'count revisions from in-memory index tree' (duration: 118.51319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:57.322369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:30:57.322454Z","caller":"traceutil/trace.go:171","msg":"trace[1101866522] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25613; }","duration":"168.758634ms","start":"2026-05-18T01:30:57.153675Z","end":"2026-05-18T01:30:57.322433Z","steps":["trace[1101866522] 'count revisions from in-memory index tree' (duration: 168.466847ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:57.376274Z","caller":"traceutil/trace.go:171","msg":"trace[322908481] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"135.759721ms","start":"2026-05-18T01:30:57.240472Z","end":"2026-05-18T01:30:57.376232Z","steps":["trace[322908481] 'process raft request' (duration: 135.550975ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:57.566692Z","caller":"traceutil/trace.go:171","msg":"trace[2024758338] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"137.094244ms","start":"2026-05-18T01:30:57.429555Z","end":"2026-05-18T01:30:57.566649Z","steps":["trace[2024758338] 'process raft request' (duration: 104.740697ms)","trace[2024758338] 'compare' (duration: 31.947097ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:30:57.821181Z","caller":"traceutil/trace.go:171","msg":"trace[1050964147] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"371.281262ms","start":"2026-05-18T01:30:57.449866Z","end":"2026-05-18T01:30:57.821147Z","steps":["trace[1050964147] 'process raft request' (duration: 311.449888ms)","trace[1050964147] 'compare' (duration: 59.64663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:57.82135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:57.449834Z","time spent":"371.416406ms","remote":"127.0.0.1:43930","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-05-18T01:30:57.821362Z","caller":"traceutil/trace.go:171","msg":"trace[269634026] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"352.298908ms","start":"2026-05-18T01:30:57.469046Z","end":"2026-05-18T01:30:57.821345Z","steps":["trace[269634026] 'process raft request' (duration: 352.064643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:57.821445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:57.469007Z","time spent":"352.391281ms","remote":"127.0.0.1:43930","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-05-18T01:30:57.821621Z","caller":"traceutil/trace.go:171","msg":"trace[947290963] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"144.97936ms","start":"2026-05-18T01:30:57.676635Z","end":"2026-05-18T01:30:57.821615Z","steps":["trace[947290963] 'process raft request' (duration: 144.952349ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:57.821703Z","caller":"traceutil/trace.go:171","msg":"trace[346058939] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"350.558095ms","start":"2026-05-18T01:30:57.471132Z","end":"2026-05-18T01:30:57.82169Z","steps":["trace[346058939] 'process raft request' (duration: 350.159815ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:57.821728Z","caller":"traceutil/trace.go:171","msg":"trace[871532444] linearizableReadLoop","detail":"{readStateIndex:26455; appliedIndex:26451; }","duration":"321.026637ms","start":"2026-05-18T01:30:57.500693Z","end":"2026-05-18T01:30:57.82172Z","steps":["trace[871532444] 'read index received' (duration: 33.559048ms)","trace[871532444] 'applied index is now lower than readState.Index' (duration: 287.466459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:30:57.821777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:57.471111Z","time spent":"350.617787ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:30:57.821844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.1632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-05-18T01:30:57.821862Z","caller":"traceutil/trace.go:171","msg":"trace[1866066680] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:25620; }","duration":"321.183321ms","start":"2026-05-18T01:30:57.500674Z","end":"2026-05-18T01:30:57.821857Z","steps":["trace[1866066680] 'agreement among raft nodes before linearized reading' (duration: 321.115989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:57.82188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:57.50059Z","time spent":"321.285273ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":203,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-18T01:30:57.822062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.414613ms","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-05-18T01:30:57.822144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.93194ms","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-05-18T01:30:57.822175Z","caller":"traceutil/trace.go:171","msg":"trace[364049010] 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:25620; }","duration":"308.556006ms","start":"2026-05-18T01:30:57.513581Z","end":"2026-05-18T01:30:57.822137Z","steps":["trace[364049010] 'agreement among raft nodes before linearized reading' (duration: 308.387742ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:30:57.822197Z","caller":"traceutil/trace.go:171","msg":"trace[1423752779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25620; }","duration":"103.020932ms","start":"2026-05-18T01:30:57.71916Z","end":"2026-05-18T01:30:57.822181Z","steps":["trace[1423752779] 'agreement among raft nodes before linearized reading' (duration: 102.813187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:57.822234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:30:57.513564Z","time spent":"308.657669ms","remote":"127.0.0.1:57144","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-05-18T01:30:57.822113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.57834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:30:57.822303Z","caller":"traceutil/trace.go:171","msg":"trace[1501416645] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25620; }","duration":"128.805266ms","start":"2026-05-18T01:30:57.693489Z","end":"2026-05-18T01:30:57.822295Z","steps":["trace[1501416645] 'agreement among raft nodes before linearized reading' (duration: 128.596351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:30:57.823413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.035708ms","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-05-18T01:30:57.823467Z","caller":"traceutil/trace.go:171","msg":"trace[1833905406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25620; }","duration":"104.099919ms","start":"2026-05-18T01:30:57.719356Z","end":"2026-05-18T01:30:57.823456Z","steps":["trace[1833905406] 'agreement among raft nodes before linearized reading' (duration: 102.646913ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:03.192514Z","caller":"traceutil/trace.go:171","msg":"trace[2057901487] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"210.239071ms","start":"2026-05-18T01:31:02.982241Z","end":"2026-05-18T01:31:03.19248Z","steps":["trace[2057901487] 'process raft request' (duration: 139.003872ms)","trace[2057901487] 'compare' (duration: 70.923421ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:31:03.192704Z","caller":"traceutil/trace.go:171","msg":"trace[1603653752] linearizableReadLoop","detail":"{readStateIndex:26492; appliedIndex:26489; }","duration":"157.376541ms","start":"2026-05-18T01:31:03.035291Z","end":"2026-05-18T01:31:03.192667Z","steps":["trace[1603653752] 'read index received' (duration: 8.916384ms)","trace[1603653752] 'applied index is now lower than readState.Index' (duration: 148.458007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:31:03.192921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.621328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:31:03.19303Z","caller":"traceutil/trace.go:171","msg":"trace[1747715344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25655; }","duration":"157.780611ms","start":"2026-05-18T01:31:03.035227Z","end":"2026-05-18T01:31:03.193008Z","steps":["trace[1747715344] 'agreement among raft nodes before linearized reading' (duration: 157.588356ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:03.192946Z","caller":"traceutil/trace.go:171","msg":"trace[225613829] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"209.957804ms","start":"2026-05-18T01:31:02.98277Z","end":"2026-05-18T01:31:03.192728Z","steps":["trace[225613829] 'process raft request' (duration: 209.656435ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:04.010989Z","caller":"traceutil/trace.go:171","msg":"trace[954935563] linearizableReadLoop","detail":"{readStateIndex:26501; appliedIndex:26496; }","duration":"154.835528ms","start":"2026-05-18T01:31:03.856125Z","end":"2026-05-18T01:31:04.010961Z","steps":["trace[954935563] 'read index received' (duration: 48.995634ms)","trace[954935563] 'applied index is now lower than readState.Index' (duration: 105.838294ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:31:04.011196Z","caller":"traceutil/trace.go:171","msg":"trace[1731687673] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"164.415157ms","start":"2026-05-18T01:31:03.84674Z","end":"2026-05-18T01:31:04.011155Z","steps":["trace[1731687673] 'process raft request' (duration: 164.14133ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:04.011214Z","caller":"traceutil/trace.go:171","msg":"trace[1868616695] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"157.140815ms","start":"2026-05-18T01:31:03.854056Z","end":"2026-05-18T01:31:04.011197Z","steps":["trace[1868616695] 'process raft request' (duration: 156.862058ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:04.011473Z","caller":"traceutil/trace.go:171","msg":"trace[195603356] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"164.775185ms","start":"2026-05-18T01:31:03.846654Z","end":"2026-05-18T01:31:04.01143Z","steps":["trace[195603356] 'process raft request' (duration: 164.175301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:31:04.011614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.190496ms","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-05-18T01:31:04.011474Z","caller":"traceutil/trace.go:171","msg":"trace[224159101] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"165.048392ms","start":"2026-05-18T01:31:03.84634Z","end":"2026-05-18T01:31:04.011388Z","steps":["trace[224159101] 'process raft request' (duration: 164.238822ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:04.011666Z","caller":"traceutil/trace.go:171","msg":"trace[1154119961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25664; }","duration":"155.295809ms","start":"2026-05-18T01:31:03.856356Z","end":"2026-05-18T01:31:04.011652Z","steps":["trace[1154119961] 'agreement among raft nodes before linearized reading' (duration: 155.155715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:31:04.012098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.966936ms","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-05-18T01:31:04.012153Z","caller":"traceutil/trace.go:171","msg":"trace[1740425727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25664; }","duration":"156.062018ms","start":"2026-05-18T01:31:03.856077Z","end":"2026-05-18T01:31:04.012139Z","steps":["trace[1740425727] 'agreement among raft nodes before linearized reading' (duration: 155.967336ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:31.591676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23031} {"level":"info","ts":"2026-05-18T01:31:31.726015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23031,"took":"128.775595ms","hash":978379811,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":35172352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-18T01:31:31.726107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":978379811,"revision":23031,"compact-revision":20113} {"level":"info","ts":"2026-05-18T01:32:07.413117Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a27.wal"} {"level":"info","ts":"2026-05-18T01:34:53.184126Z","caller":"traceutil/trace.go:171","msg":"trace[407586072] transaction","detail":"{read_only:false; response_revision:27977; number_of_response:1; }","duration":"141.602697ms","start":"2026-05-18T01:34:53.042493Z","end":"2026-05-18T01:34:53.184096Z","steps":["trace[407586072] 'process raft request' (duration: 139.184026ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:34:53.184401Z","caller":"traceutil/trace.go:171","msg":"trace[970774845] transaction","detail":"{read_only:false; response_revision:27978; number_of_response:1; }","duration":"141.032945ms","start":"2026-05-18T01:34:53.043323Z","end":"2026-05-18T01:34:53.184356Z","steps":["trace[970774845] 'process raft request' (duration: 140.727378ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:34:53.184483Z","caller":"traceutil/trace.go:171","msg":"trace[385474764] transaction","detail":"{read_only:false; response_revision:27979; number_of_response:1; }","duration":"139.40026ms","start":"2026-05-18T01:34:53.045043Z","end":"2026-05-18T01:34:53.184443Z","steps":["trace[385474764] 'process raft request' (duration: 139.221936ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:36:31.599496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26013} {"level":"info","ts":"2026-05-18T01:36:31.749626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26013,"took":"144.923815ms","hash":4068523621,"current-db-size-bytes":58855424,"current-db-size":"59 MB","current-db-size-in-use-bytes":35008512,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-18T01:36:31.749713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4068523621,"revision":26013,"compact-revision":23031} {"level":"info","ts":"2026-05-18T01:37:15.276548Z","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-05-18T01:37:15.282037Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-18T01:37:15.282133Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-18T01:41:31.606967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28754} {"level":"info","ts":"2026-05-18T01:41:31.753132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28754,"took":"140.961005ms","hash":3292036588,"current-db-size-bytes":58855424,"current-db-size":"59 MB","current-db-size-in-use-bytes":30109696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T01:41:31.753214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3292036588,"revision":28754,"compact-revision":26013} {"level":"info","ts":"2026-05-18T01:46:31.614305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30974} {"level":"info","ts":"2026-05-18T01:46:31.731028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30974,"took":"112.443033ms","hash":1403869725,"current-db-size-bytes":58855424,"current-db-size":"59 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T01:46:31.731114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1403869725,"revision":30974,"compact-revision":28754} {"level":"info","ts":"2026-05-18T01:51:31.62134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33240} {"level":"info","ts":"2026-05-18T01:51:31.774182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33240,"took":"146.91205ms","hash":3397118060,"current-db-size-bytes":59899904,"current-db-size":"60 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:51:31.774365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3397118060,"revision":33240,"compact-revision":30974} {"level":"info","ts":"2026-05-18T01:52:01.105677Z","caller":"traceutil/trace.go:171","msg":"trace[1043694637] linearizableReadLoop","detail":"{readStateIndex:36870; appliedIndex:36869; }","duration":"307.56052ms","start":"2026-05-18T01:52:00.798084Z","end":"2026-05-18T01:52:01.105644Z","steps":["trace[1043694637] 'read index received' (duration: 305.633485ms)","trace[1043694637] 'applied index is now lower than readState.Index' (duration: 1.925195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:52:01.106147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.03066ms","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-05-18T01:52:01.106238Z","caller":"traceutil/trace.go:171","msg":"trace[2063363491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:35750; }","duration":"308.180884ms","start":"2026-05-18T01:52:00.798037Z","end":"2026-05-18T01:52:01.106218Z","steps":["trace[2063363491] 'agreement among raft nodes before linearized reading' (duration: 307.915948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:01.106275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:00.798016Z","time spent":"308.252385ms","remote":"127.0.0.1:43930","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":"info","ts":"2026-05-18T01:52:01.106307Z","caller":"traceutil/trace.go:171","msg":"trace[1050486851] transaction","detail":"{read_only:false; response_revision:35750; number_of_response:1; }","duration":"351.621489ms","start":"2026-05-18T01:52:00.754642Z","end":"2026-05-18T01:52:01.106264Z","steps":["trace[1050486851] 'process raft request' (duration: 349.159672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:01.106495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:00.754615Z","time spent":"351.759962ms","remote":"127.0.0.1:43930","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-05-18T01:52:01.10697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.228967ms","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-05-18T01:52:01.107075Z","caller":"traceutil/trace.go:171","msg":"trace[1212622812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:35750; }","duration":"296.370211ms","start":"2026-05-18T01:52:00.810681Z","end":"2026-05-18T01:52:01.107051Z","steps":["trace[1212622812] 'agreement among raft nodes before linearized reading' (duration: 295.300525ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:41.745162Z","caller":"traceutil/trace.go:171","msg":"trace[1520092196] linearizableReadLoop","detail":"{readStateIndex:37171; appliedIndex:37170; }","duration":"396.63123ms","start":"2026-05-18T01:52:41.348503Z","end":"2026-05-18T01:52:41.745134Z","steps":["trace[1520092196] 'read index received' (duration: 396.358324ms)","trace[1520092196] 'applied index is now lower than readState.Index' (duration: 271.726µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:52:41.745225Z","caller":"traceutil/trace.go:171","msg":"trace[1122763698] transaction","detail":"{read_only:false; response_revision:36043; number_of_response:1; }","duration":"397.584583ms","start":"2026-05-18T01:52:41.347599Z","end":"2026-05-18T01:52:41.745184Z","steps":["trace[1122763698] 'process raft request' (duration: 397.286707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.745401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.865916ms","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-05-18T01:52:41.745721Z","caller":"traceutil/trace.go:171","msg":"trace[1551364253] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:36043; }","duration":"397.228974ms","start":"2026-05-18T01:52:41.348468Z","end":"2026-05-18T01:52:41.745697Z","steps":["trace[1551364253] 'agreement among raft nodes before linearized reading' (duration: 396.844175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.745828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.348455Z","time spent":"397.352088ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-18T01:52:41.745416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.347574Z","time spent":"397.733937ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:52:41.80524Z","caller":"traceutil/trace.go:171","msg":"trace[962392944] transaction","detail":"{read_only:false; response_revision:36044; number_of_response:1; }","duration":"454.264966ms","start":"2026-05-18T01:52:41.350947Z","end":"2026-05-18T01:52:41.805211Z","steps":["trace[962392944] 'process raft request' (duration: 433.242359ms)","trace[962392944] 'compare' (duration: 20.830913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:52:41.805398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.350933Z","time spent":"454.376228ms","remote":"127.0.0.1:43930","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-05-18T01:52:41.805492Z","caller":"traceutil/trace.go:171","msg":"trace[346718830] transaction","detail":"{read_only:false; response_revision:36045; number_of_response:1; }","duration":"450.504789ms","start":"2026-05-18T01:52:41.354946Z","end":"2026-05-18T01:52:41.80545Z","steps":["trace[346718830] 'process raft request' (duration: 450.225182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.805699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.354937Z","time spent":"450.653002ms","remote":"127.0.0.1:43930","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-05-18T01:52:41.805731Z","caller":"traceutil/trace.go:171","msg":"trace[55180245] transaction","detail":"{read_only:false; response_revision:36046; number_of_response:1; }","duration":"445.927942ms","start":"2026-05-18T01:52:41.359786Z","end":"2026-05-18T01:52:41.805713Z","steps":["trace[55180245] 'process raft request' (duration: 445.574534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.805878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.359771Z","time spent":"446.060614ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:52:41.805899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.976134ms","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-05-18T01:52:41.80593Z","caller":"traceutil/trace.go:171","msg":"trace[140666412] 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:36049; }","duration":"195.053255ms","start":"2026-05-18T01:52:41.610866Z","end":"2026-05-18T01:52:41.80592Z","steps":["trace[140666412] 'agreement among raft nodes before linearized reading' (duration: 194.994034ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:41.806085Z","caller":"traceutil/trace.go:171","msg":"trace[425218050] transaction","detail":"{read_only:false; response_revision:36047; number_of_response:1; }","duration":"345.346304ms","start":"2026-05-18T01:52:41.460729Z","end":"2026-05-18T01:52:41.806075Z","steps":["trace[425218050] 'process raft request' (duration: 344.699379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.80615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.460702Z","time spent":"345.419245ms","remote":"127.0.0.1:43930","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-05-18T01:52:41.80624Z","caller":"traceutil/trace.go:171","msg":"trace[1452801554] transaction","detail":"{read_only:false; response_revision:36048; number_of_response:1; }","duration":"340.513252ms","start":"2026-05-18T01:52:41.465716Z","end":"2026-05-18T01:52:41.806229Z","steps":["trace[1452801554] 'process raft request' (duration: 339.762805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.806284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.465682Z","time spent":"340.579174ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:52:41.80633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.402888ms","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-05-18T01:52:41.80651Z","caller":"traceutil/trace.go:171","msg":"trace[362999125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:36049; }","duration":"365.631263ms","start":"2026-05-18T01:52:41.440852Z","end":"2026-05-18T01:52:41.806483Z","steps":["trace[362999125] 'agreement among raft nodes before linearized reading' (duration: 365.253634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.806563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.440829Z","time spent":"365.720995ms","remote":"127.0.0.1:43930","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-05-18T01:52:41.806653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.618063ms","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-05-18T01:52:41.806704Z","caller":"traceutil/trace.go:171","msg":"trace[2138002355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:36049; }","duration":"371.724165ms","start":"2026-05-18T01:52:41.434968Z","end":"2026-05-18T01:52:41.806692Z","steps":["trace[2138002355] 'agreement among raft nodes before linearized reading' (duration: 370.898956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:41.806735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:41.434943Z","time spent":"371.784956ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T01:52:44.112867Z","caller":"traceutil/trace.go:171","msg":"trace[461468545] linearizableReadLoop","detail":"{readStateIndex:37192; appliedIndex:37187; }","duration":"268.549796ms","start":"2026-05-18T01:52:43.844292Z","end":"2026-05-18T01:52:44.112842Z","steps":["trace[461468545] 'read index received' (duration: 267.685756ms)","trace[461468545] 'applied index is now lower than readState.Index' (duration: 863.05µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:52:44.113143Z","caller":"traceutil/trace.go:171","msg":"trace[1959461651] transaction","detail":"{read_only:false; response_revision:36060; number_of_response:1; }","duration":"302.87458ms","start":"2026-05-18T01:52:43.810256Z","end":"2026-05-18T01:52:44.11313Z","steps":["trace[1959461651] 'process raft request' (duration: 301.806286ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:44.113259Z","caller":"traceutil/trace.go:171","msg":"trace[1034838447] transaction","detail":"{read_only:false; response_revision:36064; number_of_response:1; }","duration":"300.71418ms","start":"2026-05-18T01:52:43.812502Z","end":"2026-05-18T01:52:44.113216Z","steps":["trace[1034838447] 'process raft request' (duration: 300.297871ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:44.113331Z","caller":"traceutil/trace.go:171","msg":"trace[11974120] transaction","detail":"{read_only:false; response_revision:36062; number_of_response:1; }","duration":"301.5346ms","start":"2026-05-18T01:52:43.811769Z","end":"2026-05-18T01:52:44.113304Z","steps":["trace[11974120] 'process raft request' (duration: 300.936956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:44.113445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.15093ms","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-05-18T01:52:44.113487Z","caller":"traceutil/trace.go:171","msg":"trace[1866166743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:36064; }","duration":"269.219672ms","start":"2026-05-18T01:52:43.844257Z","end":"2026-05-18T01:52:44.113477Z","steps":["trace[1866166743] 'agreement among raft nodes before linearized reading' (duration: 269.076329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:44.113485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:43.811756Z","time spent":"301.647563ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:52:44.113486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:43.812474Z","time spent":"300.888266ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:52:44.113579Z","caller":"traceutil/trace.go:171","msg":"trace[412233990] transaction","detail":"{read_only:false; response_revision:36063; number_of_response:1; }","duration":"301.046989ms","start":"2026-05-18T01:52:43.812513Z","end":"2026-05-18T01:52:44.11356Z","steps":["trace[412233990] 'process raft request' (duration: 300.2507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:44.114345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:43.812495Z","time spent":"301.790976ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:52:44.113621Z","caller":"traceutil/trace.go:171","msg":"trace[1328613763] transaction","detail":"{read_only:false; response_revision:36061; number_of_response:1; }","duration":"302.228806ms","start":"2026-05-18T01:52:43.811382Z","end":"2026-05-18T01:52:44.113611Z","steps":["trace[1328613763] 'process raft request' (duration: 301.270694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:44.114614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:43.811356Z","time spent":"303.196378ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:52:44.11329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:52:43.810227Z","time spent":"302.953873ms","remote":"127.0.0.1:43930","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-05-18T01:52:44.113814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.166529ms","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-05-18T01:52:44.11483Z","caller":"traceutil/trace.go:171","msg":"trace[1498911890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:36064; }","duration":"263.212073ms","start":"2026-05-18T01:52:43.851599Z","end":"2026-05-18T01:52:44.114811Z","steps":["trace[1498911890] 'agreement among raft nodes before linearized reading' (duration: 262.088387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:52:44.113895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.49845ms","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-05-18T01:52:44.114951Z","caller":"traceutil/trace.go:171","msg":"trace[741692594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:36064; }","duration":"229.571044ms","start":"2026-05-18T01:52:43.885366Z","end":"2026-05-18T01:52:44.114937Z","steps":["trace[741692594] 'agreement among raft nodes before linearized reading' (duration: 228.475059ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:44.271301Z","caller":"traceutil/trace.go:171","msg":"trace[1733278738] transaction","detail":"{read_only:false; response_revision:36066; number_of_response:1; }","duration":"151.980868ms","start":"2026-05-18T01:52:44.11927Z","end":"2026-05-18T01:52:44.271251Z","steps":["trace[1733278738] 'process raft request' (duration: 141.671799ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:44.273475Z","caller":"traceutil/trace.go:171","msg":"trace[2004050336] transaction","detail":"{read_only:false; response_revision:36067; number_of_response:1; }","duration":"153.437952ms","start":"2026-05-18T01:52:44.119969Z","end":"2026-05-18T01:52:44.273407Z","steps":["trace[2004050336] 'process raft request' (duration: 151.053877ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:52:44.652559Z","caller":"traceutil/trace.go:171","msg":"trace[1873575483] transaction","detail":"{read_only:false; response_revision:36071; number_of_response:1; }","duration":"109.966345ms","start":"2026-05-18T01:52:44.542555Z","end":"2026-05-18T01:52:44.652521Z","steps":["trace[1873575483] 'process raft request' (duration: 38.112022ms)","trace[1873575483] 'compare' (duration: 71.368762ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:52:44.841819Z","caller":"traceutil/trace.go:171","msg":"trace[501204152] transaction","detail":"{read_only:false; response_revision:36072; number_of_response:1; }","duration":"198.227139ms","start":"2026-05-18T01:52:44.643549Z","end":"2026-05-18T01:52:44.841776Z","steps":["trace[501204152] 'process raft request' (duration: 155.866637ms)","trace[501204152] 'compare' (duration: 41.944741ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:56:31.628846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35536} {"level":"info","ts":"2026-05-18T01:56:31.778752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35536,"took":"143.065802ms","hash":3629363137,"current-db-size-bytes":60788736,"current-db-size":"61 MB","current-db-size-in-use-bytes":31272960,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T01:56:31.778841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3629363137,"revision":35536,"compact-revision":33240} {"level":"info","ts":"2026-05-18T01:58:45.547408Z","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-05-18T01:58:45.553923Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-18T01:58:45.554076Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-18T02:01:31.635827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37765} {"level":"info","ts":"2026-05-18T02:01:31.760469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37765,"took":"120.073799ms","hash":2137145637,"current-db-size-bytes":60788736,"current-db-size":"61 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T02:01:31.760576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2137145637,"revision":37765,"compact-revision":35536}