{"level":"warn","ts":"2026-03-19T07:19:23.530491Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T07:19:23.530572Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-03-19T07:19:23.530634Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T07:19:23.530649Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-19T07:19:23.530669Z","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-03-19T07:19:23.531321Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-03-19T07:19:23.531396Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-03-19T07:19:23.535909Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.304868ms"} {"level":"info","ts":"2026-03-19T07:19:23.542193Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-03-19T07:19:23.542284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T07:19:23.542308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-03-19T07:19:23.542324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T07:19:23.542333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-03-19T07:19:23.542376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-03-19T07:19:23.548519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T07:19:23.550148Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T07:19:23.552339Z","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-03-19T07:19:23.554845Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T07:19:23.555014Z","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-03-19T07:19:23.555182Z","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-03-19T07:19:23.5552Z","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-03-19T07:19:23.555451Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T07:19:23.557064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-03-19T07:19:23.557227Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-19T07:19:23.56159Z","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-03-19T07:19:23.561724Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-19T07:19:23.561797Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-19T07:19:23.561885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T07:19:23.561944Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T07:19:24.243246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T07:19:24.243321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T07:19:24.243386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-03-19T07:19:24.243407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-03-19T07:19:24.243418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-19T07:19:24.243434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-03-19T07:19:24.243448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-19T07:19:24.244663Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T07:19:24.244943Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T07:19:24.245074Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T07:19:24.244951Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T07:19:24.245528Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T07:19:24.245571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T07:19:24.248869Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-03-19T07:19:24.248931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T07:19:24.264146Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T07:19:24.264252Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T07:19:24.264272Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T07:19:45.107805Z","caller":"traceutil/trace.go:171","msg":"trace[65528138] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"115.529991ms","start":"2026-03-19T07:19:44.99225Z","end":"2026-03-19T07:19:45.10778Z","steps":["trace[65528138] 'process raft request' (duration: 115.384778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:19:50.844463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.193759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T07:19:50.844613Z","caller":"traceutil/trace.go:171","msg":"trace[2021975637] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"339.780545ms","start":"2026-03-19T07:19:50.504801Z","end":"2026-03-19T07:19:50.844582Z","steps":["trace[2021975637] 'process raft request' (duration: 182.866329ms)","trace[2021975637] 'compare' (duration: 155.899342ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:19:50.844699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:19:50.504782Z","time spent":"339.879198ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T07:19:50.928874Z","caller":"traceutil/trace.go:171","msg":"trace[1372873309] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"393.618025ms","start":"2026-03-19T07:19:50.535214Z","end":"2026-03-19T07:19:50.928832Z","steps":["trace[1372873309] 'process raft request' (duration: 393.446101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:19:50.929041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:19:50.535194Z","time spent":"393.772648ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T07:19:50.929264Z","caller":"traceutil/trace.go:171","msg":"trace[667889463] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"394.067225ms","start":"2026-03-19T07:19:50.535173Z","end":"2026-03-19T07:19:50.92924Z","steps":["trace[667889463] 'process raft request' (duration: 393.715067ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:50.929308Z","caller":"traceutil/trace.go:171","msg":"trace[1698542327] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"392.827506ms","start":"2026-03-19T07:19:50.536464Z","end":"2026-03-19T07:19:50.929292Z","steps":["trace[1698542327] 'process raft request' (duration: 392.765035ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:50.929381Z","caller":"traceutil/trace.go:171","msg":"trace[1029113499] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"393.551523ms","start":"2026-03-19T07:19:50.535816Z","end":"2026-03-19T07:19:50.929367Z","steps":["trace[1029113499] 'process raft request' (duration: 393.362899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:19:50.929509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:19:50.535782Z","time spent":"393.685906ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T07:19:50.929574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:19:50.535151Z","time spent":"394.163427ms","remote":"127.0.0.1:34100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T07:19:50.929686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:19:50.536457Z","time spent":"392.910398ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T07:19:50.950917Z","caller":"traceutil/trace.go:171","msg":"trace[1556499247] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"101.775447ms","start":"2026-03-19T07:19:50.849124Z","end":"2026-03-19T07:19:50.950899Z","steps":["trace[1556499247] 'process raft request' (duration: 96.624089ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:50.951159Z","caller":"traceutil/trace.go:171","msg":"trace[2046296208] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"101.674455ms","start":"2026-03-19T07:19:50.849451Z","end":"2026-03-19T07:19:50.951126Z","steps":["trace[2046296208] 'process raft request' (duration: 101.415149ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:58.559711Z","caller":"traceutil/trace.go:171","msg":"trace[1423610590] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"171.60228ms","start":"2026-03-19T07:19:58.38808Z","end":"2026-03-19T07:19:58.559682Z","steps":["trace[1423610590] 'read index received' (duration: 171.374545ms)","trace[1423610590] 'applied index is now lower than readState.Index' (duration: 226.735µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:19:58.559881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.753713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:19:58.559922Z","caller":"traceutil/trace.go:171","msg":"trace[410405748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"171.904456ms","start":"2026-03-19T07:19:58.388006Z","end":"2026-03-19T07:19:58.559911Z","steps":["trace[410405748] 'agreement among raft nodes before linearized reading' (duration: 171.783793ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:58.560039Z","caller":"traceutil/trace.go:171","msg":"trace[993420283] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"201.124944ms","start":"2026-03-19T07:19:58.358884Z","end":"2026-03-19T07:19:58.560009Z","steps":["trace[993420283] 'process raft request' (duration: 200.652474ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:59.406248Z","caller":"traceutil/trace.go:171","msg":"trace[1986412046] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"248.190963ms","start":"2026-03-19T07:19:59.158021Z","end":"2026-03-19T07:19:59.406212Z","steps":["trace[1986412046] 'process raft request' (duration: 248.05629ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:59.679549Z","caller":"traceutil/trace.go:171","msg":"trace[938449330] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"228.009212ms","start":"2026-03-19T07:19:59.451513Z","end":"2026-03-19T07:19:59.679522Z","steps":["trace[938449330] 'process raft request' (duration: 227.707455ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:19:59.679519Z","caller":"traceutil/trace.go:171","msg":"trace[96499419] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"152.037252ms","start":"2026-03-19T07:19:59.527454Z","end":"2026-03-19T07:19:59.679492Z","steps":["trace[96499419] 'read index received' (duration: 151.612163ms)","trace[96499419] 'applied index is now lower than readState.Index' (duration: 423.959µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:19:59.679831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.346778ms","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-03-19T07:19:59.679938Z","caller":"traceutil/trace.go:171","msg":"trace[746809400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"152.489682ms","start":"2026-03-19T07:19:59.527424Z","end":"2026-03-19T07:19:59.679914Z","steps":["trace[746809400] 'agreement among raft nodes before linearized reading' (duration: 152.230835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:21:11.970616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.850004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5435eb92-663a-4720-91b5-d7c4561899a4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-19T07:21:11.970715Z","caller":"traceutil/trace.go:171","msg":"trace[1399585870] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5435eb92-663a-4720-91b5-d7c4561899a4; range_end:; response_count:1; response_revision:1591; }","duration":"300.991138ms","start":"2026-03-19T07:21:11.669695Z","end":"2026-03-19T07:21:11.970686Z","steps":["trace[1399585870] 'range keys from in-memory index tree' (duration: 300.598749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:21:11.970771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:21:11.669676Z","time spent":"301.084279ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5435eb92-663a-4720-91b5-d7c4561899a4\" "} {"level":"info","ts":"2026-03-19T07:21:11.970861Z","caller":"traceutil/trace.go:171","msg":"trace[1820009811] linearizableReadLoop","detail":"{readStateIndex:1652; appliedIndex:1651; }","duration":"218.650266ms","start":"2026-03-19T07:21:11.752189Z","end":"2026-03-19T07:21:11.970839Z","steps":["trace[1820009811] 'read index received' (duration: 160.158158ms)","trace[1820009811] 'applied index is now lower than readState.Index' (duration: 58.491058ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:21:11.971038Z","caller":"traceutil/trace.go:171","msg":"trace[1550455403] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"274.785593ms","start":"2026-03-19T07:21:11.6962Z","end":"2026-03-19T07:21:11.970986Z","steps":["trace[1550455403] 'process raft request' (duration: 216.213884ms)","trace[1550455403] 'compare' (duration: 58.227172ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:21:11.971163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.955912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-03-19T07:21:11.971232Z","caller":"traceutil/trace.go:171","msg":"trace[1952041755] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1592; }","duration":"219.084905ms","start":"2026-03-19T07:21:11.752133Z","end":"2026-03-19T07:21:11.971218Z","steps":["trace[1952041755] 'agreement among raft nodes before linearized reading' (duration: 218.898361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:21:11.977757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.519312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T07:21:11.977829Z","caller":"traceutil/trace.go:171","msg":"trace[2086996352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1593; }","duration":"217.607144ms","start":"2026-03-19T07:21:11.7602Z","end":"2026-03-19T07:21:11.977807Z","steps":["trace[2086996352] 'agreement among raft nodes before linearized reading' (duration: 217.41865ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:21:12.272711Z","caller":"traceutil/trace.go:171","msg":"trace[2007116834] linearizableReadLoop","detail":"{readStateIndex:1661; appliedIndex:1660; }","duration":"143.599448ms","start":"2026-03-19T07:21:12.12909Z","end":"2026-03-19T07:21:12.272689Z","steps":["trace[2007116834] 'read index received' (duration: 143.407474ms)","trace[2007116834] 'applied index is now lower than readState.Index' (duration: 190.664µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:21:12.272803Z","caller":"traceutil/trace.go:171","msg":"trace[515605971] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"143.793111ms","start":"2026-03-19T07:21:12.128971Z","end":"2026-03-19T07:21:12.272764Z","steps":["trace[515605971] 'process raft request' (duration: 143.562957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:21:12.273131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.011716ms","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-03-19T07:21:12.273211Z","caller":"traceutil/trace.go:171","msg":"trace[392397363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1601; }","duration":"144.152889ms","start":"2026-03-19T07:21:12.129041Z","end":"2026-03-19T07:21:12.273194Z","steps":["trace[392397363] 'agreement among raft nodes before linearized reading' (duration: 143.781172ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:21:12.418147Z","caller":"traceutil/trace.go:171","msg":"trace[200684524] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"139.91959ms","start":"2026-03-19T07:21:12.278206Z","end":"2026-03-19T07:21:12.418125Z","steps":["trace[200684524] 'process raft request' (duration: 66.249921ms)","trace[200684524] 'compare' (duration: 73.514705ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:21:12.418647Z","caller":"traceutil/trace.go:171","msg":"trace[777730594] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"140.223376ms","start":"2026-03-19T07:21:12.278388Z","end":"2026-03-19T07:21:12.418612Z","steps":["trace[777730594] 'process raft request' (duration: 139.697545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:22:02.496943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.466206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T07:22:02.497064Z","caller":"traceutil/trace.go:171","msg":"trace[492957250] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"250.988726ms","start":"2026-03-19T07:22:02.246059Z","end":"2026-03-19T07:22:02.497048Z","steps":["trace[492957250] 'read index received' (duration: 121.348166ms)","trace[492957250] 'applied index is now lower than readState.Index' (duration: 129.63934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:22:02.497158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.108718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:22:02.497187Z","caller":"traceutil/trace.go:171","msg":"trace[722046857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"251.17977ms","start":"2026-03-19T07:22:02.245995Z","end":"2026-03-19T07:22:02.497175Z","steps":["trace[722046857] 'agreement among raft nodes before linearized reading' (duration: 251.133299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:22:02.497251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.770023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:22:02.497334Z","caller":"traceutil/trace.go:171","msg":"trace[362175742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"107.897205ms","start":"2026-03-19T07:22:02.389416Z","end":"2026-03-19T07:22:02.497313Z","steps":["trace[362175742] 'agreement among raft nodes before linearized reading' (duration: 107.776452ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:22:02.497125Z","caller":"traceutil/trace.go:171","msg":"trace[602787772] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"257.985363ms","start":"2026-03-19T07:22:02.239103Z","end":"2026-03-19T07:22:02.497088Z","steps":["trace[602787772] 'process raft request' (duration: 128.275922ms)","trace[602787772] 'compare' (duration: 129.368964ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:23:04.620533Z","caller":"traceutil/trace.go:171","msg":"trace[1400155221] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"169.911649ms","start":"2026-03-19T07:23:04.450584Z","end":"2026-03-19T07:23:04.620496Z","steps":["trace[1400155221] 'process raft request' (duration: 168.668954ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:23:39.192551Z","caller":"traceutil/trace.go:171","msg":"trace[243662316] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"135.412026ms","start":"2026-03-19T07:23:39.057093Z","end":"2026-03-19T07:23:39.192505Z","steps":["trace[243662316] 'process raft request' (duration: 113.071216ms)","trace[243662316] 'compare' (duration: 22.186447ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:25:54.91058Z","caller":"traceutil/trace.go:171","msg":"trace[2117099050] transaction","detail":"{read_only:false; response_revision:3408; number_of_response:1; }","duration":"135.555749ms","start":"2026-03-19T07:25:54.774995Z","end":"2026-03-19T07:25:54.910551Z","steps":["trace[2117099050] 'process raft request' (duration: 135.328524ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:26:00.254798Z","caller":"traceutil/trace.go:171","msg":"trace[753008773] transaction","detail":"{read_only:false; response_revision:3436; number_of_response:1; }","duration":"115.101767ms","start":"2026-03-19T07:26:00.139674Z","end":"2026-03-19T07:26:00.254776Z","steps":["trace[753008773] 'process raft request' (duration: 114.941154ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:26:00.277984Z","caller":"traceutil/trace.go:171","msg":"trace[1170803502] transaction","detail":"{read_only:false; response_revision:3437; number_of_response:1; }","duration":"115.110357ms","start":"2026-03-19T07:26:00.162836Z","end":"2026-03-19T07:26:00.277946Z","steps":["trace[1170803502] 'process raft request' (duration: 114.904683ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:20.682826Z","caller":"traceutil/trace.go:171","msg":"trace[737528246] linearizableReadLoop","detail":"{readStateIndex:4473; appliedIndex:4471; }","duration":"121.886166ms","start":"2026-03-19T07:27:20.560915Z","end":"2026-03-19T07:27:20.682801Z","steps":["trace[737528246] 'read index received' (duration: 107.575696ms)","trace[737528246] 'applied index is now lower than readState.Index' (duration: 14.30949ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:27:20.683132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.190242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-19T07:27:20.683229Z","caller":"traceutil/trace.go:171","msg":"trace[605243251] transaction","detail":"{read_only:false; response_revision:4302; number_of_response:1; }","duration":"127.929002ms","start":"2026-03-19T07:27:20.555216Z","end":"2026-03-19T07:27:20.683145Z","steps":["trace[605243251] 'process raft request' (duration: 113.295876ms)","trace[605243251] 'compare' (duration: 14.089355ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:27:20.683292Z","caller":"traceutil/trace.go:171","msg":"trace[131122796] transaction","detail":"{read_only:false; response_revision:4303; number_of_response:1; }","duration":"127.504604ms","start":"2026-03-19T07:27:20.555668Z","end":"2026-03-19T07:27:20.683172Z","steps":["trace[131122796] 'process raft request' (duration: 127.071855ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:20.683419Z","caller":"traceutil/trace.go:171","msg":"trace[354873631] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4303; }","duration":"122.264734ms","start":"2026-03-19T07:27:20.560899Z","end":"2026-03-19T07:27:20.683163Z","steps":["trace[354873631] 'agreement among raft nodes before linearized reading' (duration: 122.000838ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:20.688049Z","caller":"traceutil/trace.go:171","msg":"trace[177883843] transaction","detail":"{read_only:false; response_revision:4304; number_of_response:1; }","duration":"125.724556ms","start":"2026-03-19T07:27:20.562308Z","end":"2026-03-19T07:27:20.688033Z","steps":["trace[177883843] 'process raft request' (duration: 125.579012ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:26.090387Z","caller":"traceutil/trace.go:171","msg":"trace[591495972] transaction","detail":"{read_only:false; response_revision:4443; number_of_response:1; }","duration":"219.211955ms","start":"2026-03-19T07:27:25.871149Z","end":"2026-03-19T07:27:26.090361Z","steps":["trace[591495972] 'process raft request' (duration: 219.059592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:30.289084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.292168ms","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-03-19T07:27:30.289177Z","caller":"traceutil/trace.go:171","msg":"trace[794439741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4601; }","duration":"105.40227ms","start":"2026-03-19T07:27:30.183755Z","end":"2026-03-19T07:27:30.289158Z","steps":["trace[794439741] 'range keys from in-memory index tree' (duration: 105.132224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:30.289229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.65818ms","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-03-19T07:27:30.289329Z","caller":"traceutil/trace.go:171","msg":"trace[1743281778] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4601; }","duration":"245.767992ms","start":"2026-03-19T07:27:30.043546Z","end":"2026-03-19T07:27:30.289314Z","steps":["trace[1743281778] 'range keys from in-memory index tree' (duration: 245.64363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:30.289362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.429763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5ee28fb-3b3f-478f-8698-09e25fdca5d8\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-19T07:27:30.28946Z","caller":"traceutil/trace.go:171","msg":"trace[2063586046] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5ee28fb-3b3f-478f-8698-09e25fdca5d8; range_end:; response_count:1; response_revision:4601; }","duration":"181.581777ms","start":"2026-03-19T07:27:30.107854Z","end":"2026-03-19T07:27:30.289435Z","steps":["trace[2063586046] 'range keys from in-memory index tree' (duration: 181.238399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:30.289577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.109293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T07:27:30.289603Z","caller":"traceutil/trace.go:171","msg":"trace[2053029633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4601; }","duration":"116.175926ms","start":"2026-03-19T07:27:30.173421Z","end":"2026-03-19T07:27:30.289597Z","steps":["trace[2053029633] 'range keys from in-memory index tree' (duration: 115.973971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:30.289692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.906244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-19T07:27:30.289754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.343878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sgxdm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-19T07:27:30.289756Z","caller":"traceutil/trace.go:171","msg":"trace[1384484970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4601; }","duration":"110.015486ms","start":"2026-03-19T07:27:30.179726Z","end":"2026-03-19T07:27:30.289741Z","steps":["trace[1384484970] 'range keys from in-memory index tree' (duration: 109.757101ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:30.289771Z","caller":"traceutil/trace.go:171","msg":"trace[17385895] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sgxdm; range_end:; response_count:1; response_revision:4601; }","duration":"136.398069ms","start":"2026-03-19T07:27:30.153368Z","end":"2026-03-19T07:27:30.289766Z","steps":["trace[17385895] 'range keys from in-memory index tree' (duration: 136.242807ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:30.739947Z","caller":"traceutil/trace.go:171","msg":"trace[1317628533] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"132.848876ms","start":"2026-03-19T07:27:30.607028Z","end":"2026-03-19T07:27:30.739876Z","steps":["trace[1317628533] 'process raft request' (duration: 67.913845ms)","trace[1317628533] 'compare' (duration: 64.737277ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:27:30.748121Z","caller":"traceutil/trace.go:171","msg":"trace[1790103510] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"113.111472ms","start":"2026-03-19T07:27:30.634971Z","end":"2026-03-19T07:27:30.748082Z","steps":["trace[1790103510] 'process raft request' (duration: 112.545049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:40.101515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.292233ms","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":"warn","ts":"2026-03-19T07:27:40.101578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.080675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T07:27:40.101671Z","caller":"traceutil/trace.go:171","msg":"trace[850592906] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4979; }","duration":"392.807915ms","start":"2026-03-19T07:27:39.708852Z","end":"2026-03-19T07:27:40.101659Z","steps":["trace[850592906] 'read index received' (duration: 57.891µs)","trace[850592906] 'applied index is now lower than readState.Index' (duration: 392.749174ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:27:40.101606Z","caller":"traceutil/trace.go:171","msg":"trace[131174785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4794; }","duration":"620.446798ms","start":"2026-03-19T07:27:39.481142Z","end":"2026-03-19T07:27:40.101589Z","steps":["trace[131174785] 'range keys from in-memory index tree' (duration: 620.069438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:40.10192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:27:39.48112Z","time spent":"620.782644ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T07:27:40.101942Z","caller":"traceutil/trace.go:171","msg":"trace[2054688189] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"556.126999ms","start":"2026-03-19T07:27:39.545769Z","end":"2026-03-19T07:27:40.101896Z","steps":["trace[2054688189] 'compare' (duration: 551.630005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:40.102053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:27:39.545739Z","time spent":"556.256731ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T07:27:40.102094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.244764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-f56jd\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-03-19T07:27:40.102137Z","caller":"traceutil/trace.go:171","msg":"trace[1508607118] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-f56jd; range_end:; response_count:1; response_revision:4795; }","duration":"393.333356ms","start":"2026-03-19T07:27:39.708776Z","end":"2026-03-19T07:27:40.102109Z","steps":["trace[1508607118] 'agreement among raft nodes before linearized reading' (duration: 392.917178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:40.102163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:27:39.708745Z","time spent":"393.413099ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7842,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-f56jd\" "} {"level":"info","ts":"2026-03-19T07:27:47.936416Z","caller":"traceutil/trace.go:171","msg":"trace[1264641019] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"113.112849ms","start":"2026-03-19T07:27:47.823271Z","end":"2026-03-19T07:27:47.936384Z","steps":["trace[1264641019] 'process raft request' (duration: 112.928935ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:48.121137Z","caller":"traceutil/trace.go:171","msg":"trace[1462307834] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"176.502731ms","start":"2026-03-19T07:27:47.944611Z","end":"2026-03-19T07:27:48.121113Z","steps":["trace[1462307834] 'process raft request' (duration: 176.272827ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:48.121339Z","caller":"traceutil/trace.go:171","msg":"trace[1467531416] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5155; }","duration":"165.641556ms","start":"2026-03-19T07:27:47.955682Z","end":"2026-03-19T07:27:48.121324Z","steps":["trace[1467531416] 'read index received' (duration: 42.420698ms)","trace[1467531416] 'applied index is now lower than readState.Index' (duration: 123.219568ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:27:48.121588Z","caller":"traceutil/trace.go:171","msg":"trace[1706570748] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"175.598421ms","start":"2026-03-19T07:27:47.945911Z","end":"2026-03-19T07:27:48.12151Z","steps":["trace[1706570748] 'process raft request' (duration: 175.134671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:48.121666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.952332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-03-19T07:27:48.121745Z","caller":"traceutil/trace.go:171","msg":"trace[1081675492] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4969; }","duration":"166.075515ms","start":"2026-03-19T07:27:47.955647Z","end":"2026-03-19T07:27:48.121723Z","steps":["trace[1081675492] 'agreement among raft nodes before linearized reading' (duration: 165.82627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:48.122097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.550232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8afd8e2a-173b-46bf-b113-f3a79d695f4f\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-03-19T07:27:48.122345Z","caller":"traceutil/trace.go:171","msg":"trace[1271450060] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8afd8e2a-173b-46bf-b113-f3a79d695f4f; range_end:; response_count:1; response_revision:4969; }","duration":"165.314399ms","start":"2026-03-19T07:27:47.957005Z","end":"2026-03-19T07:27:48.122319Z","steps":["trace[1271450060] 'agreement among raft nodes before linearized reading' (duration: 164.541112ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:27:48.352219Z","caller":"traceutil/trace.go:171","msg":"trace[2111508547] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5159; }","duration":"158.984253ms","start":"2026-03-19T07:27:48.193203Z","end":"2026-03-19T07:27:48.352188Z","steps":["trace[2111508547] 'read index received' (duration: 98.603955ms)","trace[2111508547] 'applied index is now lower than readState.Index' (duration: 60.379038ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:27:48.352293Z","caller":"traceutil/trace.go:171","msg":"trace[447695357] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"161.277982ms","start":"2026-03-19T07:27:48.190965Z","end":"2026-03-19T07:27:48.352243Z","steps":["trace[447695357] 'process raft request' (duration: 100.868263ms)","trace[447695357] 'compare' (duration: 60.217635ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:27:48.352417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.195767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:27:48.352468Z","caller":"traceutil/trace.go:171","msg":"trace[1012960344] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4971; }","duration":"159.268648ms","start":"2026-03-19T07:27:48.193187Z","end":"2026-03-19T07:27:48.352456Z","steps":["trace[1012960344] 'agreement among raft nodes before linearized reading' (duration: 159.166536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:27:48.352823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.552089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189e2d71151b37eb\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-03-19T07:27:48.3529Z","caller":"traceutil/trace.go:171","msg":"trace[2002527128] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189e2d71151b37eb; range_end:; response_count:1; response_revision:4971; }","duration":"155.66393ms","start":"2026-03-19T07:27:48.197218Z","end":"2026-03-19T07:27:48.352882Z","steps":["trace[2002527128] 'agreement among raft nodes before linearized reading' (duration: 155.342184ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:29:24.284159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2785} {"level":"info","ts":"2026-03-19T07:29:24.349904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2785,"took":"63.975338ms","hash":1029415754,"current-db-size-bytes":41402368,"current-db-size":"41 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-19T07:29:24.35001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1029415754,"revision":2785,"compact-revision":-1} {"level":"warn","ts":"2026-03-19T07:33:56.226997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.35534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-03-19T07:33:56.227143Z","caller":"traceutil/trace.go:171","msg":"trace[1967723671] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7625; }","duration":"422.585934ms","start":"2026-03-19T07:33:55.804527Z","end":"2026-03-19T07:33:56.227112Z","steps":["trace[1967723671] 'range keys from in-memory index tree' (duration: 422.104244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:33:56.227206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:33:55.804505Z","time spent":"422.683366ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-19T07:33:56.237325Z","caller":"traceutil/trace.go:171","msg":"trace[2036502611] transaction","detail":"{read_only:false; response_revision:7626; number_of_response:1; }","duration":"105.954755ms","start":"2026-03-19T07:33:56.13134Z","end":"2026-03-19T07:33:56.237294Z","steps":["trace[2036502611] 'process raft request' (duration: 105.757271ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:33:56.23859Z","caller":"traceutil/trace.go:171","msg":"trace[1970964118] transaction","detail":"{read_only:false; response_revision:7627; number_of_response:1; }","duration":"106.531028ms","start":"2026-03-19T07:33:56.132037Z","end":"2026-03-19T07:33:56.238568Z","steps":["trace[1970964118] 'process raft request' (duration: 106.244182ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:34:08.911533Z","caller":"traceutil/trace.go:171","msg":"trace[895871329] linearizableReadLoop","detail":"{readStateIndex:7970; appliedIndex:7969; }","duration":"176.913362ms","start":"2026-03-19T07:34:08.734588Z","end":"2026-03-19T07:34:08.911502Z","steps":["trace[895871329] 'read index received' (duration: 176.691467ms)","trace[895871329] 'applied index is now lower than readState.Index' (duration: 220.505µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:34:08.911653Z","caller":"traceutil/trace.go:171","msg":"trace[1218158486] transaction","detail":"{read_only:false; response_revision:7692; number_of_response:1; }","duration":"234.612139ms","start":"2026-03-19T07:34:08.677001Z","end":"2026-03-19T07:34:08.911613Z","steps":["trace[1218158486] 'process raft request' (duration: 234.289262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:34:08.911852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.377604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sgxdm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-19T07:34:08.911905Z","caller":"traceutil/trace.go:171","msg":"trace[1449793148] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sgxdm; range_end:; response_count:1; response_revision:7692; }","duration":"106.479837ms","start":"2026-03-19T07:34:08.805414Z","end":"2026-03-19T07:34:08.911893Z","steps":["trace[1449793148] 'agreement among raft nodes before linearized reading' (duration: 106.272543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:34:08.911745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.122607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:34:08.911995Z","caller":"traceutil/trace.go:171","msg":"trace[1324011158] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:7692; }","duration":"177.407004ms","start":"2026-03-19T07:34:08.734568Z","end":"2026-03-19T07:34:08.911975Z","steps":["trace[1324011158] 'agreement among raft nodes before linearized reading' (duration: 177.045715ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:34:24.291706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5745} {"level":"info","ts":"2026-03-19T07:34:24.388953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5745,"took":"94.933703ms","hash":1873771520,"current-db-size-bytes":42119168,"current-db-size":"42 MB","current-db-size-in-use-bytes":22765568,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-19T07:34:24.389032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1873771520,"revision":5745,"compact-revision":2785} {"level":"info","ts":"2026-03-19T07:34:40.786303Z","caller":"traceutil/trace.go:171","msg":"trace[818322680] linearizableReadLoop","detail":"{readStateIndex:8186; appliedIndex:8185; }","duration":"164.057362ms","start":"2026-03-19T07:34:40.622204Z","end":"2026-03-19T07:34:40.786261Z","steps":["trace[818322680] 'read index received' (duration: 153.733904ms)","trace[818322680] 'applied index is now lower than readState.Index' (duration: 10.321658ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:34:40.786386Z","caller":"traceutil/trace.go:171","msg":"trace[106491138] transaction","detail":"{read_only:false; response_revision:7899; number_of_response:1; }","duration":"170.357474ms","start":"2026-03-19T07:34:40.615986Z","end":"2026-03-19T07:34:40.786343Z","steps":["trace[106491138] 'process raft request' (duration: 159.971095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:34:40.786695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.46143ms","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-03-19T07:34:40.786757Z","caller":"traceutil/trace.go:171","msg":"trace[1154784229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7899; }","duration":"164.563562ms","start":"2026-03-19T07:34:40.622172Z","end":"2026-03-19T07:34:40.786736Z","steps":["trace[1154784229] 'agreement among raft nodes before linearized reading' (duration: 164.337107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:34:40.786831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.803158ms","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-03-19T07:34:40.78695Z","caller":"traceutil/trace.go:171","msg":"trace[984676417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7899; }","duration":"147.975361ms","start":"2026-03-19T07:34:40.638944Z","end":"2026-03-19T07:34:40.786919Z","steps":["trace[984676417] 'agreement among raft nodes before linearized reading' (duration: 147.618154ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:34:41.058425Z","caller":"traceutil/trace.go:171","msg":"trace[759224776] transaction","detail":"{read_only:false; response_revision:7900; number_of_response:1; }","duration":"266.603695ms","start":"2026-03-19T07:34:40.791794Z","end":"2026-03-19T07:34:41.058397Z","steps":["trace[759224776] 'process raft request' (duration: 233.538897ms)","trace[759224776] 'compare' (duration: 32.756231ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:34:41.0587Z","caller":"traceutil/trace.go:171","msg":"trace[1902546904] transaction","detail":"{read_only:false; response_revision:7901; number_of_response:1; }","duration":"266.772898ms","start":"2026-03-19T07:34:40.791886Z","end":"2026-03-19T07:34:41.058659Z","steps":["trace[1902546904] 'process raft request' (duration: 266.467202ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:34:49.850212Z","caller":"traceutil/trace.go:171","msg":"trace[522669880] transaction","detail":"{read_only:false; response_revision:7984; number_of_response:1; }","duration":"211.91079ms","start":"2026-03-19T07:34:49.638273Z","end":"2026-03-19T07:34:49.850183Z","steps":["trace[522669880] 'process raft request' (duration: 211.752456ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:38:22.428163Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T07:38:22.433682Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-19T07:38:22.433841Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-19T07:39:24.299313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7766} {"level":"info","ts":"2026-03-19T07:39:24.368588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7766,"took":"66.533793ms","hash":4194822025,"current-db-size-bytes":42119168,"current-db-size":"42 MB","current-db-size-in-use-bytes":27799552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-19T07:39:24.368721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4194822025,"revision":7766,"compact-revision":5745} {"level":"warn","ts":"2026-03-19T07:39:32.646921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.540941ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T07:44:24.308253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10279} {"level":"info","ts":"2026-03-19T07:44:24.393591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10279,"took":"82.46678ms","hash":525609030,"current-db-size-bytes":42119168,"current-db-size":"42 MB","current-db-size-in-use-bytes":24637440,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-19T07:44:24.393655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":525609030,"revision":10279,"compact-revision":7766} {"level":"info","ts":"2026-03-19T07:45:34.439886Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032e2.wal"} {"level":"info","ts":"2026-03-19T07:46:19.450218Z","caller":"traceutil/trace.go:171","msg":"trace[489965920] transaction","detail":"{read_only:false; response_revision:12841; number_of_response:1; }","duration":"117.787558ms","start":"2026-03-19T07:46:19.332356Z","end":"2026-03-19T07:46:19.450143Z","steps":["trace[489965920] 'process raft request' (duration: 116.914359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:46:27.504398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.533551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T07:46:27.504509Z","caller":"traceutil/trace.go:171","msg":"trace[567805556] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:12891; }","duration":"314.710015ms","start":"2026-03-19T07:46:27.189774Z","end":"2026-03-19T07:46:27.504484Z","steps":["trace[567805556] 'count revisions from in-memory index tree' (duration: 314.409879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:46:27.50459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:46:27.189757Z","time spent":"314.820147ms","remote":"127.0.0.1:34538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":37,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-03-19T07:46:27.504819Z","caller":"traceutil/trace.go:171","msg":"trace[1959862070] transaction","detail":"{read_only:false; response_revision:12892; number_of_response:1; }","duration":"236.094815ms","start":"2026-03-19T07:46:27.268694Z","end":"2026-03-19T07:46:27.504789Z","steps":["trace[1959862070] 'process raft request' (duration: 145.519606ms)","trace[1959862070] 'compare' (duration: 90.115899ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:46:27.56847Z","caller":"traceutil/trace.go:171","msg":"trace[1341720513] linearizableReadLoop","detail":"{readStateIndex:13353; appliedIndex:13352; }","duration":"129.88086ms","start":"2026-03-19T07:46:27.43857Z","end":"2026-03-19T07:46:27.568451Z","steps":["trace[1341720513] 'read index received' (duration: 129.740697ms)","trace[1341720513] 'applied index is now lower than readState.Index' (duration: 138.833µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:46:27.568675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.098334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-03-19T07:46:27.568731Z","caller":"traceutil/trace.go:171","msg":"trace[455688758] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:12893; }","duration":"130.245857ms","start":"2026-03-19T07:46:27.43847Z","end":"2026-03-19T07:46:27.568716Z","steps":["trace[455688758] 'agreement among raft nodes before linearized reading' (duration: 130.054473ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:46:27.568736Z","caller":"traceutil/trace.go:171","msg":"trace[1065842129] transaction","detail":"{read_only:false; response_revision:12893; number_of_response:1; }","duration":"199.291807ms","start":"2026-03-19T07:46:27.369414Z","end":"2026-03-19T07:46:27.568706Z","steps":["trace[1065842129] 'process raft request' (duration: 198.890309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:46:51.03138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.108199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T07:46:51.0315Z","caller":"traceutil/trace.go:171","msg":"trace[1537878104] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:13197; }","duration":"100.290993ms","start":"2026-03-19T07:46:50.931186Z","end":"2026-03-19T07:46:51.031477Z","steps":["trace[1537878104] 'count revisions from in-memory index tree' (duration: 100.015276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:46:51.440266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.149823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sgxdm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-19T07:46:51.440378Z","caller":"traceutil/trace.go:171","msg":"trace[142332955] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sgxdm; range_end:; response_count:1; response_revision:13200; }","duration":"245.307377ms","start":"2026-03-19T07:46:51.195046Z","end":"2026-03-19T07:46:51.440353Z","steps":["trace[142332955] 'range keys from in-memory index tree' (duration: 244.926409ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:48:13.486447Z","caller":"traceutil/trace.go:171","msg":"trace[1543947680] transaction","detail":"{read_only:false; response_revision:13946; number_of_response:1; }","duration":"138.248496ms","start":"2026-03-19T07:48:13.348168Z","end":"2026-03-19T07:48:13.486416Z","steps":["trace[1543947680] 'process raft request' (duration: 137.802256ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:48:13.794914Z","caller":"traceutil/trace.go:171","msg":"trace[867335739] linearizableReadLoop","detail":"{readStateIndex:14445; appliedIndex:14444; }","duration":"287.873836ms","start":"2026-03-19T07:48:13.507011Z","end":"2026-03-19T07:48:13.794885Z","steps":["trace[867335739] 'read index received' (duration: 287.671251ms)","trace[867335739] 'applied index is now lower than readState.Index' (duration: 201.345µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:48:13.795053Z","caller":"traceutil/trace.go:171","msg":"trace[379818448] transaction","detail":"{read_only:false; response_revision:13947; number_of_response:1; }","duration":"288.238063ms","start":"2026-03-19T07:48:13.50671Z","end":"2026-03-19T07:48:13.794948Z","steps":["trace[379818448] 'process raft request' (duration: 287.972417ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:48:13.795666Z","caller":"traceutil/trace.go:171","msg":"trace[1465786936] transaction","detail":"{read_only:false; response_revision:13948; number_of_response:1; }","duration":"288.479227ms","start":"2026-03-19T07:48:13.507164Z","end":"2026-03-19T07:48:13.795643Z","steps":["trace[1465786936] 'process raft request' (duration: 288.295484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:48:13.795778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.174003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:48:13.795855Z","caller":"traceutil/trace.go:171","msg":"trace[710791507] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13948; }","duration":"244.334798ms","start":"2026-03-19T07:48:13.551504Z","end":"2026-03-19T07:48:13.795839Z","steps":["trace[710791507] 'agreement among raft nodes before linearized reading' (duration: 244.185104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:48:13.795868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.847996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737691"} {"level":"info","ts":"2026-03-19T07:48:13.796084Z","caller":"traceutil/trace.go:171","msg":"trace[1766152412] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:13947; }","duration":"289.07942ms","start":"2026-03-19T07:48:13.506995Z","end":"2026-03-19T07:48:13.796074Z","steps":["trace[1766152412] 'agreement among raft nodes before linearized reading' (duration: 288.041869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:48:13.796096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.620633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:48:13.796153Z","caller":"traceutil/trace.go:171","msg":"trace[903624379] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13948; }","duration":"244.702206ms","start":"2026-03-19T07:48:13.551435Z","end":"2026-03-19T07:48:13.796137Z","steps":["trace[903624379] 'agreement among raft nodes before linearized reading' (duration: 244.639283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:48:13.796273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.840979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T07:48:13.796308Z","caller":"traceutil/trace.go:171","msg":"trace[1272189057] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13948; }","duration":"244.89376ms","start":"2026-03-19T07:48:13.551405Z","end":"2026-03-19T07:48:13.796299Z","steps":["trace[1272189057] 'agreement among raft nodes before linearized reading' (duration: 244.844069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:48:13.796324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.825996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T07:48:13.79586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.076912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-03-19T07:48:13.796468Z","caller":"traceutil/trace.go:171","msg":"trace[934174651] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:13948; }","duration":"220.014339ms","start":"2026-03-19T07:48:13.576426Z","end":"2026-03-19T07:48:13.79644Z","steps":["trace[934174651] 'agreement among raft nodes before linearized reading' (duration: 219.663131ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:48:13.796504Z","caller":"traceutil/trace.go:171","msg":"trace[265844307] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13948; }","duration":"245.744287ms","start":"2026-03-19T07:48:13.550748Z","end":"2026-03-19T07:48:13.796492Z","steps":["trace[265844307] 'agreement among raft nodes before linearized reading' (duration: 244.914069ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:49:24.314639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12165} {"level":"info","ts":"2026-03-19T07:49:24.389996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12165,"took":"73.067148ms","hash":292430169,"current-db-size-bytes":42119168,"current-db-size":"42 MB","current-db-size-in-use-bytes":27021312,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-19T07:49:24.390059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292430169,"revision":12165,"compact-revision":10279} {"level":"warn","ts":"2026-03-19T07:54:14.271596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.021367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T07:54:14.271741Z","caller":"traceutil/trace.go:171","msg":"trace[223772103] linearizableReadLoop","detail":"{readStateIndex:17006; appliedIndex:17005; }","duration":"124.765747ms","start":"2026-03-19T07:54:14.146958Z","end":"2026-03-19T07:54:14.271724Z","steps":["trace[223772103] 'read index received' (duration: 59.641µs)","trace[223772103] 'applied index is now lower than readState.Index' (duration: 124.704866ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:54:14.271802Z","caller":"traceutil/trace.go:171","msg":"trace[1757252856] transaction","detail":"{read_only:false; response_revision:16419; number_of_response:1; }","duration":"187.617101ms","start":"2026-03-19T07:54:14.084146Z","end":"2026-03-19T07:54:14.271763Z","steps":["trace[1757252856] 'process raft request' (duration: 39.313128ms)","trace[1757252856] 'compare' (duration: 147.869244ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:54:14.271937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.269878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6rmfx\" ","response":"range_response_count:1 size:20872"} {"level":"info","ts":"2026-03-19T07:54:14.271971Z","caller":"traceutil/trace.go:171","msg":"trace[973309053] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6rmfx; range_end:; response_count:1; response_revision:16419; }","duration":"106.336969ms","start":"2026-03-19T07:54:14.165626Z","end":"2026-03-19T07:54:14.271963Z","steps":["trace[973309053] 'agreement among raft nodes before linearized reading' (duration: 106.196866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:14.272077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.087185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g7r2v\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-03-19T07:54:14.272181Z","caller":"traceutil/trace.go:171","msg":"trace[424039304] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g7r2v; range_end:; response_count:1; response_revision:16419; }","duration":"125.278848ms","start":"2026-03-19T07:54:14.146881Z","end":"2026-03-19T07:54:14.27216Z","steps":["trace[424039304] 'agreement among raft nodes before linearized reading' (duration: 124.939371ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:14.44661Z","caller":"traceutil/trace.go:171","msg":"trace[631049266] linearizableReadLoop","detail":"{readStateIndex:17008; appliedIndex:17007; }","duration":"157.773212ms","start":"2026-03-19T07:54:14.288809Z","end":"2026-03-19T07:54:14.446583Z","steps":["trace[631049266] 'read index received' (duration: 78.35859ms)","trace[631049266] 'applied index is now lower than readState.Index' (duration: 79.413002ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:54:14.446967Z","caller":"traceutil/trace.go:171","msg":"trace[1350074029] transaction","detail":"{read_only:false; response_revision:16421; number_of_response:1; }","duration":"158.721482ms","start":"2026-03-19T07:54:14.288232Z","end":"2026-03-19T07:54:14.446953Z","steps":["trace[1350074029] 'process raft request' (duration: 158.073458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:14.448111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.283814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:947141"} {"level":"info","ts":"2026-03-19T07:54:14.448159Z","caller":"traceutil/trace.go:171","msg":"trace[980651533] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:16421; }","duration":"159.379176ms","start":"2026-03-19T07:54:14.28877Z","end":"2026-03-19T07:54:14.448149Z","steps":["trace[980651533] 'agreement among raft nodes before linearized reading' (duration: 158.411456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:14.767616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.03311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-03-19T07:54:14.767896Z","caller":"traceutil/trace.go:171","msg":"trace[1829069198] transaction","detail":"{read_only:false; response_revision:16423; number_of_response:1; }","duration":"276.118615ms","start":"2026-03-19T07:54:14.491728Z","end":"2026-03-19T07:54:14.767846Z","steps":["trace[1829069198] 'process raft request' (duration: 48.741417ms)","trace[1829069198] 'compare' (duration: 226.0524ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:54:14.76802Z","caller":"traceutil/trace.go:171","msg":"trace[2108105606] linearizableReadLoop","detail":"{readStateIndex:17011; appliedIndex:17009; }","duration":"275.306838ms","start":"2026-03-19T07:54:14.492682Z","end":"2026-03-19T07:54:14.767989Z","steps":["trace[2108105606] 'read index received' (duration: 47.669014ms)","trace[2108105606] 'applied index is now lower than readState.Index' (duration: 227.635904ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:54:14.768158Z","caller":"traceutil/trace.go:171","msg":"trace[1260633166] transaction","detail":"{read_only:false; response_revision:16424; number_of_response:1; }","duration":"275.809108ms","start":"2026-03-19T07:54:14.492278Z","end":"2026-03-19T07:54:14.768087Z","steps":["trace[1260633166] 'process raft request' (duration: 275.508632ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:14.768174Z","caller":"traceutil/trace.go:171","msg":"trace[2137694031] transaction","detail":"{read_only:false; response_revision:16425; number_of_response:1; }","duration":"275.340637ms","start":"2026-03-19T07:54:14.49278Z","end":"2026-03-19T07:54:14.768121Z","steps":["trace[2137694031] 'process raft request' (duration: 275.135143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:14.768494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.792578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128017"} {"level":"info","ts":"2026-03-19T07:54:14.768552Z","caller":"traceutil/trace.go:171","msg":"trace[236286913] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:16425; }","duration":"275.886489ms","start":"2026-03-19T07:54:14.49265Z","end":"2026-03-19T07:54:14.768536Z","steps":["trace[236286913] 'agreement among raft nodes before linearized reading' (duration: 275.513411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:14.768561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.954031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-shvbh\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-03-19T07:54:14.768634Z","caller":"traceutil/trace.go:171","msg":"trace[709570930] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-shvbh; range_end:; response_count:1; response_revision:16425; }","duration":"163.159876ms","start":"2026-03-19T07:54:14.605462Z","end":"2026-03-19T07:54:14.768622Z","steps":["trace[709570930] 'agreement among raft nodes before linearized reading' (duration: 162.904581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:15.116358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.275067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-03-19T07:54:15.116472Z","caller":"traceutil/trace.go:171","msg":"trace[2008652655] transaction","detail":"{read_only:false; response_revision:16427; number_of_response:1; }","duration":"288.177478ms","start":"2026-03-19T07:54:14.828277Z","end":"2026-03-19T07:54:15.116454Z","steps":["trace[2008652655] 'process raft request' (duration: 59.728647ms)","trace[2008652655] 'compare' (duration: 228.102914ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:54:15.13809Z","caller":"traceutil/trace.go:171","msg":"trace[1003807057] transaction","detail":"{read_only:false; response_revision:16428; number_of_response:1; }","duration":"272.904237ms","start":"2026-03-19T07:54:14.865154Z","end":"2026-03-19T07:54:15.138058Z","steps":["trace[1003807057] 'process raft request' (duration: 272.711043ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:15.369963Z","caller":"traceutil/trace.go:171","msg":"trace[1580949799] linearizableReadLoop","detail":"{readStateIndex:17019; appliedIndex:17015; }","duration":"121.252373ms","start":"2026-03-19T07:54:15.24868Z","end":"2026-03-19T07:54:15.369932Z","steps":["trace[1580949799] 'read index received' (duration: 120.821444ms)","trace[1580949799] 'applied index is now lower than readState.Index' (duration: 425.339µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:54:15.369972Z","caller":"traceutil/trace.go:171","msg":"trace[562092775] transaction","detail":"{read_only:false; response_revision:16430; number_of_response:1; }","duration":"248.514523ms","start":"2026-03-19T07:54:15.121415Z","end":"2026-03-19T07:54:15.36993Z","steps":["trace[562092775] 'process raft request' (duration: 248.267658ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:15.370082Z","caller":"traceutil/trace.go:171","msg":"trace[375458040] transaction","detail":"{read_only:false; response_revision:16431; number_of_response:1; }","duration":"248.510804ms","start":"2026-03-19T07:54:15.121543Z","end":"2026-03-19T07:54:15.370054Z","steps":["trace[375458040] 'process raft request' (duration: 248.215758ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:15.370117Z","caller":"traceutil/trace.go:171","msg":"trace[1268408835] transaction","detail":"{read_only:false; response_revision:16432; number_of_response:1; }","duration":"247.758457ms","start":"2026-03-19T07:54:15.122346Z","end":"2026-03-19T07:54:15.370105Z","steps":["trace[1268408835] 'process raft request' (duration: 247.521202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:15.370346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.436886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-19T07:54:15.370421Z","caller":"traceutil/trace.go:171","msg":"trace[519733100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16432; }","duration":"120.578409ms","start":"2026-03-19T07:54:15.249827Z","end":"2026-03-19T07:54:15.370405Z","steps":["trace[519733100] 'agreement among raft nodes before linearized reading' (duration: 120.324244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:54:15.370612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.919417ms","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-03-19T07:54:15.370663Z","caller":"traceutil/trace.go:171","msg":"trace[770062302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16432; }","duration":"122.040899ms","start":"2026-03-19T07:54:15.24861Z","end":"2026-03-19T07:54:15.370651Z","steps":["trace[770062302] 'agreement among raft nodes before linearized reading' (duration: 121.550779ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:15.370069Z","caller":"traceutil/trace.go:171","msg":"trace[1621493617] transaction","detail":"{read_only:false; response_revision:16429; number_of_response:1; }","duration":"249.124887ms","start":"2026-03-19T07:54:15.120865Z","end":"2026-03-19T07:54:15.36999Z","steps":["trace[1621493617] 'process raft request' (duration: 248.660337ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:54:24.332895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14372} {"level":"info","ts":"2026-03-19T07:54:24.434748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14372,"took":"97.678958ms","hash":2674314752,"current-db-size-bytes":42119168,"current-db-size":"42 MB","current-db-size-in-use-bytes":28143616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-19T07:54:24.434809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2674314752,"revision":14372,"compact-revision":12165} {"level":"info","ts":"2026-03-19T07:59:24.338878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16613} {"level":"info","ts":"2026-03-19T07:59:24.452897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16613,"took":"110.263235ms","hash":2457856178,"current-db-size-bytes":44228608,"current-db-size":"44 MB","current-db-size-in-use-bytes":29454336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-19T07:59:24.452991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2457856178,"revision":16613,"compact-revision":14372} {"level":"info","ts":"2026-03-19T07:59:59.559705Z","caller":"traceutil/trace.go:171","msg":"trace[1509900758] transaction","detail":"{read_only:false; response_revision:18922; number_of_response:1; }","duration":"141.310696ms","start":"2026-03-19T07:59:59.418359Z","end":"2026-03-19T07:59:59.55967Z","steps":["trace[1509900758] 'process raft request' (duration: 141.131713ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:59:59.574371Z","caller":"traceutil/trace.go:171","msg":"trace[1220366584] linearizableReadLoop","detail":"{readStateIndex:19601; appliedIndex:19599; }","duration":"132.737187ms","start":"2026-03-19T07:59:59.441578Z","end":"2026-03-19T07:59:59.574316Z","steps":["trace[1220366584] 'read index received' (duration: 117.878205ms)","trace[1220366584] 'applied index is now lower than readState.Index' (duration: 14.857762ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T07:59:59.574608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.013022ms","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-03-19T07:59:59.574649Z","caller":"traceutil/trace.go:171","msg":"trace[1346144614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18923; }","duration":"133.152715ms","start":"2026-03-19T07:59:59.441485Z","end":"2026-03-19T07:59:59.574638Z","steps":["trace[1346144614] 'agreement among raft nodes before linearized reading' (duration: 132.955111ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:59:59.574933Z","caller":"traceutil/trace.go:171","msg":"trace[424970636] transaction","detail":"{read_only:false; response_revision:18923; number_of_response:1; }","duration":"156.155698ms","start":"2026-03-19T07:59:59.418768Z","end":"2026-03-19T07:59:59.574924Z","steps":["trace[424970636] 'process raft request' (duration: 155.397763ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:59:59.836459Z","caller":"traceutil/trace.go:171","msg":"trace[1618316917] linearizableReadLoop","detail":"{readStateIndex:19602; appliedIndex:19601; }","duration":"198.17318ms","start":"2026-03-19T07:59:59.638253Z","end":"2026-03-19T07:59:59.836426Z","steps":["trace[1618316917] 'read index received' (duration: 197.940025ms)","trace[1618316917] 'applied index is now lower than readState.Index' (duration: 231.525µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:59:59.836724Z","caller":"traceutil/trace.go:171","msg":"trace[711960158] transaction","detail":"{read_only:false; response_revision:18924; number_of_response:1; }","duration":"257.974815ms","start":"2026-03-19T07:59:59.57858Z","end":"2026-03-19T07:59:59.836555Z","steps":["trace[711960158] 'process raft request' (duration: 257.642568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:59:59.836976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.671611ms","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-03-19T07:59:59.837441Z","caller":"traceutil/trace.go:171","msg":"trace[972415125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18924; }","duration":"199.235992ms","start":"2026-03-19T07:59:59.638178Z","end":"2026-03-19T07:59:59.837413Z","steps":["trace[972415125] 'agreement among raft nodes before linearized reading' (duration: 198.444746ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T07:59:59.97647Z","caller":"traceutil/trace.go:171","msg":"trace[1044332540] linearizableReadLoop","detail":"{readStateIndex:19603; appliedIndex:19602; }","duration":"139.816845ms","start":"2026-03-19T07:59:59.83661Z","end":"2026-03-19T07:59:59.976427Z","steps":["trace[1044332540] 'read index received' (duration: 53.924101ms)","trace[1044332540] 'applied index is now lower than readState.Index' (duration: 85.891264ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:59:59.97648Z","caller":"traceutil/trace.go:171","msg":"trace[16587663] transaction","detail":"{read_only:false; response_revision:18925; number_of_response:1; }","duration":"199.818405ms","start":"2026-03-19T07:59:59.776617Z","end":"2026-03-19T07:59:59.976436Z","steps":["trace[16587663] 'process raft request' (duration: 113.975203ms)","trace[16587663] 'compare' (duration: 85.576785ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T07:59:59.976623Z","caller":"traceutil/trace.go:171","msg":"trace[186808214] transaction","detail":"{read_only:false; response_revision:18926; number_of_response:1; }","duration":"134.952643ms","start":"2026-03-19T07:59:59.84166Z","end":"2026-03-19T07:59:59.976613Z","steps":["trace[186808214] 'process raft request' (duration: 134.724619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:59:59.976753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.544203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-19T07:59:59.976822Z","caller":"traceutil/trace.go:171","msg":"trace[58013866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18926; }","duration":"334.666345ms","start":"2026-03-19T07:59:59.642138Z","end":"2026-03-19T07:59:59.976805Z","steps":["trace[58013866] 'agreement among raft nodes before linearized reading' (duration: 334.427011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T07:59:59.976855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T07:59:59.642118Z","time spent":"334.731697ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T07:59:59.976863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.853168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T07:59:59.976949Z","caller":"traceutil/trace.go:171","msg":"trace[1315896232] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:18926; }","duration":"182.028371ms","start":"2026-03-19T07:59:59.794897Z","end":"2026-03-19T07:59:59.976926Z","steps":["trace[1315896232] 'agreement among raft nodes before linearized reading' (duration: 181.548341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:00.241875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.153537ms","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-03-19T08:00:00.241985Z","caller":"traceutil/trace.go:171","msg":"trace[1573293121] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:18927; }","duration":"135.342351ms","start":"2026-03-19T08:00:00.106619Z","end":"2026-03-19T08:00:00.241961Z","steps":["trace[1573293121] 'range keys from in-memory index tree' (duration: 134.977413ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:00.406335Z","caller":"traceutil/trace.go:171","msg":"trace[1304800186] transaction","detail":"{read_only:false; response_revision:18928; number_of_response:1; }","duration":"161.856458ms","start":"2026-03-19T08:00:00.24444Z","end":"2026-03-19T08:00:00.406297Z","steps":["trace[1304800186] 'process raft request' (duration: 161.570362ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:00.587712Z","caller":"traceutil/trace.go:171","msg":"trace[990677658] transaction","detail":"{read_only:false; response_revision:18929; number_of_response:1; }","duration":"343.140684ms","start":"2026-03-19T08:00:00.244547Z","end":"2026-03-19T08:00:00.587688Z","steps":["trace[990677658] 'process raft request' (duration: 342.899839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:00.587856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.859554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:00:00.588122Z","caller":"traceutil/trace.go:171","msg":"trace[1675103452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18930; }","duration":"198.18317ms","start":"2026-03-19T08:00:00.389923Z","end":"2026-03-19T08:00:00.588106Z","steps":["trace[1675103452] 'agreement among raft nodes before linearized reading' (duration: 197.842173ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:00.587844Z","caller":"traceutil/trace.go:171","msg":"trace[1632068367] transaction","detail":"{read_only:false; response_revision:18930; number_of_response:1; }","duration":"343.232326ms","start":"2026-03-19T08:00:00.244543Z","end":"2026-03-19T08:00:00.587776Z","steps":["trace[1632068367] 'process raft request' (duration: 343.074942ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:00.587699Z","caller":"traceutil/trace.go:171","msg":"trace[1960893580] linearizableReadLoop","detail":"{readStateIndex:19608; appliedIndex:19605; }","duration":"197.6839ms","start":"2026-03-19T08:00:00.389984Z","end":"2026-03-19T08:00:00.587668Z","steps":["trace[1960893580] 'read index received' (duration: 16.091338ms)","trace[1960893580] 'applied index is now lower than readState.Index' (duration: 181.590952ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:00:00.587991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.24451Z","time spent":"343.280417ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5246,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T08:00:00.588519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.244496Z","time spent":"343.847398ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T08:00:00.791221Z","caller":"traceutil/trace.go:171","msg":"trace[1834527626] linearizableReadLoop","detail":"{readStateIndex:19611; appliedIndex:19608; }","duration":"202.740425ms","start":"2026-03-19T08:00:00.588435Z","end":"2026-03-19T08:00:00.791175Z","steps":["trace[1834527626] 'read index received' (duration: 202.324597ms)","trace[1834527626] 'applied index is now lower than readState.Index' (duration: 414.348µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:00:00.791252Z","caller":"traceutil/trace.go:171","msg":"trace[39985728] transaction","detail":"{read_only:false; response_revision:18932; number_of_response:1; }","duration":"375.982622ms","start":"2026-03-19T08:00:00.415229Z","end":"2026-03-19T08:00:00.791211Z","steps":["trace[39985728] 'process raft request' (duration: 375.807208ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:00.791221Z","caller":"traceutil/trace.go:171","msg":"trace[2032017328] transaction","detail":"{read_only:false; response_revision:18931; number_of_response:1; }","duration":"379.180369ms","start":"2026-03-19T08:00:00.411988Z","end":"2026-03-19T08:00:00.791168Z","steps":["trace[2032017328] 'process raft request' (duration: 378.75459ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:00.791377Z","caller":"traceutil/trace.go:171","msg":"trace[846701406] transaction","detail":"{read_only:false; response_revision:18933; number_of_response:1; }","duration":"247.367392ms","start":"2026-03-19T08:00:00.543996Z","end":"2026-03-19T08:00:00.791363Z","steps":["trace[846701406] 'process raft request' (duration: 247.119877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:00.791445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.415197Z","time spent":"376.133254ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6718,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:00:00.791445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.41196Z","time spent":"379.391714ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T08:00:00.79166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.476124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:00:00.791769Z","caller":"traceutil/trace.go:171","msg":"trace[1011204743] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:18933; }","duration":"377.612337ms","start":"2026-03-19T08:00:00.414128Z","end":"2026-03-19T08:00:00.79174Z","steps":["trace[1011204743] 'agreement among raft nodes before linearized reading' (duration: 377.414173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:00.791831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.414116Z","time spent":"377.702189ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"info","ts":"2026-03-19T08:00:01.015513Z","caller":"traceutil/trace.go:171","msg":"trace[1470604027] transaction","detail":"{read_only:false; response_revision:18934; number_of_response:1; }","duration":"416.663676ms","start":"2026-03-19T08:00:00.598818Z","end":"2026-03-19T08:00:01.015482Z","steps":["trace[1470604027] 'process raft request' (duration: 416.421701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.015705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.598802Z","time spent":"416.802939ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:00:01.015775Z","caller":"traceutil/trace.go:171","msg":"trace[1732743052] linearizableReadLoop","detail":"{readStateIndex:19613; appliedIndex:19611; }","duration":"224.432972ms","start":"2026-03-19T08:00:00.791305Z","end":"2026-03-19T08:00:01.015738Z","steps":["trace[1732743052] 'read index received' (duration: 224.039323ms)","trace[1732743052] 'applied index is now lower than readState.Index' (duration: 391.329µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:00:01.015895Z","caller":"traceutil/trace.go:171","msg":"trace[2020678461] transaction","detail":"{read_only:false; response_revision:18935; number_of_response:1; }","duration":"417.025924ms","start":"2026-03-19T08:00:00.598844Z","end":"2026-03-19T08:00:01.01587Z","steps":["trace[2020678461] 'process raft request' (duration: 416.575083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.016061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.598826Z","time spent":"417.118106ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:00:01.016066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.354321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:00:01.016169Z","caller":"traceutil/trace.go:171","msg":"trace[253309946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18935; }","duration":"426.525743ms","start":"2026-03-19T08:00:00.589618Z","end":"2026-03-19T08:00:01.016144Z","steps":["trace[253309946] 'agreement among raft nodes before linearized reading' (duration: 426.317279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.01624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.589592Z","time spent":"426.635436ms","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T08:00:01.101539Z","caller":"traceutil/trace.go:171","msg":"trace[1510953871] transaction","detail":"{read_only:false; response_revision:18936; number_of_response:1; }","duration":"306.387761ms","start":"2026-03-19T08:00:00.795114Z","end":"2026-03-19T08:00:01.101502Z","steps":["trace[1510953871] 'process raft request' (duration: 306.116485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.101737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.795086Z","time spent":"306.546374ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6850,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T08:00:01.10171Z","caller":"traceutil/trace.go:171","msg":"trace[739060547] transaction","detail":"{read_only:false; response_revision:18937; number_of_response:1; }","duration":"306.183317ms","start":"2026-03-19T08:00:00.795478Z","end":"2026-03-19T08:00:01.101661Z","steps":["trace[739060547] 'process raft request' (duration: 305.925062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.101896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.795436Z","time spent":"306.372051ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T08:00:01.102116Z","caller":"traceutil/trace.go:171","msg":"trace[1736914601] transaction","detail":"{read_only:false; response_revision:18938; number_of_response:1; }","duration":"306.591715ms","start":"2026-03-19T08:00:00.79551Z","end":"2026-03-19T08:00:01.102102Z","steps":["trace[1736914601] 'process raft request' (duration: 305.945342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.102192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.795471Z","time spent":"306.693187ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5443,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T08:00:01.102577Z","caller":"traceutil/trace.go:171","msg":"trace[1665787255] transaction","detail":"{read_only:false; response_revision:18939; number_of_response:1; }","duration":"306.686428ms","start":"2026-03-19T08:00:00.795877Z","end":"2026-03-19T08:00:01.102564Z","steps":["trace[1665787255] 'process raft request' (duration: 306.168256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.10266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:00.795848Z","time spent":"306.76781ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T08:00:01.103283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.575278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T08:00:01.103345Z","caller":"traceutil/trace.go:171","msg":"trace[351665494] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:18941; }","duration":"187.69691ms","start":"2026-03-19T08:00:00.915634Z","end":"2026-03-19T08:00:01.103331Z","steps":["trace[351665494] 'agreement among raft nodes before linearized reading' (duration: 187.566108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.103746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.748321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-03-19T08:00:01.103788Z","caller":"traceutil/trace.go:171","msg":"trace[480715407] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:18941; }","duration":"136.839253ms","start":"2026-03-19T08:00:00.966939Z","end":"2026-03-19T08:00:01.103778Z","steps":["trace[480715407] 'agreement among raft nodes before linearized reading' (duration: 136.647359ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:01.103967Z","caller":"traceutil/trace.go:171","msg":"trace[1358486145] transaction","detail":"{read_only:false; response_revision:18940; number_of_response:1; }","duration":"163.578503ms","start":"2026-03-19T08:00:00.940377Z","end":"2026-03-19T08:00:01.103956Z","steps":["trace[1358486145] 'process raft request' (duration: 162.123113ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:01.104644Z","caller":"traceutil/trace.go:171","msg":"trace[1018752648] transaction","detail":"{read_only:false; response_revision:18941; number_of_response:1; }","duration":"163.203395ms","start":"2026-03-19T08:00:00.940404Z","end":"2026-03-19T08:00:01.103607Z","steps":["trace[1018752648] 'process raft request' (duration: 162.731825ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:01.433203Z","caller":"traceutil/trace.go:171","msg":"trace[68586282] transaction","detail":"{read_only:false; response_revision:18942; number_of_response:1; }","duration":"325.109624ms","start":"2026-03-19T08:00:01.108063Z","end":"2026-03-19T08:00:01.433173Z","steps":["trace[68586282] 'process raft request' (duration: 324.924791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.43336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.108034Z","time spent":"325.251358ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T08:00:01.538931Z","caller":"traceutil/trace.go:171","msg":"trace[694773775] transaction","detail":"{read_only:false; response_revision:18943; number_of_response:1; }","duration":"429.972735ms","start":"2026-03-19T08:00:01.108936Z","end":"2026-03-19T08:00:01.538909Z","steps":["trace[694773775] 'process raft request' (duration: 429.662769ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:01.538962Z","caller":"traceutil/trace.go:171","msg":"trace[1413182868] transaction","detail":"{read_only:false; response_revision:18944; number_of_response:1; }","duration":"429.747231ms","start":"2026-03-19T08:00:01.109197Z","end":"2026-03-19T08:00:01.538945Z","steps":["trace[1413182868] 'process raft request' (duration: 429.606588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.539054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.10914Z","time spent":"429.866703ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T08:00:01.539059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.108908Z","time spent":"430.077538ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6896,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:00:01.539464Z","caller":"traceutil/trace.go:171","msg":"trace[636594568] transaction","detail":"{read_only:false; response_revision:18945; number_of_response:1; }","duration":"429.70099ms","start":"2026-03-19T08:00:01.109746Z","end":"2026-03-19T08:00:01.539447Z","steps":["trace[636594568] 'process raft request' (duration: 429.131827ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:01.539531Z","caller":"traceutil/trace.go:171","msg":"trace[515213029] transaction","detail":"{read_only:false; response_revision:18946; number_of_response:1; }","duration":"429.562528ms","start":"2026-03-19T08:00:01.109953Z","end":"2026-03-19T08:00:01.539516Z","steps":["trace[515213029] 'process raft request' (duration: 429.280461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.539546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.109723Z","time spent":"429.780442ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:00:01.539615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.109945Z","time spent":"429.626049ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5660,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:00:01.539664Z","caller":"traceutil/trace.go:171","msg":"trace[1465933065] transaction","detail":"{read_only:false; response_revision:18947; number_of_response:1; }","duration":"429.275452ms","start":"2026-03-19T08:00:01.110363Z","end":"2026-03-19T08:00:01.539638Z","steps":["trace[1465933065] 'process raft request' (duration: 429.029816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.539735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.110354Z","time spent":"429.347663ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:00:01.539785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.631191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:00:01.53981Z","caller":"traceutil/trace.go:171","msg":"trace[1841185013] transaction","detail":"{read_only:false; response_revision:18948; number_of_response:1; }","duration":"427.668367ms","start":"2026-03-19T08:00:01.112129Z","end":"2026-03-19T08:00:01.539797Z","steps":["trace[1841185013] 'process raft request' (duration: 427.343961ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:01.539619Z","caller":"traceutil/trace.go:171","msg":"trace[579411527] linearizableReadLoop","detail":"{readStateIndex:19627; appliedIndex:19619; }","duration":"149.464028ms","start":"2026-03-19T08:00:01.390124Z","end":"2026-03-19T08:00:01.539588Z","steps":["trace[579411527] 'read index received' (duration: 42.960793ms)","trace[579411527] 'applied index is now lower than readState.Index' (duration: 106.501585ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:00:01.539842Z","caller":"traceutil/trace.go:171","msg":"trace[327641314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18949; }","duration":"149.762084ms","start":"2026-03-19T08:00:01.390069Z","end":"2026-03-19T08:00:01.539831Z","steps":["trace[327641314] 'agreement among raft nodes before linearized reading' (duration: 149.620801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.539875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.11212Z","time spent":"427.719358ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5428,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:00:01.54011Z","caller":"traceutil/trace.go:171","msg":"trace[1886150433] transaction","detail":"{read_only:false; response_revision:18949; number_of_response:1; }","duration":"427.520094ms","start":"2026-03-19T08:00:01.112562Z","end":"2026-03-19T08:00:01.540082Z","steps":["trace[1886150433] 'process raft request' (duration: 426.967553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:00:01.540188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:00:01.112551Z","time spent":"427.601406ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6835,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:00:01.540328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.53706ms","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:8"} {"level":"info","ts":"2026-03-19T08:00:01.540372Z","caller":"traceutil/trace.go:171","msg":"trace[590698872] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:18949; }","duration":"119.639712ms","start":"2026-03-19T08:00:01.42072Z","end":"2026-03-19T08:00:01.54036Z","steps":["trace[590698872] 'agreement among raft nodes before linearized reading' (duration: 119.477749ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:00:40.967523Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T08:00:40.972467Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-03-19T08:00:40.972625Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-03-19T08:03:47.643139Z","caller":"traceutil/trace.go:171","msg":"trace[528013708] transaction","detail":"{read_only:false; response_revision:20672; number_of_response:1; }","duration":"127.743475ms","start":"2026-03-19T08:03:47.515347Z","end":"2026-03-19T08:03:47.643091Z","steps":["trace[528013708] 'process raft request' (duration: 109.468042ms)","trace[528013708] 'compare' (duration: 18.076179ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:04:24.346367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18757} {"level":"info","ts":"2026-03-19T08:04:24.478262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18757,"took":"126.834287ms","hash":3491024000,"current-db-size-bytes":49508352,"current-db-size":"50 MB","current-db-size-in-use-bytes":36552704,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-03-19T08:04:24.478354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3491024000,"revision":18757,"compact-revision":16613} {"level":"info","ts":"2026-03-19T08:04:26.490618Z","caller":"traceutil/trace.go:171","msg":"trace[1880853047] transaction","detail":"{read_only:false; response_revision:21457; number_of_response:1; }","duration":"239.111198ms","start":"2026-03-19T08:04:26.25146Z","end":"2026-03-19T08:04:26.490571Z","steps":["trace[1880853047] 'process raft request' (duration: 238.854873ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:04:26.768289Z","caller":"traceutil/trace.go:171","msg":"trace[718030929] transaction","detail":"{read_only:false; response_revision:21458; number_of_response:1; }","duration":"171.852694ms","start":"2026-03-19T08:04:26.596404Z","end":"2026-03-19T08:04:26.768257Z","steps":["trace[718030929] 'process raft request' (duration: 169.944844ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:04:26.768917Z","caller":"traceutil/trace.go:171","msg":"trace[1397458424] transaction","detail":"{read_only:false; response_revision:21459; number_of_response:1; }","duration":"155.239334ms","start":"2026-03-19T08:04:26.613619Z","end":"2026-03-19T08:04:26.768858Z","steps":["trace[1397458424] 'process raft request' (duration: 154.378876ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:09.297564Z","caller":"traceutil/trace.go:171","msg":"trace[1205393358] transaction","detail":"{read_only:false; response_revision:23614; number_of_response:1; }","duration":"121.988185ms","start":"2026-03-19T08:08:09.175551Z","end":"2026-03-19T08:08:09.297539Z","steps":["trace[1205393358] 'process raft request' (duration: 121.836252ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:09.375438Z","caller":"traceutil/trace.go:171","msg":"trace[1619479498] transaction","detail":"{read_only:false; response_revision:23615; number_of_response:1; }","duration":"141.495856ms","start":"2026-03-19T08:08:09.233841Z","end":"2026-03-19T08:08:09.375337Z","steps":["trace[1619479498] 'process raft request' (duration: 132.837362ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:14.863151Z","caller":"traceutil/trace.go:171","msg":"trace[1358204290] transaction","detail":"{read_only:false; response_revision:23651; number_of_response:1; }","duration":"215.659895ms","start":"2026-03-19T08:08:14.647424Z","end":"2026-03-19T08:08:14.863084Z","steps":["trace[1358204290] 'process raft request' (duration: 215.41145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:15.093977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.026188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:15.094126Z","caller":"traceutil/trace.go:171","msg":"trace[1230414403] linearizableReadLoop","detail":"{readStateIndex:24470; appliedIndex:24468; }","duration":"245.546493ms","start":"2026-03-19T08:08:14.848554Z","end":"2026-03-19T08:08:15.0941Z","steps":["trace[1230414403] 'read index received' (duration: 14.341481ms)","trace[1230414403] 'applied index is now lower than readState.Index' (duration: 231.202382ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:15.094187Z","caller":"traceutil/trace.go:171","msg":"trace[1068358596] transaction","detail":"{read_only:false; response_revision:23652; number_of_response:1; }","duration":"258.477225ms","start":"2026-03-19T08:08:14.835669Z","end":"2026-03-19T08:08:15.094146Z","steps":["trace[1068358596] 'process raft request' (duration: 124.13754ms)","trace[1068358596] 'compare' (duration: 133.778813ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:15.094412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.85982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-19T08:08:15.094786Z","caller":"traceutil/trace.go:171","msg":"trace[1269693518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23652; }","duration":"246.016823ms","start":"2026-03-19T08:08:14.848538Z","end":"2026-03-19T08:08:15.094555Z","steps":["trace[1269693518] 'agreement among raft nodes before linearized reading' (duration: 245.698367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:15.095035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.240143ms","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-03-19T08:08:15.095193Z","caller":"traceutil/trace.go:171","msg":"trace[1781692801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23652; }","duration":"242.439436ms","start":"2026-03-19T08:08:14.852703Z","end":"2026-03-19T08:08:15.095143Z","steps":["trace[1781692801] 'agreement among raft nodes before linearized reading' (duration: 241.976747ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:15.523669Z","caller":"traceutil/trace.go:171","msg":"trace[1493207270] transaction","detail":"{read_only:false; response_revision:23656; number_of_response:1; }","duration":"125.673723ms","start":"2026-03-19T08:08:15.397967Z","end":"2026-03-19T08:08:15.52364Z","steps":["trace[1493207270] 'process raft request' (duration: 124.710713ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:15.771667Z","caller":"traceutil/trace.go:171","msg":"trace[415121402] transaction","detail":"{read_only:false; response_revision:23658; number_of_response:1; }","duration":"323.623795ms","start":"2026-03-19T08:08:15.447966Z","end":"2026-03-19T08:08:15.77159Z","steps":["trace[415121402] 'process raft request' (duration: 323.089324ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:15.771723Z","caller":"traceutil/trace.go:171","msg":"trace[1330593655] transaction","detail":"{read_only:false; response_revision:23659; number_of_response:1; }","duration":"319.60823ms","start":"2026-03-19T08:08:15.452058Z","end":"2026-03-19T08:08:15.771667Z","steps":["trace[1330593655] 'process raft request' (duration: 319.041319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:15.771918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:15.44793Z","time spent":"323.84794ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:15.77212Z","caller":"traceutil/trace.go:171","msg":"trace[424209273] transaction","detail":"{read_only:false; response_revision:23657; number_of_response:1; }","duration":"373.410331ms","start":"2026-03-19T08:08:15.398693Z","end":"2026-03-19T08:08:15.772103Z","steps":["trace[424209273] 'process raft request' (duration: 288.296452ms)","trace[424209273] 'compare' (duration: 83.922655ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:15.772198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:15.398662Z","time spent":"373.492683ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:15.772211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:15.45204Z","time spent":"319.923577ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:16.00222Z","caller":"traceutil/trace.go:171","msg":"trace[774621969] transaction","detail":"{read_only:false; response_revision:23661; number_of_response:1; }","duration":"164.334006ms","start":"2026-03-19T08:08:15.837844Z","end":"2026-03-19T08:08:16.002178Z","steps":["trace[774621969] 'process raft request' (duration: 89.167556ms)","trace[774621969] 'compare' (duration: 74.742991ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:16.558741Z","caller":"traceutil/trace.go:171","msg":"trace[455917212] transaction","detail":"{read_only:false; response_revision:23662; number_of_response:1; }","duration":"224.097832ms","start":"2026-03-19T08:08:16.334612Z","end":"2026-03-19T08:08:16.558709Z","steps":["trace[455917212] 'process raft request' (duration: 223.795526ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:16.579144Z","caller":"traceutil/trace.go:171","msg":"trace[1112795733] linearizableReadLoop","detail":"{readStateIndex:24481; appliedIndex:24479; }","duration":"193.062929ms","start":"2026-03-19T08:08:16.386035Z","end":"2026-03-19T08:08:16.579098Z","steps":["trace[1112795733] 'read index received' (duration: 172.378205ms)","trace[1112795733] 'applied index is now lower than readState.Index' (duration: 20.682684ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:16.579323Z","caller":"traceutil/trace.go:171","msg":"trace[1858709026] transaction","detail":"{read_only:false; response_revision:23663; number_of_response:1; }","duration":"244.361258ms","start":"2026-03-19T08:08:16.334935Z","end":"2026-03-19T08:08:16.579297Z","steps":["trace[1858709026] 'process raft request' (duration: 243.935899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:16.579355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.282354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:16.579439Z","caller":"traceutil/trace.go:171","msg":"trace[1963938580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23663; }","duration":"193.384736ms","start":"2026-03-19T08:08:16.386003Z","end":"2026-03-19T08:08:16.579388Z","steps":["trace[1963938580] 'agreement among raft nodes before linearized reading' (duration: 193.266814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:16.876432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.518349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:16.876565Z","caller":"traceutil/trace.go:171","msg":"trace[1801517302] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:23663; }","duration":"242.680062ms","start":"2026-03-19T08:08:16.633857Z","end":"2026-03-19T08:08:16.876537Z","steps":["trace[1801517302] 'range keys from in-memory index tree' (duration: 242.468658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:16.876954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.735758ms","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-03-19T08:08:16.877056Z","caller":"traceutil/trace.go:171","msg":"trace[1686364549] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23663; }","duration":"110.911501ms","start":"2026-03-19T08:08:16.76612Z","end":"2026-03-19T08:08:16.877032Z","steps":["trace[1686364549] 'count revisions from in-memory index tree' (duration: 110.633566ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:17.025132Z","caller":"traceutil/trace.go:171","msg":"trace[1473877142] linearizableReadLoop","detail":"{readStateIndex:24482; appliedIndex:24481; }","duration":"110.186097ms","start":"2026-03-19T08:08:16.914918Z","end":"2026-03-19T08:08:17.025104Z","steps":["trace[1473877142] 'read index received' (duration: 110.053324ms)","trace[1473877142] 'applied index is now lower than readState.Index' (duration: 131.683µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:17.025372Z","caller":"traceutil/trace.go:171","msg":"trace[1109074587] transaction","detail":"{read_only:false; response_revision:23664; number_of_response:1; }","duration":"130.577665ms","start":"2026-03-19T08:08:16.894763Z","end":"2026-03-19T08:08:17.025341Z","steps":["trace[1109074587] 'process raft request' (duration: 130.145166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:17.025787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.612955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-03-19T08:08:17.025863Z","caller":"traceutil/trace.go:171","msg":"trace[16742329] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23664; }","duration":"110.988764ms","start":"2026-03-19T08:08:16.914857Z","end":"2026-03-19T08:08:17.025846Z","steps":["trace[16742329] 'agreement among raft nodes before linearized reading' (duration: 110.36634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:17.414072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.529679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:17.414218Z","caller":"traceutil/trace.go:171","msg":"trace[2047369135] linearizableReadLoop","detail":"{readStateIndex:24484; appliedIndex:24482; }","duration":"264.4811ms","start":"2026-03-19T08:08:17.149723Z","end":"2026-03-19T08:08:17.414204Z","steps":["trace[2047369135] 'read index received' (duration: 11.708356ms)","trace[2047369135] 'applied index is now lower than readState.Index' (duration: 252.771854ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:17.414379Z","caller":"traceutil/trace.go:171","msg":"trace[2018094546] transaction","detail":"{read_only:false; response_revision:23665; number_of_response:1; }","duration":"384.218539ms","start":"2026-03-19T08:08:17.030148Z","end":"2026-03-19T08:08:17.414367Z","steps":["trace[2018094546] 'process raft request' (duration: 131.326792ms)","trace[2018094546] 'compare' (duration: 252.10683ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:17.414434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.030128Z","time spent":"384.273269ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:17.414562Z","caller":"traceutil/trace.go:171","msg":"trace[1082542518] transaction","detail":"{read_only:false; response_revision:23666; number_of_response:1; }","duration":"313.131395ms","start":"2026-03-19T08:08:17.101426Z","end":"2026-03-19T08:08:17.414557Z","steps":["trace[1082542518] 'process raft request' (duration: 312.730056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:17.414594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.101392Z","time spent":"313.187955ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:17.414719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.008082ms","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-03-19T08:08:17.414742Z","caller":"traceutil/trace.go:171","msg":"trace[1203628385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23666; }","duration":"265.075343ms","start":"2026-03-19T08:08:17.149661Z","end":"2026-03-19T08:08:17.414736Z","steps":["trace[1203628385] 'agreement among raft nodes before linearized reading' (duration: 264.982381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:17.88899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.902179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:17.889291Z","caller":"traceutil/trace.go:171","msg":"trace[1939755015] transaction","detail":"{read_only:false; response_revision:23669; number_of_response:1; }","duration":"358.954047ms","start":"2026-03-19T08:08:17.530314Z","end":"2026-03-19T08:08:17.889268Z","steps":["trace[1939755015] 'process raft request' (duration: 358.900356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:17.889371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.530284Z","time spent":"359.05034ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:17.889598Z","caller":"traceutil/trace.go:171","msg":"trace[1842607500] transaction","detail":"{read_only:false; response_revision:23667; number_of_response:1; }","duration":"530.950764ms","start":"2026-03-19T08:08:17.358635Z","end":"2026-03-19T08:08:17.889586Z","steps":["trace[1842607500] 'process raft request' (duration: 199.31198ms)","trace[1842607500] 'compare' (duration: 330.692444ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:17.889652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.358603Z","time spent":"531.019026ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:17.889831Z","caller":"traceutil/trace.go:171","msg":"trace[2142932111] linearizableReadLoop","detail":"{readStateIndex:24485; appliedIndex:24484; }","duration":"475.57876ms","start":"2026-03-19T08:08:17.414242Z","end":"2026-03-19T08:08:17.889821Z","steps":["trace[2142932111] 'read index received' (duration: 143.684511ms)","trace[2142932111] 'applied index is now lower than readState.Index' (duration: 331.892599ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:17.889972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.88624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-19T08:08:17.890012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.205312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-19T08:08:17.89005Z","caller":"traceutil/trace.go:171","msg":"trace[733879061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23669; }","duration":"738.266933ms","start":"2026-03-19T08:08:17.151774Z","end":"2026-03-19T08:08:17.890041Z","steps":["trace[733879061] 'agreement among raft nodes before linearized reading' (duration: 738.11639ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:17.890062Z","caller":"traceutil/trace.go:171","msg":"trace[2063707808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23669; }","duration":"500.019162ms","start":"2026-03-19T08:08:17.390019Z","end":"2026-03-19T08:08:17.890038Z","steps":["trace[2063707808] 'agreement among raft nodes before linearized reading' (duration: 499.854339ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:17.890003Z","caller":"traceutil/trace.go:171","msg":"trace[1233414763] transaction","detail":"{read_only:false; response_revision:23668; number_of_response:1; }","duration":"468.523471ms","start":"2026-03-19T08:08:17.421433Z","end":"2026-03-19T08:08:17.889956Z","steps":["trace[1233414763] 'process raft request' (duration: 467.722324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:17.890084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.151763Z","time spent":"738.314514ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T08:08:17.890095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.389999Z","time spent":"500.091484ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T08:08:17.890214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.421412Z","time spent":"468.696375ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:17.892737Z","caller":"traceutil/trace.go:171","msg":"trace[1813522107] transaction","detail":"{read_only:false; response_revision:23670; number_of_response:1; }","duration":"113.838785ms","start":"2026-03-19T08:08:17.778871Z","end":"2026-03-19T08:08:17.892709Z","steps":["trace[1813522107] 'process raft request' (duration: 113.692622ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:18.106752Z","caller":"traceutil/trace.go:171","msg":"trace[777123378] linearizableReadLoop","detail":"{readStateIndex:24490; appliedIndex:24488; }","duration":"216.86886ms","start":"2026-03-19T08:08:17.889854Z","end":"2026-03-19T08:08:18.106722Z","steps":["trace[777123378] 'read index received' (duration: 2.922032ms)","trace[777123378] 'applied index is now lower than readState.Index' (duration: 213.945538ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:18.106782Z","caller":"traceutil/trace.go:171","msg":"trace[405468550] transaction","detail":"{read_only:false; response_revision:23672; number_of_response:1; }","duration":"325.657087ms","start":"2026-03-19T08:08:17.781096Z","end":"2026-03-19T08:08:18.106753Z","steps":["trace[405468550] 'process raft request' (duration: 325.587045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.106919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.389688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T08:08:18.106959Z","caller":"traceutil/trace.go:171","msg":"trace[1252443854] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:23672; }","duration":"302.45495ms","start":"2026-03-19T08:08:17.804494Z","end":"2026-03-19T08:08:18.106949Z","steps":["trace[1252443854] 'agreement among raft nodes before linearized reading' (duration: 302.336127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.106983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.80444Z","time spent":"302.535811ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":32,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-03-19T08:08:18.107097Z","caller":"traceutil/trace.go:171","msg":"trace[1862706677] transaction","detail":"{read_only:false; response_revision:23671; number_of_response:1; }","duration":"327.469805ms","start":"2026-03-19T08:08:17.77962Z","end":"2026-03-19T08:08:18.10709Z","steps":["trace[1862706677] 'process raft request' (duration: 326.913223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.107145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.779611Z","time spent":"327.511615ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:18.107252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.288553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-19T08:08:18.107273Z","caller":"traceutil/trace.go:171","msg":"trace[985787678] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:23672; }","duration":"582.337544ms","start":"2026-03-19T08:08:17.524931Z","end":"2026-03-19T08:08:18.107269Z","steps":["trace[985787678] 'agreement among raft nodes before linearized reading' (duration: 582.228402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.107294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.524918Z","time spent":"582.373665ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-03-19T08:08:18.106929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.781088Z","time spent":"325.756669ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:18.10757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.032019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-03-19T08:08:18.107593Z","caller":"traceutil/trace.go:171","msg":"trace[783430980] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:23672; }","duration":"670.101631ms","start":"2026-03-19T08:08:17.437484Z","end":"2026-03-19T08:08:18.107586Z","steps":["trace[783430980] 'agreement among raft nodes before linearized reading' (duration: 669.921097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.10761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.437475Z","time spent":"670.131131ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":177,"response size":33,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-19T08:08:18.352709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.038176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:18.353236Z","caller":"traceutil/trace.go:171","msg":"trace[160256418] transaction","detail":"{read_only:false; response_revision:23673; number_of_response:1; }","duration":"459.182545ms","start":"2026-03-19T08:08:17.894025Z","end":"2026-03-19T08:08:18.353208Z","steps":["trace[160256418] 'process raft request' (duration: 307.530286ms)","trace[160256418] 'compare' (duration: 150.862732ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:18.353285Z","caller":"traceutil/trace.go:171","msg":"trace[1545598289] linearizableReadLoop","detail":"{readStateIndex:24492; appliedIndex:24490; }","duration":"246.459502ms","start":"2026-03-19T08:08:18.106808Z","end":"2026-03-19T08:08:18.353268Z","steps":["trace[1545598289] 'read index received' (duration: 94.764173ms)","trace[1545598289] 'applied index is now lower than readState.Index' (duration: 151.693209ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:18.353269Z","caller":"traceutil/trace.go:171","msg":"trace[487738501] transaction","detail":"{read_only:false; response_revision:23674; number_of_response:1; }","duration":"343.054802ms","start":"2026-03-19T08:08:18.010181Z","end":"2026-03-19T08:08:18.353236Z","steps":["trace[487738501] 'process raft request' (duration: 342.635633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.353333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.894007Z","time spent":"459.277736ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:18.35342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.900431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:18.353444Z","caller":"traceutil/trace.go:171","msg":"trace[1386571200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23674; }","duration":"460.980592ms","start":"2026-03-19T08:08:17.892455Z","end":"2026-03-19T08:08:18.353436Z","steps":["trace[1386571200] 'agreement among raft nodes before linearized reading' (duration: 460.929741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.353428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:18.010155Z","time spent":"343.181195ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:18.353461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:17.892429Z","time spent":"461.028243ms","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T08:08:18.353489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.57425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:18.353524Z","caller":"traceutil/trace.go:171","msg":"trace[1938141166] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:23674; }","duration":"164.660902ms","start":"2026-03-19T08:08:18.188851Z","end":"2026-03-19T08:08:18.353512Z","steps":["trace[1938141166] 'agreement among raft nodes before linearized reading' (duration: 164.59079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:18.353497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.317238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:18.353581Z","caller":"traceutil/trace.go:171","msg":"trace[1653521927] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:23674; }","duration":"196.469251ms","start":"2026-03-19T08:08:18.157087Z","end":"2026-03-19T08:08:18.353556Z","steps":["trace[1653521927] 'agreement among raft nodes before linearized reading' (duration: 196.317958ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:18.730151Z","caller":"traceutil/trace.go:171","msg":"trace[2001336806] transaction","detail":"{read_only:false; response_revision:23677; number_of_response:1; }","duration":"142.348284ms","start":"2026-03-19T08:08:18.587745Z","end":"2026-03-19T08:08:18.730093Z","steps":["trace[2001336806] 'process raft request' (duration: 140.710309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:19.354859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.929564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-19T08:08:19.354961Z","caller":"traceutil/trace.go:171","msg":"trace[1719092605] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23679; }","duration":"132.070866ms","start":"2026-03-19T08:08:19.222867Z","end":"2026-03-19T08:08:19.354938Z","steps":["trace[1719092605] 'range keys from in-memory index tree' (duration: 131.698939ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:19.538187Z","caller":"traceutil/trace.go:171","msg":"trace[2114445530] transaction","detail":"{read_only:false; response_revision:23680; number_of_response:1; }","duration":"111.930703ms","start":"2026-03-19T08:08:19.426219Z","end":"2026-03-19T08:08:19.53815Z","steps":["trace[2114445530] 'process raft request' (duration: 111.328491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:19.789905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.979904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:19.790039Z","caller":"traceutil/trace.go:171","msg":"trace[1238733781] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:23680; }","duration":"199.188208ms","start":"2026-03-19T08:08:19.590818Z","end":"2026-03-19T08:08:19.790006Z","steps":["trace[1238733781] 'count revisions from in-memory index tree' (duration: 198.878802ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:24.889264Z","caller":"traceutil/trace.go:171","msg":"trace[897699213] transaction","detail":"{read_only:false; response_revision:23718; number_of_response:1; }","duration":"124.857775ms","start":"2026-03-19T08:08:24.764377Z","end":"2026-03-19T08:08:24.889235Z","steps":["trace[897699213] 'process raft request' (duration: 124.670471ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:29.412645Z","caller":"traceutil/trace.go:171","msg":"trace[688334123] transaction","detail":"{read_only:false; response_revision:23746; number_of_response:1; }","duration":"194.017149ms","start":"2026-03-19T08:08:29.218599Z","end":"2026-03-19T08:08:29.412616Z","steps":["trace[688334123] 'process raft request' (duration: 193.840346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:29.738552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.801423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-19T08:08:29.739135Z","caller":"traceutil/trace.go:171","msg":"trace[339694319] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23746; }","duration":"259.972367ms","start":"2026-03-19T08:08:29.478658Z","end":"2026-03-19T08:08:29.738631Z","steps":["trace[339694319] 'range keys from in-memory index tree' (duration: 259.534106ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:29.768602Z","caller":"traceutil/trace.go:171","msg":"trace[1661790102] transaction","detail":"{read_only:false; response_revision:23747; number_of_response:1; }","duration":"185.473479ms","start":"2026-03-19T08:08:29.583089Z","end":"2026-03-19T08:08:29.768562Z","steps":["trace[1661790102] 'process raft request' (duration: 185.071151ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:30.066808Z","caller":"traceutil/trace.go:171","msg":"trace[1235759494] transaction","detail":"{read_only:false; response_revision:23748; number_of_response:1; }","duration":"124.028138ms","start":"2026-03-19T08:08:29.942752Z","end":"2026-03-19T08:08:30.06678Z","steps":["trace[1235759494] 'process raft request' (duration: 123.850854ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:30.534325Z","caller":"traceutil/trace.go:171","msg":"trace[380601469] transaction","detail":"{read_only:false; response_revision:23749; number_of_response:1; }","duration":"590.749741ms","start":"2026-03-19T08:08:29.943544Z","end":"2026-03-19T08:08:30.534294Z","steps":["trace[380601469] 'process raft request' (duration: 590.527357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:30.534533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:29.943513Z","time spent":"590.895805ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:30.534693Z","caller":"traceutil/trace.go:171","msg":"trace[1606820121] transaction","detail":"{read_only:false; response_revision:23750; number_of_response:1; }","duration":"589.26993ms","start":"2026-03-19T08:08:29.94541Z","end":"2026-03-19T08:08:30.53468Z","steps":["trace[1606820121] 'process raft request' (duration: 588.837592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:30.534849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:29.945382Z","time spent":"589.420784ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:30.534885Z","caller":"traceutil/trace.go:171","msg":"trace[1349404175] linearizableReadLoop","detail":"{readStateIndex:24572; appliedIndex:24568; }","duration":"585.061432ms","start":"2026-03-19T08:08:29.94979Z","end":"2026-03-19T08:08:30.534852Z","steps":["trace[1349404175] 'read index received' (duration: 116.906688ms)","trace[1349404175] 'applied index is now lower than readState.Index' (duration: 468.151964ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:30.534963Z","caller":"traceutil/trace.go:171","msg":"trace[262027886] transaction","detail":"{read_only:false; response_revision:23751; number_of_response:1; }","duration":"588.81127ms","start":"2026-03-19T08:08:29.946139Z","end":"2026-03-19T08:08:30.53495Z","steps":["trace[262027886] 'process raft request' (duration: 588.402722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:30.535036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:29.946115Z","time spent":"588.885652ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:30.535218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.419819ms","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-03-19T08:08:30.535266Z","caller":"traceutil/trace.go:171","msg":"trace[1694208116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23751; }","duration":"585.511761ms","start":"2026-03-19T08:08:29.949742Z","end":"2026-03-19T08:08:30.535254Z","steps":["trace[1694208116] 'agreement among raft nodes before linearized reading' (duration: 585.335508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:30.535315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:29.949733Z","time spent":"585.574012ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T08:08:31.03601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725018703437106,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T08:08:31.146919Z","caller":"traceutil/trace.go:171","msg":"trace[211452204] transaction","detail":"{read_only:false; response_revision:23754; number_of_response:1; }","duration":"749.08288ms","start":"2026-03-19T08:08:30.397804Z","end":"2026-03-19T08:08:31.146887Z","steps":["trace[211452204] 'process raft request' (duration: 749.013389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:31.147159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.397761Z","time spent":"749.256804ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:31.147537Z","caller":"traceutil/trace.go:171","msg":"trace[2059972646] transaction","detail":"{read_only:false; response_revision:23753; number_of_response:1; }","duration":"994.48195ms","start":"2026-03-19T08:08:30.153001Z","end":"2026-03-19T08:08:31.147483Z","steps":["trace[2059972646] 'process raft request' (duration: 993.726795ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:31.147546Z","caller":"traceutil/trace.go:171","msg":"trace[409674965] transaction","detail":"{read_only:false; response_revision:23752; number_of_response:1; }","duration":"994.46084ms","start":"2026-03-19T08:08:30.152991Z","end":"2026-03-19T08:08:31.147451Z","steps":["trace[409674965] 'process raft request' (duration: 976.614735ms)","trace[409674965] 'compare' (duration: 14.692619ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:31.147791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.152971Z","time spent":"994.692925ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:31.147906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.15296Z","time spent":"994.808407ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:31.453187Z","caller":"traceutil/trace.go:171","msg":"trace[2029991406] transaction","detail":"{read_only:false; response_revision:23755; number_of_response:1; }","duration":"913.12619ms","start":"2026-03-19T08:08:30.540012Z","end":"2026-03-19T08:08:31.453138Z","steps":["trace[2029991406] 'process raft request' (duration: 896.222775ms)","trace[2029991406] 'compare' (duration: 16.515926ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:31.453194Z","caller":"traceutil/trace.go:171","msg":"trace[625102035] linearizableReadLoop","detail":"{readStateIndex:24577; appliedIndex:24572; }","duration":"918.180155ms","start":"2026-03-19T08:08:30.534974Z","end":"2026-03-19T08:08:31.453154Z","steps":["trace[625102035] 'read index received' (duration: 594.596751ms)","trace[625102035] 'applied index is now lower than readState.Index' (duration: 323.581894ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:31.453348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.53999Z","time spent":"913.267773ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:31.453564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06245221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-19T08:08:31.453597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100748304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-19T08:08:31.453631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.971022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:31.453652Z","caller":"traceutil/trace.go:171","msg":"trace[927684187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23756; }","duration":"1.062574022s","start":"2026-03-19T08:08:30.391058Z","end":"2026-03-19T08:08:31.453632Z","steps":["trace[927684187] 'agreement among raft nodes before linearized reading' (duration: 1.062418199s)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:31.453666Z","caller":"traceutil/trace.go:171","msg":"trace[1391109724] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:23756; }","duration":"1.100893138s","start":"2026-03-19T08:08:30.352753Z","end":"2026-03-19T08:08:31.453646Z","steps":["trace[1391109724] 'agreement among raft nodes before linearized reading' (duration: 1.100746375s)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:31.453691Z","caller":"traceutil/trace.go:171","msg":"trace[136879629] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:23756; }","duration":"323.092484ms","start":"2026-03-19T08:08:31.13058Z","end":"2026-03-19T08:08:31.453672Z","steps":["trace[136879629] 'agreement among raft nodes before linearized reading' (duration: 322.961091ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:31.453643Z","caller":"traceutil/trace.go:171","msg":"trace[1866923844] transaction","detail":"{read_only:false; response_revision:23756; number_of_response:1; }","duration":"656.621797ms","start":"2026-03-19T08:08:30.796963Z","end":"2026-03-19T08:08:31.453585Z","steps":["trace[1866923844] 'process raft request' (duration: 656.025315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:31.453739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:31.130548Z","time spent":"323.180506ms","remote":"127.0.0.1:60418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-03-19T08:08:31.453734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.352725Z","time spent":"1.10098887s","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-03-19T08:08:31.453745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.302487ms","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-03-19T08:08:31.453738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.108184ms","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-03-19T08:08:31.453798Z","caller":"traceutil/trace.go:171","msg":"trace[1633401117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23756; }","duration":"537.40545ms","start":"2026-03-19T08:08:30.916375Z","end":"2026-03-19T08:08:31.453781Z","steps":["trace[1633401117] 'agreement among raft nodes before linearized reading' (duration: 537.208296ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:31.453817Z","caller":"traceutil/trace.go:171","msg":"trace[1105125353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23756; }","duration":"820.261728ms","start":"2026-03-19T08:08:30.633533Z","end":"2026-03-19T08:08:31.453795Z","steps":["trace[1105125353] 'agreement among raft nodes before linearized reading' (duration: 820.010582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:31.453841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.916348Z","time spent":"537.485211ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T08:08:31.453855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.633509Z","time spent":"820.339489ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T08:08:31.453896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.796925Z","time spent":"656.837191ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:31.453985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047312141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-19T08:08:31.454019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.7671ms","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-03-19T08:08:31.454026Z","caller":"traceutil/trace.go:171","msg":"trace[887115492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23756; }","duration":"1.047437544s","start":"2026-03-19T08:08:30.406578Z","end":"2026-03-19T08:08:31.454016Z","steps":["trace[887115492] 'agreement among raft nodes before linearized reading' (duration: 1.047164529s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:31.454097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.406556Z","time spent":"1.047527846s","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T08:08:31.453692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.391023Z","time spent":"1.062661104s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T08:08:31.454142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.576265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:31.454202Z","caller":"traceutil/trace.go:171","msg":"trace[1767023612] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:23756; }","duration":"101.688118ms","start":"2026-03-19T08:08:31.352496Z","end":"2026-03-19T08:08:31.454184Z","steps":["trace[1767023612] 'agreement among raft nodes before linearized reading' (duration: 101.589516ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:31.454043Z","caller":"traceutil/trace.go:171","msg":"trace[2065786198] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23756; }","duration":"608.828041ms","start":"2026-03-19T08:08:30.845206Z","end":"2026-03-19T08:08:31.454034Z","steps":["trace[2065786198] 'agreement among raft nodes before linearized reading' (duration: 608.77927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:31.454267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:30.845192Z","time spent":"609.055256ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-03-19T08:08:32.031675Z","caller":"traceutil/trace.go:171","msg":"trace[576085822] linearizableReadLoop","detail":"{readStateIndex:24581; appliedIndex:24577; }","duration":"569.072305ms","start":"2026-03-19T08:08:31.462579Z","end":"2026-03-19T08:08:32.031651Z","steps":["trace[576085822] 'read index received' (duration: 14.704079ms)","trace[576085822] 'applied index is now lower than readState.Index' (duration: 554.366626ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:32.031986Z","caller":"traceutil/trace.go:171","msg":"trace[1369975564] transaction","detail":"{read_only:false; response_revision:23758; number_of_response:1; }","duration":"572.400005ms","start":"2026-03-19T08:08:31.459569Z","end":"2026-03-19T08:08:32.031969Z","steps":["trace[1369975564] 'process raft request' (duration: 571.800963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.032127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:31.459553Z","time spent":"572.479427ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:32.032335Z","caller":"traceutil/trace.go:171","msg":"trace[1654780809] transaction","detail":"{read_only:false; response_revision:23759; number_of_response:1; }","duration":"572.546258ms","start":"2026-03-19T08:08:31.459778Z","end":"2026-03-19T08:08:32.032324Z","steps":["trace[1654780809] 'process raft request' (duration: 571.766932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.032415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:31.459771Z","time spent":"572.602389ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:32.032586Z","caller":"traceutil/trace.go:171","msg":"trace[1585728979] transaction","detail":"{read_only:false; response_revision:23760; number_of_response:1; }","duration":"572.738302ms","start":"2026-03-19T08:08:31.459837Z","end":"2026-03-19T08:08:32.032576Z","steps":["trace[1585728979] 'process raft request' (duration: 571.765362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.032658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:31.459832Z","time spent":"572.793063ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:32.03281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.23181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:32.03285Z","caller":"traceutil/trace.go:171","msg":"trace[269252117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23760; }","duration":"570.318302ms","start":"2026-03-19T08:08:31.462523Z","end":"2026-03-19T08:08:32.032841Z","steps":["trace[269252117] 'agreement among raft nodes before linearized reading' (duration: 570.2507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.032878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:31.462497Z","time spent":"570.376033ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T08:08:32.043253Z","caller":"traceutil/trace.go:171","msg":"trace[1624858481] transaction","detail":"{read_only:false; response_revision:23761; number_of_response:1; }","duration":"267.624727ms","start":"2026-03-19T08:08:31.775606Z","end":"2026-03-19T08:08:32.043231Z","steps":["trace[1624858481] 'process raft request' (duration: 267.366961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.043317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.235099ms","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-03-19T08:08:32.043423Z","caller":"traceutil/trace.go:171","msg":"trace[1019783708] 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:23761; }","duration":"277.375562ms","start":"2026-03-19T08:08:31.766022Z","end":"2026-03-19T08:08:32.043398Z","steps":["trace[1019783708] 'agreement among raft nodes before linearized reading' (duration: 277.158018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.04377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.998335ms","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-03-19T08:08:32.043815Z","caller":"traceutil/trace.go:171","msg":"trace[125444068] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:23761; }","duration":"240.085907ms","start":"2026-03-19T08:08:31.803718Z","end":"2026-03-19T08:08:32.043804Z","steps":["trace[125444068] 'agreement among raft nodes before linearized reading' (duration: 240.011596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.497491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.808255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:32.49761Z","caller":"traceutil/trace.go:171","msg":"trace[624822458] linearizableReadLoop","detail":"{readStateIndex:24583; appliedIndex:24582; }","duration":"196.109804ms","start":"2026-03-19T08:08:32.301482Z","end":"2026-03-19T08:08:32.497591Z","steps":["trace[624822458] 'read index received' (duration: 39.953601ms)","trace[624822458] 'applied index is now lower than readState.Index' (duration: 156.154813ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:32.497883Z","caller":"traceutil/trace.go:171","msg":"trace[361032684] transaction","detail":"{read_only:false; response_revision:23762; number_of_response:1; }","duration":"414.669519ms","start":"2026-03-19T08:08:32.08317Z","end":"2026-03-19T08:08:32.49784Z","steps":["trace[361032684] 'process raft request' (duration: 258.412953ms)","trace[361032684] 'compare' (duration: 155.55548ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:32.497957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:32.083145Z","time spent":"414.775552ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:32.498256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.782028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-03-19T08:08:32.498294Z","caller":"traceutil/trace.go:171","msg":"trace[872248038] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:23762; }","duration":"196.86557ms","start":"2026-03-19T08:08:32.301418Z","end":"2026-03-19T08:08:32.498284Z","steps":["trace[872248038] 'agreement among raft nodes before linearized reading' (duration: 196.724627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:32.910512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.109848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:32.910641Z","caller":"traceutil/trace.go:171","msg":"trace[2032656173] transaction","detail":"{read_only:false; response_revision:23763; number_of_response:1; }","duration":"405.766072ms","start":"2026-03-19T08:08:32.504853Z","end":"2026-03-19T08:08:32.910619Z","steps":["trace[2032656173] 'process raft request' (duration: 146.407068ms)","trace[2032656173] 'compare' (duration: 257.471274ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:32.91071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:32.504833Z","time spent":"405.843253ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:33.127748Z","caller":"traceutil/trace.go:171","msg":"trace[1353177931] transaction","detail":"{read_only:false; response_revision:23764; number_of_response:1; }","duration":"585.622433ms","start":"2026-03-19T08:08:32.54209Z","end":"2026-03-19T08:08:33.127712Z","steps":["trace[1353177931] 'process raft request' (duration: 585.319917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:33.127976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:32.542064Z","time spent":"585.790737ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:33.128225Z","caller":"traceutil/trace.go:171","msg":"trace[1420029117] linearizableReadLoop","detail":"{readStateIndex:24586; appliedIndex:24583; }","duration":"570.305082ms","start":"2026-03-19T08:08:32.557883Z","end":"2026-03-19T08:08:33.128188Z","steps":["trace[1420029117] 'read index received' (duration: 93.342362ms)","trace[1420029117] 'applied index is now lower than readState.Index' (duration: 476.96133ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:33.128353Z","caller":"traceutil/trace.go:171","msg":"trace[1093231409] transaction","detail":"{read_only:false; response_revision:23765; number_of_response:1; }","duration":"585.400378ms","start":"2026-03-19T08:08:32.542851Z","end":"2026-03-19T08:08:33.128252Z","steps":["trace[1093231409] 'process raft request' (duration: 584.785294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:33.128408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.520226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T08:08:33.128481Z","caller":"traceutil/trace.go:171","msg":"trace[1843485640] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:23765; }","duration":"570.67952ms","start":"2026-03-19T08:08:32.557788Z","end":"2026-03-19T08:08:33.128468Z","steps":["trace[1843485640] 'agreement among raft nodes before linearized reading' (duration: 570.522216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:33.128528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:32.557772Z","time spent":"570.745631ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":32,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-19T08:08:33.128546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:32.542833Z","time spent":"585.609323ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:39.763412Z","caller":"traceutil/trace.go:171","msg":"trace[613599260] linearizableReadLoop","detail":"{readStateIndex:24699; appliedIndex:24698; }","duration":"214.96481ms","start":"2026-03-19T08:08:39.548413Z","end":"2026-03-19T08:08:39.763378Z","steps":["trace[613599260] 'read index received' (duration: 198.117796ms)","trace[613599260] 'applied index is now lower than readState.Index' (duration: 16.845284ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:39.763514Z","caller":"traceutil/trace.go:171","msg":"trace[428105371] transaction","detail":"{read_only:false; response_revision:23875; number_of_response:1; }","duration":"238.27283ms","start":"2026-03-19T08:08:39.525191Z","end":"2026-03-19T08:08:39.763464Z","steps":["trace[428105371] 'process raft request' (duration: 221.420635ms)","trace[428105371] 'compare' (duration: 16.084539ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:39.763616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.167984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:39.763685Z","caller":"traceutil/trace.go:171","msg":"trace[1399477673] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:23875; }","duration":"215.277007ms","start":"2026-03-19T08:08:39.548389Z","end":"2026-03-19T08:08:39.763666Z","steps":["trace[1399477673] 'agreement among raft nodes before linearized reading' (duration: 215.142334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:39.763729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.625075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39060324-5b5d-4337-b26a-690802c4710a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-03-19T08:08:39.763828Z","caller":"traceutil/trace.go:171","msg":"trace[2093795874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39060324-5b5d-4337-b26a-690802c4710a; range_end:; response_count:1; response_revision:23875; }","duration":"148.671776ms","start":"2026-03-19T08:08:39.615082Z","end":"2026-03-19T08:08:39.763754Z","steps":["trace[2093795874] 'agreement among raft nodes before linearized reading' (duration: 148.481622ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:40.215147Z","caller":"traceutil/trace.go:171","msg":"trace[242755200] transaction","detail":"{read_only:false; response_revision:23876; number_of_response:1; }","duration":"124.007837ms","start":"2026-03-19T08:08:40.09111Z","end":"2026-03-19T08:08:40.215118Z","steps":["trace[242755200] 'process raft request' (duration: 123.812323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:40.514671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.571842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:40.514957Z","caller":"traceutil/trace.go:171","msg":"trace[1899155517] linearizableReadLoop","detail":"{readStateIndex:24703; appliedIndex:24699; }","duration":"422.516053ms","start":"2026-03-19T08:08:40.09242Z","end":"2026-03-19T08:08:40.514936Z","steps":["trace[1899155517] 'read index received' (duration: 122.565616ms)","trace[1899155517] 'applied index is now lower than readState.Index' (duration: 299.948977ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:40.515097Z","caller":"traceutil/trace.go:171","msg":"trace[286740002] transaction","detail":"{read_only:false; response_revision:23877; number_of_response:1; }","duration":"423.851552ms","start":"2026-03-19T08:08:40.091221Z","end":"2026-03-19T08:08:40.515073Z","steps":["trace[286740002] 'process raft request' (duration: 315.76227ms)","trace[286740002] 'compare' (duration: 107.262994ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:40.515151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.726627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18509"} {"level":"info","ts":"2026-03-19T08:08:40.515076Z","caller":"traceutil/trace.go:171","msg":"trace[948839170] transaction","detail":"{read_only:false; response_revision:23878; number_of_response:1; }","duration":"423.73377ms","start":"2026-03-19T08:08:40.091244Z","end":"2026-03-19T08:08:40.514978Z","steps":["trace[948839170] 'process raft request' (duration: 423.555106ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:40.515231Z","caller":"traceutil/trace.go:171","msg":"trace[973096945] transaction","detail":"{read_only:false; response_revision:23879; number_of_response:1; }","duration":"423.923163ms","start":"2026-03-19T08:08:40.091294Z","end":"2026-03-19T08:08:40.515217Z","steps":["trace[973096945] 'process raft request' (duration: 423.593656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:40.515309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:40.091275Z","time spent":"423.995895ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:40.515188Z","caller":"traceutil/trace.go:171","msg":"trace[1018899795] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23879; }","duration":"422.778339ms","start":"2026-03-19T08:08:40.092398Z","end":"2026-03-19T08:08:40.515176Z","steps":["trace[1018899795] 'agreement among raft nodes before linearized reading' (duration: 422.602805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:40.515445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:40.091219Z","time spent":"424.085177ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:40.515495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:40.09239Z","time spent":"423.097736ms","remote":"127.0.0.1:34174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18533,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-19T08:08:40.5152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:40.091199Z","time spent":"423.952263ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:40.78576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.501355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:40.785884Z","caller":"traceutil/trace.go:171","msg":"trace[1008204351] linearizableReadLoop","detail":"{readStateIndex:24704; appliedIndex:24703; }","duration":"211.770724ms","start":"2026-03-19T08:08:40.57409Z","end":"2026-03-19T08:08:40.785861Z","steps":["trace[1008204351] 'read index received' (duration: 79.983893ms)","trace[1008204351] 'applied index is now lower than readState.Index' (duration: 131.785271ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:40.78599Z","caller":"traceutil/trace.go:171","msg":"trace[1161130510] transaction","detail":"{read_only:false; response_revision:23880; number_of_response:1; }","duration":"261.838205ms","start":"2026-03-19T08:08:40.52414Z","end":"2026-03-19T08:08:40.785978Z","steps":["trace[1161130510] 'process raft request' (duration: 129.993953ms)","trace[1161130510] 'compare' (duration: 131.200599ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:40.786227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.144241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:40.786261Z","caller":"traceutil/trace.go:171","msg":"trace[1310505690] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23880; }","duration":"212.213673ms","start":"2026-03-19T08:08:40.574036Z","end":"2026-03-19T08:08:40.786249Z","steps":["trace[1310505690] 'agreement among raft nodes before linearized reading' (duration: 212.143511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:40.786588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.746665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-19T08:08:40.786567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.350121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-03-19T08:08:40.786623Z","caller":"traceutil/trace.go:171","msg":"trace[591893700] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:23880; }","duration":"204.835327ms","start":"2026-03-19T08:08:40.581776Z","end":"2026-03-19T08:08:40.786612Z","steps":["trace[591893700] 'agreement among raft nodes before linearized reading' (duration: 204.765746ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:40.786656Z","caller":"traceutil/trace.go:171","msg":"trace[1028754645] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23880; }","duration":"208.456903ms","start":"2026-03-19T08:08:40.578173Z","end":"2026-03-19T08:08:40.78663Z","steps":["trace[1028754645] 'agreement among raft nodes before linearized reading' (duration: 208.211098ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:41.026595Z","caller":"traceutil/trace.go:171","msg":"trace[2040953079] transaction","detail":"{read_only:false; response_revision:23882; number_of_response:1; }","duration":"164.195083ms","start":"2026-03-19T08:08:40.862348Z","end":"2026-03-19T08:08:41.026543Z","steps":["trace[2040953079] 'process raft request' (duration: 151.803923ms)","trace[2040953079] 'compare' (duration: 12.155055ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:41.026686Z","caller":"traceutil/trace.go:171","msg":"trace[303776239] linearizableReadLoop","detail":"{readStateIndex:24706; appliedIndex:24705; }","duration":"156.513281ms","start":"2026-03-19T08:08:40.870129Z","end":"2026-03-19T08:08:41.026643Z","steps":["trace[303776239] 'read index received' (duration: 144.0462ms)","trace[303776239] 'applied index is now lower than readState.Index' (duration: 12.436451ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:41.02668Z","caller":"traceutil/trace.go:171","msg":"trace[327029030] transaction","detail":"{read_only:false; response_revision:23883; number_of_response:1; }","duration":"139.289619ms","start":"2026-03-19T08:08:40.887356Z","end":"2026-03-19T08:08:41.026646Z","steps":["trace[327029030] 'process raft request' (duration: 139.179857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:41.026937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.611616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:08:41.026991Z","caller":"traceutil/trace.go:171","msg":"trace[1781575325] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:23883; }","duration":"120.737149ms","start":"2026-03-19T08:08:40.906239Z","end":"2026-03-19T08:08:41.026976Z","steps":["trace[1781575325] 'agreement among raft nodes before linearized reading' (duration: 120.605596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:41.026965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.811717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-03-19T08:08:41.028851Z","caller":"traceutil/trace.go:171","msg":"trace[2044078391] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:23883; }","duration":"158.707327ms","start":"2026-03-19T08:08:40.870107Z","end":"2026-03-19T08:08:41.028814Z","steps":["trace[2044078391] 'agreement among raft nodes before linearized reading' (duration: 156.629943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:41.026965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.396954ms","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-03-19T08:08:41.028961Z","caller":"traceutil/trace.go:171","msg":"trace[133069561] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:23883; }","duration":"105.449697ms","start":"2026-03-19T08:08:40.923481Z","end":"2026-03-19T08:08:41.02893Z","steps":["trace[133069561] 'agreement among raft nodes before linearized reading' (duration: 103.383364ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:47.547018Z","caller":"traceutil/trace.go:171","msg":"trace[65220157] transaction","detail":"{read_only:false; response_revision:23949; number_of_response:1; }","duration":"364.133577ms","start":"2026-03-19T08:08:47.182857Z","end":"2026-03-19T08:08:47.546991Z","steps":["trace[65220157] 'process raft request' (duration: 338.199743ms)","trace[65220157] 'compare' (duration: 25.73664ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:47.547191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:47.18282Z","time spent":"364.28294ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:47.547268Z","caller":"traceutil/trace.go:171","msg":"trace[83175475] transaction","detail":"{read_only:false; response_revision:23950; number_of_response:1; }","duration":"340.469041ms","start":"2026-03-19T08:08:47.206773Z","end":"2026-03-19T08:08:47.547242Z","steps":["trace[83175475] 'process raft request' (duration: 340.149704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:47.547394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:47.206742Z","time spent":"340.580443ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:47.547387Z","caller":"traceutil/trace.go:171","msg":"trace[1020449487] transaction","detail":"{read_only:false; response_revision:23951; number_of_response:1; }","duration":"336.60674ms","start":"2026-03-19T08:08:47.210725Z","end":"2026-03-19T08:08:47.547331Z","steps":["trace[1020449487] 'process raft request' (duration: 336.406895ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:08:47.547497Z","caller":"traceutil/trace.go:171","msg":"trace[13919287] transaction","detail":"{read_only:false; response_revision:23952; number_of_response:1; }","duration":"333.688679ms","start":"2026-03-19T08:08:47.213769Z","end":"2026-03-19T08:08:47.547457Z","steps":["trace[13919287] 'process raft request' (duration: 333.475365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:47.547584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:47.210693Z","time spent":"336.790634ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:47.547707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:47.213754Z","time spent":"333.835602ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:47.877662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.925109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:08:47.877922Z","caller":"traceutil/trace.go:171","msg":"trace[1051892246] linearizableReadLoop","detail":"{readStateIndex:24780; appliedIndex:24778; }","duration":"166.271448ms","start":"2026-03-19T08:08:47.711626Z","end":"2026-03-19T08:08:47.877898Z","steps":["trace[1051892246] 'read index received' (duration: 23.520234ms)","trace[1051892246] 'applied index is now lower than readState.Index' (duration: 142.749694ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:08:47.877932Z","caller":"traceutil/trace.go:171","msg":"trace[18141547] transaction","detail":"{read_only:false; response_revision:23953; number_of_response:1; }","duration":"316.038719ms","start":"2026-03-19T08:08:47.561852Z","end":"2026-03-19T08:08:47.877891Z","steps":["trace[18141547] 'process raft request' (duration: 141.735073ms)","trace[18141547] 'compare' (duration: 173.759695ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:08:47.87804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:47.56184Z","time spent":"316.153161ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:08:47.878067Z","caller":"traceutil/trace.go:171","msg":"trace[279969872] transaction","detail":"{read_only:false; response_revision:23954; number_of_response:1; }","duration":"313.806162ms","start":"2026-03-19T08:08:47.564227Z","end":"2026-03-19T08:08:47.878033Z","steps":["trace[279969872] 'process raft request' (duration: 313.575407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:08:47.878249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:08:47.564187Z","time spent":"313.966116ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:08:47.878234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.606655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-03-19T08:08:47.878301Z","caller":"traceutil/trace.go:171","msg":"trace[636681029] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:23954; }","duration":"166.712457ms","start":"2026-03-19T08:08:47.711572Z","end":"2026-03-19T08:08:47.878285Z","steps":["trace[636681029] 'agreement among raft nodes before linearized reading' (duration: 166.471672ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:04.974032Z","caller":"traceutil/trace.go:171","msg":"trace[435823597] transaction","detail":"{read_only:false; response_revision:24078; number_of_response:1; }","duration":"315.698561ms","start":"2026-03-19T08:09:04.658299Z","end":"2026-03-19T08:09:04.973997Z","steps":["trace[435823597] 'process raft request' (duration: 315.467416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:04.974254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:09:04.658283Z","time spent":"315.850975ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:09:05.081466Z","caller":"traceutil/trace.go:171","msg":"trace[711702844] transaction","detail":"{read_only:false; response_revision:24080; number_of_response:1; }","duration":"422.176895ms","start":"2026-03-19T08:09:04.659258Z","end":"2026-03-19T08:09:05.081435Z","steps":["trace[711702844] 'process raft request' (duration: 422.072323ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.081534Z","caller":"traceutil/trace.go:171","msg":"trace[1628781455] transaction","detail":"{read_only:false; response_revision:24079; number_of_response:1; }","duration":"422.371749ms","start":"2026-03-19T08:09:04.659115Z","end":"2026-03-19T08:09:05.081487Z","steps":["trace[1628781455] 'process raft request' (duration: 360.238457ms)","trace[1628781455] 'compare' (duration: 61.754675ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:05.081751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:09:04.65911Z","time spent":"422.520732ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T08:09:05.081785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:09:04.659252Z","time spent":"422.311118ms","remote":"127.0.0.1:34276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:09:05.081951Z","caller":"traceutil/trace.go:171","msg":"trace[913443045] transaction","detail":"{read_only:false; response_revision:24081; number_of_response:1; }","duration":"140.087288ms","start":"2026-03-19T08:09:04.94183Z","end":"2026-03-19T08:09:05.081917Z","steps":["trace[913443045] 'process raft request' (duration: 139.554447ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.081937Z","caller":"traceutil/trace.go:171","msg":"trace[1552789040] linearizableReadLoop","detail":"{readStateIndex:24909; appliedIndex:24906; }","duration":"321.647297ms","start":"2026-03-19T08:09:04.760243Z","end":"2026-03-19T08:09:05.08189Z","steps":["trace[1552789040] 'read index received' (duration: 213.558399ms)","trace[1552789040] 'applied index is now lower than readState.Index' (duration: 108.085768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:05.082114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.866281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:09:05.082232Z","caller":"traceutil/trace.go:171","msg":"trace[697803814] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24081; }","duration":"322.008784ms","start":"2026-03-19T08:09:04.760179Z","end":"2026-03-19T08:09:05.082188Z","steps":["trace[697803814] 'agreement among raft nodes before linearized reading' (duration: 321.82703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:05.082313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:09:04.760155Z","time spent":"322.144586ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-19T08:09:05.082361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.130485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10851"} {"level":"info","ts":"2026-03-19T08:09:05.082416Z","caller":"traceutil/trace.go:171","msg":"trace[438663194] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24081; }","duration":"177.227667ms","start":"2026-03-19T08:09:04.905173Z","end":"2026-03-19T08:09:05.082401Z","steps":["trace[438663194] 'agreement among raft nodes before linearized reading' (duration: 177.018203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:05.082419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.294723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-03-19T08:09:05.082537Z","caller":"traceutil/trace.go:171","msg":"trace[77406865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24081; }","duration":"110.429266ms","start":"2026-03-19T08:09:04.972085Z","end":"2026-03-19T08:09:05.082514Z","steps":["trace[77406865] 'agreement among raft nodes before linearized reading' (duration: 110.13672ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.455679Z","caller":"traceutil/trace.go:171","msg":"trace[1844675032] transaction","detail":"{read_only:false; response_revision:24082; number_of_response:1; }","duration":"362.274208ms","start":"2026-03-19T08:09:05.093361Z","end":"2026-03-19T08:09:05.455635Z","steps":["trace[1844675032] 'process raft request' (duration: 344.935775ms)","trace[1844675032] 'compare' (duration: 17.142579ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:05.455874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:09:05.093337Z","time spent":"362.428641ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10653,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:09:05.459236Z","caller":"traceutil/trace.go:171","msg":"trace[869768855] transaction","detail":"{read_only:false; response_revision:24083; number_of_response:1; }","duration":"196.201345ms","start":"2026-03-19T08:09:05.263006Z","end":"2026-03-19T08:09:05.459208Z","steps":["trace[869768855] 'process raft request' (duration: 195.696505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.459496Z","caller":"traceutil/trace.go:171","msg":"trace[29034275] transaction","detail":"{read_only:false; response_revision:24084; number_of_response:1; }","duration":"129.69366ms","start":"2026-03-19T08:09:05.329792Z","end":"2026-03-19T08:09:05.459486Z","steps":["trace[29034275] 'process raft request' (duration: 129.095367ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.721777Z","caller":"traceutil/trace.go:171","msg":"trace[1684516017] transaction","detail":"{read_only:false; response_revision:24092; number_of_response:1; }","duration":"100.774654ms","start":"2026-03-19T08:09:05.620977Z","end":"2026-03-19T08:09:05.721751Z","steps":["trace[1684516017] 'process raft request' (duration: 100.735163ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.722216Z","caller":"traceutil/trace.go:171","msg":"trace[1359892842] transaction","detail":"{read_only:false; response_revision:24086; number_of_response:1; }","duration":"255.915038ms","start":"2026-03-19T08:09:05.466287Z","end":"2026-03-19T08:09:05.722202Z","steps":["trace[1359892842] 'process raft request' (duration: 240.890833ms)","trace[1359892842] 'compare' (duration: 14.156136ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:05.722481Z","caller":"traceutil/trace.go:171","msg":"trace[1776665729] transaction","detail":"{read_only:false; response_revision:24087; number_of_response:1; }","duration":"253.992828ms","start":"2026-03-19T08:09:05.468453Z","end":"2026-03-19T08:09:05.722445Z","steps":["trace[1776665729] 'process raft request' (duration: 253.013907ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.722722Z","caller":"traceutil/trace.go:171","msg":"trace[1478015032] transaction","detail":"{read_only:false; response_revision:24088; number_of_response:1; }","duration":"250.153457ms","start":"2026-03-19T08:09:05.47256Z","end":"2026-03-19T08:09:05.722713Z","steps":["trace[1478015032] 'process raft request' (duration: 248.974012ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.722944Z","caller":"traceutil/trace.go:171","msg":"trace[1609041520] transaction","detail":"{read_only:false; response_revision:24089; number_of_response:1; }","duration":"105.958392ms","start":"2026-03-19T08:09:05.616976Z","end":"2026-03-19T08:09:05.722935Z","steps":["trace[1609041520] 'process raft request' (duration: 104.614553ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.72308Z","caller":"traceutil/trace.go:171","msg":"trace[1119270109] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"104.099173ms","start":"2026-03-19T08:09:05.61897Z","end":"2026-03-19T08:09:05.723069Z","steps":["trace[1119270109] 'process raft request' (duration: 102.667802ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:05.723211Z","caller":"traceutil/trace.go:171","msg":"trace[850797398] transaction","detail":"{read_only:false; response_revision:24091; number_of_response:1; }","duration":"103.9493ms","start":"2026-03-19T08:09:05.619253Z","end":"2026-03-19T08:09:05.723202Z","steps":["trace[850797398] 'process raft request' (duration: 102.426227ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:06.02109Z","caller":"traceutil/trace.go:171","msg":"trace[940129203] transaction","detail":"{read_only:false; response_revision:24093; number_of_response:1; }","duration":"272.623118ms","start":"2026-03-19T08:09:05.748427Z","end":"2026-03-19T08:09:06.021051Z","steps":["trace[940129203] 'process raft request' (duration: 197.959912ms)","trace[940129203] 'compare' (duration: 74.422871ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:06.021084Z","caller":"traceutil/trace.go:171","msg":"trace[723222251] linearizableReadLoop","detail":"{readStateIndex:24922; appliedIndex:24921; }","duration":"181.447746ms","start":"2026-03-19T08:09:05.839579Z","end":"2026-03-19T08:09:06.021027Z","steps":["trace[723222251] 'read index received' (duration: 106.705529ms)","trace[723222251] 'applied index is now lower than readState.Index' (duration: 74.740237ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:06.02141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.791553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-03-19T08:09:06.021433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.269176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T08:09:06.021519Z","caller":"traceutil/trace.go:171","msg":"trace[1699517514] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24093; }","duration":"181.963857ms","start":"2026-03-19T08:09:05.83953Z","end":"2026-03-19T08:09:06.021494Z","steps":["trace[1699517514] 'agreement among raft nodes before linearized reading' (duration: 181.592089ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:06.02158Z","caller":"traceutil/trace.go:171","msg":"trace[196293878] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24093; }","duration":"124.394579ms","start":"2026-03-19T08:09:05.897116Z","end":"2026-03-19T08:09:06.02151Z","steps":["trace[196293878] 'agreement among raft nodes before linearized reading' (duration: 124.175935ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:06.163333Z","caller":"traceutil/trace.go:171","msg":"trace[1883444200] transaction","detail":"{read_only:false; response_revision:24095; number_of_response:1; }","duration":"136.779268ms","start":"2026-03-19T08:09:06.026531Z","end":"2026-03-19T08:09:06.163311Z","steps":["trace[1883444200] 'process raft request' (duration: 136.541764ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:06.163363Z","caller":"traceutil/trace.go:171","msg":"trace[1085286512] transaction","detail":"{read_only:false; response_revision:24096; number_of_response:1; }","duration":"136.736408ms","start":"2026-03-19T08:09:06.026596Z","end":"2026-03-19T08:09:06.163332Z","steps":["trace[1085286512] 'process raft request' (duration: 136.638746ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:06.163312Z","caller":"traceutil/trace.go:171","msg":"trace[243799678] linearizableReadLoop","detail":"{readStateIndex:24925; appliedIndex:24922; }","duration":"135.247736ms","start":"2026-03-19T08:09:06.028039Z","end":"2026-03-19T08:09:06.163286Z","steps":["trace[243799678] 'read index received' (duration: 39.755163ms)","trace[243799678] 'applied index is now lower than readState.Index' (duration: 95.491033ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:06.163536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.47295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-03-19T08:09:06.163594Z","caller":"traceutil/trace.go:171","msg":"trace[988691330] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24096; }","duration":"135.549072ms","start":"2026-03-19T08:09:06.02803Z","end":"2026-03-19T08:09:06.163579Z","steps":["trace[988691330] 'agreement among raft nodes before linearized reading' (duration: 135.338268ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:09.625343Z","caller":"traceutil/trace.go:171","msg":"trace[1649972691] transaction","detail":"{read_only:false; response_revision:24119; number_of_response:1; }","duration":"151.030498ms","start":"2026-03-19T08:09:09.474266Z","end":"2026-03-19T08:09:09.625296Z","steps":["trace[1649972691] 'process raft request' (duration: 150.822763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:09.903082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.518152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:09:09.903464Z","caller":"traceutil/trace.go:171","msg":"trace[963955618] transaction","detail":"{read_only:false; response_revision:24120; number_of_response:1; }","duration":"166.071983ms","start":"2026-03-19T08:09:09.737365Z","end":"2026-03-19T08:09:09.903437Z","steps":["trace[963955618] 'process raft request' (duration: 21.055281ms)","trace[963955618] 'compare' (duration: 144.346618ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:09.972727Z","caller":"traceutil/trace.go:171","msg":"trace[203439874] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"233.051108ms","start":"2026-03-19T08:09:09.739643Z","end":"2026-03-19T08:09:09.972694Z","steps":["trace[203439874] 'process raft request' (duration: 232.951926ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:09.973004Z","caller":"traceutil/trace.go:171","msg":"trace[866362798] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"231.852103ms","start":"2026-03-19T08:09:09.741117Z","end":"2026-03-19T08:09:09.972969Z","steps":["trace[866362798] 'process raft request' (duration: 231.524486ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:09.972739Z","caller":"traceutil/trace.go:171","msg":"trace[597092069] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"233.241922ms","start":"2026-03-19T08:09:09.739445Z","end":"2026-03-19T08:09:09.972687Z","steps":["trace[597092069] 'process raft request' (duration: 232.981347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:10.250686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.143195ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-19T08:09:10.250945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.796697ms","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-03-19T08:09:10.251135Z","caller":"traceutil/trace.go:171","msg":"trace[296771595] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24123; }","duration":"207.609024ms","start":"2026-03-19T08:09:10.043497Z","end":"2026-03-19T08:09:10.251106Z","steps":["trace[296771595] 'range keys from in-memory index tree' (duration: 207.121854ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:10.251354Z","caller":"traceutil/trace.go:171","msg":"trace[1392621069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24123; }","duration":"165.245806ms","start":"2026-03-19T08:09:10.086035Z","end":"2026-03-19T08:09:10.25128Z","steps":["trace[1392621069] 'range keys from in-memory index tree' (duration: 164.46348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:10.486343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.211516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:09:10.48641Z","caller":"traceutil/trace.go:171","msg":"trace[1654597148] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"112.51676ms","start":"2026-03-19T08:09:10.373851Z","end":"2026-03-19T08:09:10.486368Z","steps":["trace[1654597148] 'process raft request' (duration: 108.22057ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:10.486501Z","caller":"traceutil/trace.go:171","msg":"trace[797574397] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24125; }","duration":"134.399809ms","start":"2026-03-19T08:09:10.352037Z","end":"2026-03-19T08:09:10.486437Z","steps":["trace[797574397] 'count revisions from in-memory index tree' (duration: 134.094643ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:11.272929Z","caller":"traceutil/trace.go:171","msg":"trace[511124544] linearizableReadLoop","detail":"{readStateIndex:24958; appliedIndex:24957; }","duration":"155.847589ms","start":"2026-03-19T08:09:11.117056Z","end":"2026-03-19T08:09:11.272903Z","steps":["trace[511124544] 'read index received' (duration: 155.655415ms)","trace[511124544] 'applied index is now lower than readState.Index' (duration: 190.854µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:11.273279Z","caller":"traceutil/trace.go:171","msg":"trace[1971782533] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"163.172052ms","start":"2026-03-19T08:09:11.110091Z","end":"2026-03-19T08:09:11.273263Z","steps":["trace[1971782533] 'process raft request' (duration: 162.627271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:11.273586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.507963ms","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-03-19T08:09:11.273635Z","caller":"traceutil/trace.go:171","msg":"trace[295699932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24128; }","duration":"156.583354ms","start":"2026-03-19T08:09:11.11704Z","end":"2026-03-19T08:09:11.273623Z","steps":["trace[295699932] 'agreement among raft nodes before linearized reading' (duration: 156.427441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:11.273833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.725087ms","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-03-19T08:09:11.273888Z","caller":"traceutil/trace.go:171","msg":"trace[1527184427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24128; }","duration":"156.808139ms","start":"2026-03-19T08:09:11.117057Z","end":"2026-03-19T08:09:11.273866Z","steps":["trace[1527184427] 'agreement among raft nodes before linearized reading' (duration: 156.710517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:11.274174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.597083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-03-19T08:09:11.274218Z","caller":"traceutil/trace.go:171","msg":"trace[1342728479] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24128; }","duration":"155.647544ms","start":"2026-03-19T08:09:11.118558Z","end":"2026-03-19T08:09:11.274206Z","steps":["trace[1342728479] 'agreement among raft nodes before linearized reading' (duration: 155.538782ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:11.516119Z","caller":"traceutil/trace.go:171","msg":"trace[808954619] linearizableReadLoop","detail":"{readStateIndex:24961; appliedIndex:24960; }","duration":"116.196337ms","start":"2026-03-19T08:09:11.399899Z","end":"2026-03-19T08:09:11.516095Z","steps":["trace[808954619] 'read index received' (duration: 94.39342ms)","trace[808954619] 'applied index is now lower than readState.Index' (duration: 21.801627ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:11.516204Z","caller":"traceutil/trace.go:171","msg":"trace[1323913679] transaction","detail":"{read_only:false; response_revision:24131; number_of_response:1; }","duration":"120.177161ms","start":"2026-03-19T08:09:11.395981Z","end":"2026-03-19T08:09:11.516158Z","steps":["trace[1323913679] 'process raft request' (duration: 98.291452ms)","trace[1323913679] 'compare' (duration: 21.682465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:11.516429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.514004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-19T08:09:11.516469Z","caller":"traceutil/trace.go:171","msg":"trace[1103526218] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24131; }","duration":"116.619546ms","start":"2026-03-19T08:09:11.399843Z","end":"2026-03-19T08:09:11.516462Z","steps":["trace[1103526218] 'agreement among raft nodes before linearized reading' (duration: 116.422732ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:12.413698Z","caller":"traceutil/trace.go:171","msg":"trace[1160537752] linearizableReadLoop","detail":"{readStateIndex:24969; appliedIndex:24968; }","duration":"116.35018ms","start":"2026-03-19T08:09:12.297322Z","end":"2026-03-19T08:09:12.413672Z","steps":["trace[1160537752] 'read index received' (duration: 116.122345ms)","trace[1160537752] 'applied index is now lower than readState.Index' (duration: 226.455µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:12.41384Z","caller":"traceutil/trace.go:171","msg":"trace[1554841234] transaction","detail":"{read_only:false; response_revision:24139; number_of_response:1; }","duration":"120.65552ms","start":"2026-03-19T08:09:12.293132Z","end":"2026-03-19T08:09:12.413787Z","steps":["trace[1554841234] 'process raft request' (duration: 120.339284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:12.414027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.677037ms","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-03-19T08:09:12.414114Z","caller":"traceutil/trace.go:171","msg":"trace[1280568819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24139; }","duration":"116.789899ms","start":"2026-03-19T08:09:12.297304Z","end":"2026-03-19T08:09:12.414094Z","steps":["trace[1280568819] 'agreement among raft nodes before linearized reading' (duration: 116.486642ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:14.043234Z","caller":"traceutil/trace.go:171","msg":"trace[693013116] transaction","detail":"{read_only:false; response_revision:24147; number_of_response:1; }","duration":"127.790441ms","start":"2026-03-19T08:09:13.915404Z","end":"2026-03-19T08:09:14.043195Z","steps":["trace[693013116] 'process raft request' (duration: 127.566876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:16.27736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.191318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-19T08:09:16.277685Z","caller":"traceutil/trace.go:171","msg":"trace[335897256] transaction","detail":"{read_only:false; response_revision:24163; number_of_response:1; }","duration":"189.324321ms","start":"2026-03-19T08:09:16.088341Z","end":"2026-03-19T08:09:16.277665Z","steps":["trace[335897256] 'process raft request' (duration: 189.25837ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:16.277745Z","caller":"traceutil/trace.go:171","msg":"trace[1174266949] transaction","detail":"{read_only:false; response_revision:24162; number_of_response:1; }","duration":"189.487365ms","start":"2026-03-19T08:09:16.088211Z","end":"2026-03-19T08:09:16.277699Z","steps":["trace[1174266949] 'process raft request' (duration: 189.28436ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:16.277877Z","caller":"traceutil/trace.go:171","msg":"trace[2016636066] transaction","detail":"{read_only:false; response_revision:24161; number_of_response:1; }","duration":"190.082777ms","start":"2026-03-19T08:09:16.087754Z","end":"2026-03-19T08:09:16.277837Z","steps":["trace[2016636066] 'process raft request' (duration: 83.304056ms)","trace[2016636066] 'compare' (duration: 105.919142ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:24.354227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21406} {"level":"info","ts":"2026-03-19T08:09:24.477822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21406,"took":"118.822442ms","hash":1025870095,"current-db-size-bytes":51617792,"current-db-size":"52 MB","current-db-size-in-use-bytes":41332736,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-03-19T08:09:24.477897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1025870095,"revision":21406,"compact-revision":18757} {"level":"info","ts":"2026-03-19T08:09:37.654715Z","caller":"traceutil/trace.go:171","msg":"trace[757364786] linearizableReadLoop","detail":"{readStateIndex:25160; appliedIndex:25159; }","duration":"125.724636ms","start":"2026-03-19T08:09:37.52896Z","end":"2026-03-19T08:09:37.654685Z","steps":["trace[757364786] 'read index received' (duration: 37.057647ms)","trace[757364786] 'applied index is now lower than readState.Index' (duration: 88.665159ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:37.655091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.105855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-19T08:09:37.655181Z","caller":"traceutil/trace.go:171","msg":"trace[28976197] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:24324; }","duration":"126.243058ms","start":"2026-03-19T08:09:37.528921Z","end":"2026-03-19T08:09:37.655164Z","steps":["trace[28976197] 'agreement among raft nodes before linearized reading' (duration: 125.970082ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:37.655334Z","caller":"traceutil/trace.go:171","msg":"trace[1063600246] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"155.289787ms","start":"2026-03-19T08:09:37.500007Z","end":"2026-03-19T08:09:37.655297Z","steps":["trace[1063600246] 'process raft request' (duration: 66.082116ms)","trace[1063600246] 'compare' (duration: 88.137248ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:38.5147Z","caller":"traceutil/trace.go:171","msg":"trace[1917694305] linearizableReadLoop","detail":"{readStateIndex:25165; appliedIndex:25164; }","duration":"125.9157ms","start":"2026-03-19T08:09:38.388754Z","end":"2026-03-19T08:09:38.514669Z","steps":["trace[1917694305] 'read index received' (duration: 125.738927ms)","trace[1917694305] 'applied index is now lower than readState.Index' (duration: 175.093µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:38.514762Z","caller":"traceutil/trace.go:171","msg":"trace[1465263002] transaction","detail":"{read_only:false; response_revision:24328; number_of_response:1; }","duration":"140.217621ms","start":"2026-03-19T08:09:38.374504Z","end":"2026-03-19T08:09:38.514721Z","steps":["trace[1465263002] 'process raft request' (duration: 139.921985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:38.515017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.216408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:09:38.515131Z","caller":"traceutil/trace.go:171","msg":"trace[618578011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24328; }","duration":"126.431222ms","start":"2026-03-19T08:09:38.388678Z","end":"2026-03-19T08:09:38.515109Z","steps":["trace[618578011] 'agreement among raft nodes before linearized reading' (duration: 126.147087ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:38.532346Z","caller":"traceutil/trace.go:171","msg":"trace[2076694338] transaction","detail":"{read_only:false; response_revision:24330; number_of_response:1; }","duration":"114.591163ms","start":"2026-03-19T08:09:38.417729Z","end":"2026-03-19T08:09:38.53232Z","steps":["trace[2076694338] 'process raft request' (duration: 114.489701ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:38.532402Z","caller":"traceutil/trace.go:171","msg":"trace[44941969] transaction","detail":"{read_only:false; response_revision:24329; number_of_response:1; }","duration":"114.690305ms","start":"2026-03-19T08:09:38.417677Z","end":"2026-03-19T08:09:38.532368Z","steps":["trace[44941969] 'process raft request' (duration: 114.331397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:38.532581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.646987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-03-19T08:09:38.532662Z","caller":"traceutil/trace.go:171","msg":"trace[82369644] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:24330; }","duration":"137.7613ms","start":"2026-03-19T08:09:38.394881Z","end":"2026-03-19T08:09:38.532642Z","steps":["trace[82369644] 'agreement among raft nodes before linearized reading' (duration: 137.541365ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:38.69555Z","caller":"traceutil/trace.go:171","msg":"trace[353896915] transaction","detail":"{read_only:false; response_revision:24331; number_of_response:1; }","duration":"134.282826ms","start":"2026-03-19T08:09:38.56123Z","end":"2026-03-19T08:09:38.695513Z","steps":["trace[353896915] 'process raft request' (duration: 89.406595ms)","trace[353896915] 'compare' (duration: 44.522644ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:38.696646Z","caller":"traceutil/trace.go:171","msg":"trace[1375775969] transaction","detail":"{read_only:false; response_revision:24332; number_of_response:1; }","duration":"100.808034ms","start":"2026-03-19T08:09:38.595805Z","end":"2026-03-19T08:09:38.696613Z","steps":["trace[1375775969] 'process raft request' (duration: 99.825914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:09:38.697133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.758178ms","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-03-19T08:09:38.697221Z","caller":"traceutil/trace.go:171","msg":"trace[1317075200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24331; }","duration":"135.912111ms","start":"2026-03-19T08:09:38.561292Z","end":"2026-03-19T08:09:38.697204Z","steps":["trace[1317075200] 'agreement among raft nodes before linearized reading' (duration: 134.358168ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:38.695821Z","caller":"traceutil/trace.go:171","msg":"trace[1100769870] linearizableReadLoop","detail":"{readStateIndex:25168; appliedIndex:25167; }","duration":"134.46241ms","start":"2026-03-19T08:09:38.561304Z","end":"2026-03-19T08:09:38.695766Z","steps":["trace[1100769870] 'read index received' (duration: 89.306203ms)","trace[1100769870] 'applied index is now lower than readState.Index' (duration: 44.876751ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:09:43.493482Z","caller":"traceutil/trace.go:171","msg":"trace[807132460] linearizableReadLoop","detail":"{readStateIndex:25200; appliedIndex:25199; }","duration":"104.37918ms","start":"2026-03-19T08:09:43.389073Z","end":"2026-03-19T08:09:43.493452Z","steps":["trace[807132460] 'read index received' (duration: 104.126095ms)","trace[807132460] 'applied index is now lower than readState.Index' (duration: 250.975µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:09:43.493657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.574144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:09:43.493733Z","caller":"traceutil/trace.go:171","msg":"trace[1922890496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24362; }","duration":"104.725657ms","start":"2026-03-19T08:09:43.388996Z","end":"2026-03-19T08:09:43.493721Z","steps":["trace[1922890496] 'agreement among raft nodes before linearized reading' (duration: 104.593264ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:43.493567Z","caller":"traceutil/trace.go:171","msg":"trace[449043451] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"249.870031ms","start":"2026-03-19T08:09:43.243668Z","end":"2026-03-19T08:09:43.493538Z","steps":["trace[449043451] 'process raft request' (duration: 249.501583ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:43.552559Z","caller":"traceutil/trace.go:171","msg":"trace[1806109091] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"118.806573ms","start":"2026-03-19T08:09:43.433702Z","end":"2026-03-19T08:09:43.552508Z","steps":["trace[1806109091] 'process raft request' (duration: 118.366564ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:43.689225Z","caller":"traceutil/trace.go:171","msg":"trace[1963275097] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"131.018598ms","start":"2026-03-19T08:09:43.55817Z","end":"2026-03-19T08:09:43.689188Z","steps":["trace[1963275097] 'process raft request' (duration: 122.789445ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:43.831846Z","caller":"traceutil/trace.go:171","msg":"trace[1094626802] transaction","detail":"{read_only:false; response_revision:24365; number_of_response:1; }","duration":"128.960516ms","start":"2026-03-19T08:09:43.702848Z","end":"2026-03-19T08:09:43.831809Z","steps":["trace[1094626802] 'process raft request' (duration: 128.162819ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:09:44.024432Z","caller":"traceutil/trace.go:171","msg":"trace[121220308] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"110.551499ms","start":"2026-03-19T08:09:43.913838Z","end":"2026-03-19T08:09:44.02439Z","steps":["trace[121220308] 'process raft request' (duration: 108.901415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:11:10.925566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.723313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:11:10.925729Z","caller":"traceutil/trace.go:171","msg":"trace[1344403203] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25230; }","duration":"276.133371ms","start":"2026-03-19T08:11:10.649576Z","end":"2026-03-19T08:11:10.925709Z","steps":["trace[1344403203] 'range keys from in-memory index tree' (duration: 275.526149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:11:10.925771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.165363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-19T08:11:10.925863Z","caller":"traceutil/trace.go:171","msg":"trace[1375700263] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25230; }","duration":"277.294626ms","start":"2026-03-19T08:11:10.648548Z","end":"2026-03-19T08:11:10.925842Z","steps":["trace[1375700263] 'range keys from in-memory index tree' (duration: 277.091482ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:13:00.916433Z","caller":"traceutil/trace.go:171","msg":"trace[552833857] linearizableReadLoop","detail":"{readStateIndex:27150; appliedIndex:27149; }","duration":"212.552947ms","start":"2026-03-19T08:13:00.703849Z","end":"2026-03-19T08:13:00.916402Z","steps":["trace[552833857] 'read index received' (duration: 212.397574ms)","trace[552833857] 'applied index is now lower than readState.Index' (duration: 154.133µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:13:00.916554Z","caller":"traceutil/trace.go:171","msg":"trace[260248544] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"257.753926ms","start":"2026-03-19T08:13:00.658749Z","end":"2026-03-19T08:13:00.916503Z","steps":["trace[260248544] 'process raft request' (duration: 257.441189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:13:00.916704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.815703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-19T08:13:00.916772Z","caller":"traceutil/trace.go:171","msg":"trace[1394695330] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26264; }","duration":"212.966096ms","start":"2026-03-19T08:13:00.703791Z","end":"2026-03-19T08:13:00.916757Z","steps":["trace[1394695330] 'agreement among raft nodes before linearized reading' (duration: 212.745502ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:13:01.000314Z","caller":"traceutil/trace.go:171","msg":"trace[1576212218] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"157.296939ms","start":"2026-03-19T08:13:00.842984Z","end":"2026-03-19T08:13:01.000281Z","steps":["trace[1576212218] 'process raft request' (duration: 108.569428ms)","trace[1576212218] 'compare' (duration: 48.437005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:13:01.000562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.204936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-19T08:13:01.000667Z","caller":"traceutil/trace.go:171","msg":"trace[833867320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26265; }","duration":"187.40596ms","start":"2026-03-19T08:13:00.813244Z","end":"2026-03-19T08:13:01.00065Z","steps":["trace[833867320] 'agreement among raft nodes before linearized reading' (duration: 187.072862ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:14:24.360729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24222} {"level":"info","ts":"2026-03-19T08:14:24.498925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24222,"took":"132.537801ms","hash":2109714205,"current-db-size-bytes":51617792,"current-db-size":"52 MB","current-db-size-in-use-bytes":34398208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-19T08:14:24.499036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2109714205,"revision":24222,"compact-revision":21406} {"level":"info","ts":"2026-03-19T08:19:23.876681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T08:19:23.892266Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-03-19T08:19:23.893344Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-03-19T08:19:24.369631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26962} {"level":"info","ts":"2026-03-19T08:19:24.514761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26962,"took":"140.415817ms","hash":4294803615,"current-db-size-bytes":51617792,"current-db-size":"52 MB","current-db-size-in-use-bytes":27152384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-19T08:19:24.514876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4294803615,"revision":26962,"compact-revision":24222} {"level":"info","ts":"2026-03-19T08:24:24.376014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29035} {"level":"info","ts":"2026-03-19T08:24:24.496728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29035,"took":"116.136127ms","hash":294507122,"current-db-size-bytes":51617792,"current-db-size":"52 MB","current-db-size-in-use-bytes":27934720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-19T08:24:24.49682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":294507122,"revision":29035,"compact-revision":26962} {"level":"info","ts":"2026-03-19T08:29:24.385555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31155} {"level":"info","ts":"2026-03-19T08:29:24.537575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31155,"took":"147.146461ms","hash":4238658619,"current-db-size-bytes":51617792,"current-db-size":"52 MB","current-db-size-in-use-bytes":28061696,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-19T08:29:24.537687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4238658619,"revision":31155,"compact-revision":29035} {"level":"info","ts":"2026-03-19T08:34:24.394373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33293} {"level":"info","ts":"2026-03-19T08:34:24.533517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33293,"took":"132.020205ms","hash":963022809,"current-db-size-bytes":51617792,"current-db-size":"52 MB","current-db-size-in-use-bytes":28971008,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-19T08:34:24.533583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":963022809,"revision":33293,"compact-revision":31155} {"level":"info","ts":"2026-03-19T08:34:34.392398Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008f07.wal"} {"level":"info","ts":"2026-03-19T08:39:24.403025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35375} {"level":"info","ts":"2026-03-19T08:39:24.534786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35375,"took":"127.446729ms","hash":1238588207,"current-db-size-bytes":53313536,"current-db-size":"53 MB","current-db-size-in-use-bytes":29020160,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-19T08:39:24.534874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1238588207,"revision":35375,"compact-revision":33293}