{"level":"warn","ts":"2026-05-24T12:58:41.760316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T12:58:41.760593Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-24T12:58:41.760726Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T12:58:41.760754Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-24T12:58:41.760801Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-24T12:58:41.764345Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-24T12:58:41.764504Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-24T12:58:41.768183Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.443138ms"} {"level":"info","ts":"2026-05-24T12:58:41.773479Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-24T12:58:41.773583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-24T12:58:41.773617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-24T12:58:41.773639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-24T12:58:41.773661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-24T12:58:41.77371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-24T12:58:41.787371Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-24T12:58:41.789504Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-24T12:58:41.791037Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-24T12:58:41.793925Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-24T12:58:41.794207Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-24T12:58:41.796881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-24T12:58:41.797052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-24T12:58:41.794265Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-24T12:58:41.797124Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-24T12:58:41.797162Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-24T12:58:41.800202Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-24T12:58:41.800484Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-24T12:58:41.800536Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-24T12:58:41.800575Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-24T12:58:41.800614Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-24T12:58:41.974658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-24T12:58:41.974748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-24T12:58:41.974797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-24T12:58:41.97482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-24T12:58:41.974832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-24T12:58:41.974881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-24T12:58:41.974899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-24T12:58:41.976134Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-24T12:58:41.976189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T12:58:41.976241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T12:58:41.976586Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-24T12:58:41.976693Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-24T12:58:41.979551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-24T12:58:41.980586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-24T12:58:41.980789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:41.981613Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:41.981755Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:41.981805Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:45.997108Z","caller":"traceutil/trace.go:171","msg":"trace[942490956] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"179.413917ms","start":"2026-05-24T12:58:45.817669Z","end":"2026-05-24T12:58:45.997083Z","steps":["trace[942490956] 'process raft request' (duration: 179.222813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:51.750566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.922666ms","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-05-24T12:58:51.7507Z","caller":"traceutil/trace.go:171","msg":"trace[2057834432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"106.157301ms","start":"2026-05-24T12:58:51.644519Z","end":"2026-05-24T12:58:51.750677Z","steps":["trace[2057834432] 'range keys from in-memory index tree' (duration: 105.762122ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:51.949102Z","caller":"traceutil/trace.go:171","msg":"trace[1932705391] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"177.841972ms","start":"2026-05-24T12:58:51.771231Z","end":"2026-05-24T12:58:51.949073Z","steps":["trace[1932705391] 'process raft request' (duration: 177.685029ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:52.222497Z","caller":"traceutil/trace.go:171","msg":"trace[1183505088] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"251.584688ms","start":"2026-05-24T12:58:51.970879Z","end":"2026-05-24T12:58:52.222463Z","steps":["trace[1183505088] 'process raft request' (duration: 251.501756ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:52.22253Z","caller":"traceutil/trace.go:171","msg":"trace[1956313028] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"255.385364ms","start":"2026-05-24T12:58:51.96708Z","end":"2026-05-24T12:58:52.222465Z","steps":["trace[1956313028] 'process raft request' (duration: 159.793489ms)","trace[1956313028] 'compare' (duration: 95.335359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T12:59:22.331301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.129766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T12:59:22.332402Z","caller":"traceutil/trace.go:171","msg":"trace[625180148] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:604; }","duration":"232.389822ms","start":"2026-05-24T12:59:22.099952Z","end":"2026-05-24T12:59:22.332342Z","steps":["trace[625180148] 'read index received' (duration: 81.436728ms)","trace[625180148] 'applied index is now lower than readState.Index' (duration: 150.951784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T12:59:22.33254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.583397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T12:59:22.332593Z","caller":"traceutil/trace.go:171","msg":"trace[754169040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"232.640138ms","start":"2026-05-24T12:59:22.099939Z","end":"2026-05-24T12:59:22.332579Z","steps":["trace[754169040] 'agreement among raft nodes before linearized reading' (duration: 232.524446ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:59:22.332865Z","caller":"traceutil/trace.go:171","msg":"trace[1277732390] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"294.057186ms","start":"2026-05-24T12:59:22.03879Z","end":"2026-05-24T12:59:22.332848Z","steps":["trace[1277732390] 'process raft request' (duration: 142.48318ms)","trace[1277732390] 'compare' (duration: 148.877141ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T12:59:22.33967Z","caller":"traceutil/trace.go:171","msg":"trace[1656788769] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"267.316457ms","start":"2026-05-24T12:59:22.072283Z","end":"2026-05-24T12:59:22.3396Z","steps":["trace[1656788769] 'process raft request' (duration: 259.258752ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:59:22.339712Z","caller":"traceutil/trace.go:171","msg":"trace[164389002] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"255.335372ms","start":"2026-05-24T12:59:22.084305Z","end":"2026-05-24T12:59:22.339641Z","steps":["trace[164389002] 'process raft request' (duration: 247.623825ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:59:28.296124Z","caller":"traceutil/trace.go:171","msg":"trace[1116920288] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"237.829033ms","start":"2026-05-24T12:59:28.058268Z","end":"2026-05-24T12:59:28.296097Z","steps":["trace[1116920288] 'read index received' (duration: 236.927554ms)","trace[1116920288] 'applied index is now lower than readState.Index' (duration: 900.299µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T12:59:28.296172Z","caller":"traceutil/trace.go:171","msg":"trace[422439755] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"420.760453ms","start":"2026-05-24T12:59:27.875387Z","end":"2026-05-24T12:59:28.296147Z","steps":["trace[422439755] 'process raft request' (duration: 420.077769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:59:28.296326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.046367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T12:59:28.296415Z","caller":"traceutil/trace.go:171","msg":"trace[48469401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"238.179771ms","start":"2026-05-24T12:59:28.058181Z","end":"2026-05-24T12:59:28.296361Z","steps":["trace[48469401] 'agreement among raft nodes before linearized reading' (duration: 238.024448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:59:28.296463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T12:59:27.875353Z","time spent":"420.899495ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T12:59:28.312127Z","caller":"traceutil/trace.go:171","msg":"trace[1843677952] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"132.853522ms","start":"2026-05-24T12:59:28.17924Z","end":"2026-05-24T12:59:28.312093Z","steps":["trace[1843677952] 'process raft request' (duration: 132.705029ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:47.785583Z","caller":"traceutil/trace.go:171","msg":"trace[1689447142] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"120.201506ms","start":"2026-05-24T13:00:47.665312Z","end":"2026-05-24T13:00:47.785514Z","steps":["trace[1689447142] 'process raft request' (duration: 120.008722ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:02.077831Z","caller":"traceutil/trace.go:171","msg":"trace[1313636202] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"206.425493ms","start":"2026-05-24T13:01:01.871375Z","end":"2026-05-24T13:01:02.077801Z","steps":["trace[1313636202] 'read index received' (duration: 206.304361ms)","trace[1313636202] 'applied index is now lower than readState.Index' (duration: 119.842µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:01:02.077891Z","caller":"traceutil/trace.go:171","msg":"trace[1445577701] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"213.619171ms","start":"2026-05-24T13:01:01.864235Z","end":"2026-05-24T13:01:02.077854Z","steps":["trace[1445577701] 'process raft request' (duration: 213.373366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:02.078106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.70961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ce6c7cf-4e2a-4ecc-bc7e-4f6af7719702\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-24T13:01:02.078169Z","caller":"traceutil/trace.go:171","msg":"trace[1677309212] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ce6c7cf-4e2a-4ecc-bc7e-4f6af7719702; range_end:; response_count:1; response_revision:1877; }","duration":"206.894124ms","start":"2026-05-24T13:01:01.871261Z","end":"2026-05-24T13:01:02.078155Z","steps":["trace[1677309212] 'agreement among raft nodes before linearized reading' (duration: 206.669359ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:02.088564Z","caller":"traceutil/trace.go:171","msg":"trace[1930468745] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"107.232363ms","start":"2026-05-24T13:01:01.981291Z","end":"2026-05-24T13:01:02.088523Z","steps":["trace[1930468745] 'process raft request' (duration: 107.09601ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:02.509592Z","caller":"traceutil/trace.go:171","msg":"trace[822292465] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"328.960317ms","start":"2026-05-24T13:01:02.180596Z","end":"2026-05-24T13:01:02.509556Z","steps":["trace[822292465] 'process raft request' (duration: 289.252393ms)","trace[822292465] 'compare' (duration: 39.48273ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:01:02.509682Z","caller":"traceutil/trace.go:171","msg":"trace[276157349] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"222.292816ms","start":"2026-05-24T13:01:02.28736Z","end":"2026-05-24T13:01:02.509653Z","steps":["trace[276157349] 'process raft request' (duration: 222.134413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:02.509792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:01:02.18057Z","time spent":"329.129391ms","remote":"127.0.0.1:46288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T13:01:15.031848Z","caller":"traceutil/trace.go:171","msg":"trace[703331611] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"113.97389ms","start":"2026-05-24T13:01:14.917849Z","end":"2026-05-24T13:01:15.031823Z","steps":["trace[703331611] 'read index received' (duration: 113.727195ms)","trace[703331611] 'applied index is now lower than readState.Index' (duration: 245.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:01:15.031954Z","caller":"traceutil/trace.go:171","msg":"trace[1039775334] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"169.974346ms","start":"2026-05-24T13:01:14.861927Z","end":"2026-05-24T13:01:15.031901Z","steps":["trace[1039775334] 'process raft request' (duration: 169.71067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:15.032097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.237686ms","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-05-24T13:01:15.032147Z","caller":"traceutil/trace.go:171","msg":"trace[744807960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1990; }","duration":"114.361068ms","start":"2026-05-24T13:01:14.917774Z","end":"2026-05-24T13:01:15.032135Z","steps":["trace[744807960] 'agreement among raft nodes before linearized reading' (duration: 114.153414ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:18.710679Z","caller":"traceutil/trace.go:171","msg":"trace[1129828866] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"139.925446ms","start":"2026-05-24T13:01:18.570709Z","end":"2026-05-24T13:01:18.710634Z","steps":["trace[1129828866] 'process raft request' (duration: 139.59936ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:18.711324Z","caller":"traceutil/trace.go:171","msg":"trace[1930125116] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"108.104871ms","start":"2026-05-24T13:01:18.60319Z","end":"2026-05-24T13:01:18.711295Z","steps":["trace[1930125116] 'process raft request' (duration: 107.960068ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:51.367577Z","caller":"traceutil/trace.go:171","msg":"trace[639697776] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"187.955689ms","start":"2026-05-24T13:01:51.179587Z","end":"2026-05-24T13:01:51.367543Z","steps":["trace[639697776] 'process raft request' (duration: 187.753224ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:51.385767Z","caller":"traceutil/trace.go:171","msg":"trace[1877024413] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2287; }","duration":"173.044526ms","start":"2026-05-24T13:01:51.212687Z","end":"2026-05-24T13:01:51.385731Z","steps":["trace[1877024413] 'read index received' (duration: 154.790339ms)","trace[1877024413] 'applied index is now lower than readState.Index' (duration: 18.252557ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:01:51.385877Z","caller":"traceutil/trace.go:171","msg":"trace[944050583] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"189.092ms","start":"2026-05-24T13:01:51.196751Z","end":"2026-05-24T13:01:51.385843Z","steps":["trace[944050583] 'process raft request' (duration: 188.741973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:51.38612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.406273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T13:01:51.386175Z","caller":"traceutil/trace.go:171","msg":"trace[2105647471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2202; }","duration":"173.528385ms","start":"2026-05-24T13:01:51.212631Z","end":"2026-05-24T13:01:51.386159Z","steps":["trace[2105647471] 'agreement among raft nodes before linearized reading' (duration: 173.28358ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:57.594563Z","caller":"traceutil/trace.go:171","msg":"trace[1853977605] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"152.947512ms","start":"2026-05-24T13:01:57.441587Z","end":"2026-05-24T13:01:57.594535Z","steps":["trace[1853977605] 'read index received' (duration: 152.605575ms)","trace[1853977605] 'applied index is now lower than readState.Index' (duration: 340.137µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:01:57.594652Z","caller":"traceutil/trace.go:171","msg":"trace[314974554] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"185.865446ms","start":"2026-05-24T13:01:57.408742Z","end":"2026-05-24T13:01:57.594607Z","steps":["trace[314974554] 'process raft request' (duration: 185.576171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:57.594928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.318371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T13:01:57.595045Z","caller":"traceutil/trace.go:171","msg":"trace[72017438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2234; }","duration":"153.498384ms","start":"2026-05-24T13:01:57.441519Z","end":"2026-05-24T13:01:57.595018Z","steps":["trace[72017438] 'agreement among raft nodes before linearized reading' (duration: 153.217428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:57.594972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.754204ms","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-05-24T13:01:57.595142Z","caller":"traceutil/trace.go:171","msg":"trace[960193355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2234; }","duration":"119.047169ms","start":"2026-05-24T13:01:57.476079Z","end":"2026-05-24T13:01:57.595126Z","steps":["trace[960193355] 'agreement among raft nodes before linearized reading' (duration: 118.684461ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:57.850793Z","caller":"traceutil/trace.go:171","msg":"trace[1318709177] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"250.582643ms","start":"2026-05-24T13:01:57.600173Z","end":"2026-05-24T13:01:57.850755Z","steps":["trace[1318709177] 'process raft request' (duration: 250.261197ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:57.850967Z","caller":"traceutil/trace.go:171","msg":"trace[1807786297] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"250.728827ms","start":"2026-05-24T13:01:57.600202Z","end":"2026-05-24T13:01:57.850931Z","steps":["trace[1807786297] 'process raft request' (duration: 250.503282ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:01:57.954566Z","caller":"traceutil/trace.go:171","msg":"trace[114579516] linearizableReadLoop","detail":"{readStateIndex:2325; appliedIndex:2322; }","duration":"167.219112ms","start":"2026-05-24T13:01:57.787326Z","end":"2026-05-24T13:01:57.954545Z","steps":["trace[114579516] 'read index received' (duration: 63.198327ms)","trace[114579516] 'applied index is now lower than readState.Index' (duration: 104.019875ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:01:57.954672Z","caller":"traceutil/trace.go:171","msg":"trace[1473350493] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"354.193941ms","start":"2026-05-24T13:01:57.600423Z","end":"2026-05-24T13:01:57.954617Z","steps":["trace[1473350493] 'process raft request' (duration: 353.816504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:57.954799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.439136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:01:57.954848Z","caller":"traceutil/trace.go:171","msg":"trace[1956178666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2237; }","duration":"167.575439ms","start":"2026-05-24T13:01:57.787257Z","end":"2026-05-24T13:01:57.954833Z","steps":["trace[1956178666] 'agreement among raft nodes before linearized reading' (duration: 167.453997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:01:57.954922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:01:57.600386Z","time spent":"354.403034ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T13:03:06.525667Z","caller":"traceutil/trace.go:171","msg":"trace[219214358] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"308.628456ms","start":"2026-05-24T13:03:06.217009Z","end":"2026-05-24T13:03:06.525637Z","steps":["trace[219214358] 'process raft request' (duration: 308.413462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:03:06.525962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:03:06.216981Z","time spent":"308.885762ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T13:03:06.537823Z","caller":"traceutil/trace.go:171","msg":"trace[242118497] linearizableReadLoop","detail":"{readStateIndex:2915; appliedIndex:2913; }","duration":"204.429759ms","start":"2026-05-24T13:03:06.333372Z","end":"2026-05-24T13:03:06.537802Z","steps":["trace[242118497] 'read index received' (duration: 192.009478ms)","trace[242118497] 'applied index is now lower than readState.Index' (duration: 12.419051ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:03:06.538003Z","caller":"traceutil/trace.go:171","msg":"trace[778914019] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"320.544546ms","start":"2026-05-24T13:03:06.217448Z","end":"2026-05-24T13:03:06.537993Z","steps":["trace[778914019] 'process raft request' (duration: 320.25015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:03:06.538113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:03:06.217436Z","time spent":"320.597297ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T13:03:06.538243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.785906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:03:06.538322Z","caller":"traceutil/trace.go:171","msg":"trace[803655807] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2804; }","duration":"204.95137ms","start":"2026-05-24T13:03:06.333352Z","end":"2026-05-24T13:03:06.538303Z","steps":["trace[803655807] 'agreement among raft nodes before linearized reading' (duration: 204.765727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:03:06.538412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.026332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-24T13:03:06.538546Z","caller":"traceutil/trace.go:171","msg":"trace[744451589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2804; }","duration":"205.248046ms","start":"2026-05-24T13:03:06.333273Z","end":"2026-05-24T13:03:06.538521Z","steps":["trace[744451589] 'agreement among raft nodes before linearized reading' (duration: 204.885588ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:08.194384Z","caller":"traceutil/trace.go:171","msg":"trace[733050109] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"110.698199ms","start":"2026-05-24T13:07:08.083656Z","end":"2026-05-24T13:07:08.194355Z","steps":["trace[733050109] 'process raft request' (duration: 109.789951ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:14.476114Z","caller":"traceutil/trace.go:171","msg":"trace[1749937235] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"270.953891ms","start":"2026-05-24T13:07:14.205126Z","end":"2026-05-24T13:07:14.47608Z","steps":["trace[1749937235] 'process raft request' (duration: 200.003466ms)","trace[1749937235] 'compare' (duration: 70.810103ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:14.520818Z","caller":"traceutil/trace.go:171","msg":"trace[337544381] linearizableReadLoop","detail":"{readStateIndex:5226; appliedIndex:5224; }","duration":"286.936206ms","start":"2026-05-24T13:07:14.233832Z","end":"2026-05-24T13:07:14.520768Z","steps":["trace[337544381] 'read index received' (duration: 171.321821ms)","trace[337544381] 'applied index is now lower than readState.Index' (duration: 115.612625ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:14.520905Z","caller":"traceutil/trace.go:171","msg":"trace[1274129386] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"310.339597ms","start":"2026-05-24T13:07:14.210534Z","end":"2026-05-24T13:07:14.520874Z","steps":["trace[1274129386] 'process raft request' (duration: 310.026511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:14.521163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.307743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-24T13:07:14.521212Z","caller":"traceutil/trace.go:171","msg":"trace[351977376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5033; }","duration":"287.433955ms","start":"2026-05-24T13:07:14.233765Z","end":"2026-05-24T13:07:14.521199Z","steps":["trace[351977376] 'agreement among raft nodes before linearized reading' (duration: 287.195701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:14.521214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:07:14.210497Z","time spent":"310.539021ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T13:07:14.524673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.157071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:07:14.524757Z","caller":"traceutil/trace.go:171","msg":"trace[632802012] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5034; }","duration":"289.311954ms","start":"2026-05-24T13:07:14.235427Z","end":"2026-05-24T13:07:14.524739Z","steps":["trace[632802012] 'agreement among raft nodes before linearized reading' (duration: 289.160091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:14.524853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.533717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-24T13:07:14.52467Z","caller":"traceutil/trace.go:171","msg":"trace[971490550] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"117.353479ms","start":"2026-05-24T13:07:14.407292Z","end":"2026-05-24T13:07:14.524646Z","steps":["trace[971490550] 'process raft request' (duration: 117.165684ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:14.524879Z","caller":"traceutil/trace.go:171","msg":"trace[315551155] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5034; }","duration":"134.599198ms","start":"2026-05-24T13:07:14.390274Z","end":"2026-05-24T13:07:14.524873Z","steps":["trace[315551155] 'agreement among raft nodes before linearized reading' (duration: 134.453445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:14.525022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.308382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-124ad4e0-0c08-4c36-a28b-f2d94e5394ee\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-24T13:07:14.52509Z","caller":"traceutil/trace.go:171","msg":"trace[1365706497] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-124ad4e0-0c08-4c36-a28b-f2d94e5394ee; range_end:; response_count:1; response_revision:5034; }","duration":"200.423994ms","start":"2026-05-24T13:07:14.324649Z","end":"2026-05-24T13:07:14.525073Z","steps":["trace[1365706497] 'agreement among raft nodes before linearized reading' (duration: 200.115608ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:14.781604Z","caller":"traceutil/trace.go:171","msg":"trace[320869459] linearizableReadLoop","detail":"{readStateIndex:5231; appliedIndex:5230; }","duration":"129.236513ms","start":"2026-05-24T13:07:14.652341Z","end":"2026-05-24T13:07:14.781578Z","steps":["trace[320869459] 'read index received' (duration: 92.897008ms)","trace[320869459] 'applied index is now lower than readState.Index' (duration: 36.338485ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:14.781782Z","caller":"traceutil/trace.go:171","msg":"trace[77559557] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"158.912137ms","start":"2026-05-24T13:07:14.622747Z","end":"2026-05-24T13:07:14.78166Z","steps":["trace[77559557] 'process raft request' (duration: 122.590332ms)","trace[77559557] 'compare' (duration: 35.968378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:07:14.781861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.488408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:07:14.782018Z","caller":"traceutil/trace.go:171","msg":"trace[234531848] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5037; }","duration":"129.700712ms","start":"2026-05-24T13:07:14.652285Z","end":"2026-05-24T13:07:14.781985Z","steps":["trace[234531848] 'agreement among raft nodes before linearized reading' (duration: 129.392366ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:14.924583Z","caller":"traceutil/trace.go:171","msg":"trace[1172242227] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"115.129075ms","start":"2026-05-24T13:07:14.809413Z","end":"2026-05-24T13:07:14.924542Z","steps":["trace[1172242227] 'process raft request' (duration: 89.056953ms)","trace[1172242227] 'compare' (duration: 25.744706ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:14.924596Z","caller":"traceutil/trace.go:171","msg":"trace[952535552] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5232; }","duration":"107.877933ms","start":"2026-05-24T13:07:14.816688Z","end":"2026-05-24T13:07:14.924566Z","steps":["trace[952535552] 'read index received' (duration: 5.522489ms)","trace[952535552] 'applied index is now lower than readState.Index' (duration: 102.353884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:07:14.924917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.213289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-24T13:07:14.92498Z","caller":"traceutil/trace.go:171","msg":"trace[931397336] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5041; }","duration":"108.31377ms","start":"2026-05-24T13:07:14.81665Z","end":"2026-05-24T13:07:14.924963Z","steps":["trace[931397336] 'agreement among raft nodes before linearized reading' (duration: 108.002974ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:22.268362Z","caller":"traceutil/trace.go:171","msg":"trace[597463437] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"203.613337ms","start":"2026-05-24T13:07:22.064715Z","end":"2026-05-24T13:07:22.268328Z","steps":["trace[597463437] 'process raft request' (duration: 170.394373ms)","trace[597463437] 'compare' (duration: 32.963279ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:22.268879Z","caller":"traceutil/trace.go:171","msg":"trace[1505537461] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"203.835861ms","start":"2026-05-24T13:07:22.06503Z","end":"2026-05-24T13:07:22.268866Z","steps":["trace[1505537461] 'process raft request' (duration: 203.221889ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:22.268904Z","caller":"traceutil/trace.go:171","msg":"trace[133008005] linearizableReadLoop","detail":"{readStateIndex:5393; appliedIndex:5390; }","duration":"182.712175ms","start":"2026-05-24T13:07:22.086157Z","end":"2026-05-24T13:07:22.26887Z","steps":["trace[133008005] 'read index received' (duration: 148.846419ms)","trace[133008005] 'applied index is now lower than readState.Index' (duration: 33.864206ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:22.268973Z","caller":"traceutil/trace.go:171","msg":"trace[1346730854] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"203.562666ms","start":"2026-05-24T13:07:22.065361Z","end":"2026-05-24T13:07:22.268924Z","steps":["trace[1346730854] 'process raft request' (duration: 203.25073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:22.269233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.016811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/prometheus-pushgateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:07:22.269319Z","caller":"traceutil/trace.go:171","msg":"trace[1645291728] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/prometheus-pushgateway; range_end:; response_count:0; response_revision:5194; }","duration":"183.176314ms","start":"2026-05-24T13:07:22.086097Z","end":"2026-05-24T13:07:22.269274Z","steps":["trace[1645291728] 'agreement among raft nodes before linearized reading' (duration: 183.003321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:22.269358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.143894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-24T13:07:22.269419Z","caller":"traceutil/trace.go:171","msg":"trace[1503215650] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5194; }","duration":"183.260995ms","start":"2026-05-24T13:07:22.086139Z","end":"2026-05-24T13:07:22.2694Z","steps":["trace[1503215650] 'agreement among raft nodes before linearized reading' (duration: 183.037011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:22.269623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.756932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-24T13:07:22.269654Z","caller":"traceutil/trace.go:171","msg":"trace[297008687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5194; }","duration":"165.838913ms","start":"2026-05-24T13:07:22.103807Z","end":"2026-05-24T13:07:22.269646Z","steps":["trace[297008687] 'agreement among raft nodes before linearized reading' (duration: 165.753902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:22.269802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.643628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:07:22.269826Z","caller":"traceutil/trace.go:171","msg":"trace[1041548930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5194; }","duration":"170.705999ms","start":"2026-05-24T13:07:22.099113Z","end":"2026-05-24T13:07:22.269819Z","steps":["trace[1041548930] 'agreement among raft nodes before linearized reading' (duration: 170.640067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:22.269932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.612255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hb77f\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-24T13:07:22.270034Z","caller":"traceutil/trace.go:171","msg":"trace[1844632147] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hb77f; range_end:; response_count:1; response_revision:5194; }","duration":"143.887151ms","start":"2026-05-24T13:07:22.126128Z","end":"2026-05-24T13:07:22.270015Z","steps":["trace[1844632147] 'agreement among raft nodes before linearized reading' (duration: 143.335831ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:28.654555Z","caller":"traceutil/trace.go:171","msg":"trace[266948955] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"116.183556ms","start":"2026-05-24T13:07:28.538336Z","end":"2026-05-24T13:07:28.65452Z","steps":["trace[266948955] 'process raft request' (duration: 115.946161ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:28.820878Z","caller":"traceutil/trace.go:171","msg":"trace[1934842176] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"136.256351ms","start":"2026-05-24T13:07:28.68459Z","end":"2026-05-24T13:07:28.820847Z","steps":["trace[1934842176] 'process raft request' (duration: 95.410197ms)","trace[1934842176] 'compare' (duration: 40.660831ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:28.820922Z","caller":"traceutil/trace.go:171","msg":"trace[232668029] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5513; }","duration":"134.996006ms","start":"2026-05-24T13:07:28.685893Z","end":"2026-05-24T13:07:28.820889Z","steps":["trace[232668029] 'read index received' (duration: 94.127482ms)","trace[232668029] 'applied index is now lower than readState.Index' (duration: 40.867284ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:28.821312Z","caller":"traceutil/trace.go:171","msg":"trace[1176687140] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"103.579748ms","start":"2026-05-24T13:07:28.717671Z","end":"2026-05-24T13:07:28.821251Z","steps":["trace[1176687140] 'process raft request' (duration: 103.181461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:28.821409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.504596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127771"} {"level":"info","ts":"2026-05-24T13:07:28.821494Z","caller":"traceutil/trace.go:171","msg":"trace[90390916] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5313; }","duration":"135.639979ms","start":"2026-05-24T13:07:28.685835Z","end":"2026-05-24T13:07:28.821475Z","steps":["trace[90390916] 'agreement among raft nodes before linearized reading' (duration: 135.228901ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:37.669377Z","caller":"traceutil/trace.go:171","msg":"trace[1483948631] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5713; }","duration":"167.057717ms","start":"2026-05-24T13:07:37.502297Z","end":"2026-05-24T13:07:37.669355Z","steps":["trace[1483948631] 'read index received' (duration: 86.658896ms)","trace[1483948631] 'applied index is now lower than readState.Index' (duration: 80.397391ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:07:37.669495Z","caller":"traceutil/trace.go:171","msg":"trace[1217947294] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"169.992755ms","start":"2026-05-24T13:07:37.499445Z","end":"2026-05-24T13:07:37.669438Z","steps":["trace[1217947294] 'process raft request' (duration: 169.78065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:37.669635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.330893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b00aa186-d439-481d-bd1a-efe974a8bad0\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-24T13:07:37.669685Z","caller":"traceutil/trace.go:171","msg":"trace[497927187] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b00aa186-d439-481d-bd1a-efe974a8bad0; range_end:; response_count:1; response_revision:5508; }","duration":"167.456925ms","start":"2026-05-24T13:07:37.502217Z","end":"2026-05-24T13:07:37.669674Z","steps":["trace[497927187] 'agreement among raft nodes before linearized reading' (duration: 167.254001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:07:37.747444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.20713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:07:37.747632Z","caller":"traceutil/trace.go:171","msg":"trace[1531754566] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5509; }","duration":"138.473145ms","start":"2026-05-24T13:07:37.609133Z","end":"2026-05-24T13:07:37.747606Z","steps":["trace[1531754566] 'agreement among raft nodes before linearized reading' (duration: 138.199429ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:07:48.573534Z","caller":"traceutil/trace.go:171","msg":"trace[1630905468] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"126.51331ms","start":"2026-05-24T13:07:48.446999Z","end":"2026-05-24T13:07:48.573512Z","steps":["trace[1630905468] 'process raft request' (duration: 126.376707ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:08:42.425616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-05-24T13:08:42.639139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"210.504617ms","hash":2800988043,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-24T13:08:42.63925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2800988043,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-05-24T13:13:42.431966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6069} {"level":"info","ts":"2026-05-24T13:13:42.52959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6069,"took":"95.893019ms","hash":309942468,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":21917696,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-24T13:13:42.529665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":309942468,"revision":6069,"compact-revision":3000} {"level":"info","ts":"2026-05-24T13:13:50.167418Z","caller":"traceutil/trace.go:171","msg":"trace[1925532870] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"125.836999ms","start":"2026-05-24T13:13:50.041537Z","end":"2026-05-24T13:13:50.167374Z","steps":["trace[1925532870] 'process raft request' (duration: 125.527923ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:02.550542Z","caller":"traceutil/trace.go:171","msg":"trace[1047775805] linearizableReadLoop","detail":"{readStateIndex:8695; appliedIndex:8692; }","duration":"120.895551ms","start":"2026-05-24T13:14:02.429621Z","end":"2026-05-24T13:14:02.550516Z","steps":["trace[1047775805] 'read index received' (duration: 5.226543ms)","trace[1047775805] 'applied index is now lower than readState.Index' (duration: 115.667718ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:14:02.550687Z","caller":"traceutil/trace.go:171","msg":"trace[289482788] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"129.49925ms","start":"2026-05-24T13:14:02.421174Z","end":"2026-05-24T13:14:02.550673Z","steps":["trace[289482788] 'process raft request' (duration: 95.694184ms)","trace[289482788] 'compare' (duration: 33.178684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:14:02.550856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.213287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-24T13:14:02.550945Z","caller":"traceutil/trace.go:171","msg":"trace[961977998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8393; }","duration":"121.35263ms","start":"2026-05-24T13:14:02.429575Z","end":"2026-05-24T13:14:02.550928Z","steps":["trace[961977998] 'agreement among raft nodes before linearized reading' (duration: 121.143586ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:02.55085Z","caller":"traceutil/trace.go:171","msg":"trace[2104904459] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"128.973301ms","start":"2026-05-24T13:14:02.421846Z","end":"2026-05-24T13:14:02.550819Z","steps":["trace[2104904459] 'process raft request' (duration: 128.535953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:14:02.551837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.070077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-24T13:14:02.551927Z","caller":"traceutil/trace.go:171","msg":"trace[1912857129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8393; }","duration":"114.21375ms","start":"2026-05-24T13:14:02.437693Z","end":"2026-05-24T13:14:02.551907Z","steps":["trace[1912857129] 'agreement among raft nodes before linearized reading' (duration: 113.781751ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:02.757553Z","caller":"traceutil/trace.go:171","msg":"trace[1660904958] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"194.814508ms","start":"2026-05-24T13:14:02.562698Z","end":"2026-05-24T13:14:02.757513Z","steps":["trace[1660904958] 'process raft request' (duration: 129.111614ms)","trace[1660904958] 'compare' (duration: 65.264326ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:14:02.757576Z","caller":"traceutil/trace.go:171","msg":"trace[1624751881] linearizableReadLoop","detail":"{readStateIndex:8698; appliedIndex:8696; }","duration":"119.496705ms","start":"2026-05-24T13:14:02.638049Z","end":"2026-05-24T13:14:02.757546Z","steps":["trace[1624751881] 'read index received' (duration: 53.746019ms)","trace[1624751881] 'applied index is now lower than readState.Index' (duration: 65.748746ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:14:02.757621Z","caller":"traceutil/trace.go:171","msg":"trace[2140393395] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"192.456022ms","start":"2026-05-24T13:14:02.565132Z","end":"2026-05-24T13:14:02.757588Z","steps":["trace[2140393395] 'process raft request' (duration: 192.268018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:14:02.75775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.660098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:14:02.758258Z","caller":"traceutil/trace.go:171","msg":"trace[775231771] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8396; }","duration":"119.77459ms","start":"2026-05-24T13:14:02.638013Z","end":"2026-05-24T13:14:02.757788Z","steps":["trace[775231771] 'agreement among raft nodes before linearized reading' (duration: 119.651267ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:30.554332Z","caller":"traceutil/trace.go:171","msg":"trace[1214823440] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"162.465269ms","start":"2026-05-24T13:14:30.391842Z","end":"2026-05-24T13:14:30.554307Z","steps":["trace[1214823440] 'process raft request' (duration: 162.331165ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:43.536733Z","caller":"traceutil/trace.go:171","msg":"trace[1995536272] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"108.070061ms","start":"2026-05-24T13:14:43.428625Z","end":"2026-05-24T13:14:43.536695Z","steps":["trace[1995536272] 'process raft request' (duration: 107.850547ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:43.685479Z","caller":"traceutil/trace.go:171","msg":"trace[684678032] linearizableReadLoop","detail":"{readStateIndex:9028; appliedIndex:9027; }","duration":"112.730902ms","start":"2026-05-24T13:14:43.572711Z","end":"2026-05-24T13:14:43.685442Z","steps":["trace[684678032] 'read index received' (duration: 89.271416ms)","trace[684678032] 'applied index is now lower than readState.Index' (duration: 23.457686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:14:43.685724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.991266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T13:14:43.685875Z","caller":"traceutil/trace.go:171","msg":"trace[182241017] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8713; }","duration":"113.2095ms","start":"2026-05-24T13:14:43.572635Z","end":"2026-05-24T13:14:43.685844Z","steps":["trace[182241017] 'agreement among raft nodes before linearized reading' (duration: 112.943745ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:14:43.685919Z","caller":"traceutil/trace.go:171","msg":"trace[1340245191] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"175.026902ms","start":"2026-05-24T13:14:43.510859Z","end":"2026-05-24T13:14:43.685886Z","steps":["trace[1340245191] 'process raft request' (duration: 151.183399ms)","trace[1340245191] 'compare' (duration: 23.237572ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:14:56.651553Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ad.wal"} {"level":"info","ts":"2026-05-24T13:16:01.260224Z","caller":"traceutil/trace.go:171","msg":"trace[70268275] transaction","detail":"{read_only:false; response_revision:9467; number_of_response:1; }","duration":"138.849099ms","start":"2026-05-24T13:16:01.121334Z","end":"2026-05-24T13:16:01.260183Z","steps":["trace[70268275] 'process raft request' (duration: 138.622695ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:16:26.899898Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-24T13:16:26.905294Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-24T13:16:26.9054Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-24T13:18:42.439411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8162} {"level":"info","ts":"2026-05-24T13:18:42.504105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8162,"took":"62.103023ms","hash":491555164,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":28434432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-24T13:18:42.504255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":491555164,"revision":8162,"compact-revision":6069} {"level":"info","ts":"2026-05-24T13:21:35.265762Z","caller":"traceutil/trace.go:171","msg":"trace[344752111] transaction","detail":"{read_only:false; response_revision:11873; number_of_response:1; }","duration":"187.501263ms","start":"2026-05-24T13:21:35.078231Z","end":"2026-05-24T13:21:35.265732Z","steps":["trace[344752111] 'process raft request' (duration: 187.34878ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:21:35.350504Z","caller":"traceutil/trace.go:171","msg":"trace[753832961] linearizableReadLoop","detail":"{readStateIndex:12288; appliedIndex:12285; }","duration":"250.360653ms","start":"2026-05-24T13:21:35.10012Z","end":"2026-05-24T13:21:35.350481Z","steps":["trace[753832961] 'read index received' (duration: 165.511233ms)","trace[753832961] 'applied index is now lower than readState.Index' (duration: 84.84828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:21:35.350646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.512735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:21:35.350685Z","caller":"traceutil/trace.go:171","msg":"trace[1943780269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11876; }","duration":"250.591307ms","start":"2026-05-24T13:21:35.100083Z","end":"2026-05-24T13:21:35.350674Z","steps":["trace[1943780269] 'agreement among raft nodes before linearized reading' (duration: 250.496275ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:21:35.350688Z","caller":"traceutil/trace.go:171","msg":"trace[1282644661] transaction","detail":"{read_only:false; response_revision:11875; number_of_response:1; }","duration":"272.239381ms","start":"2026-05-24T13:21:35.078408Z","end":"2026-05-24T13:21:35.350647Z","steps":["trace[1282644661] 'process raft request' (duration: 271.963075ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:21:35.350757Z","caller":"traceutil/trace.go:171","msg":"trace[1572441924] transaction","detail":"{read_only:false; response_revision:11876; number_of_response:1; }","duration":"156.144746ms","start":"2026-05-24T13:21:35.194584Z","end":"2026-05-24T13:21:35.350729Z","steps":["trace[1572441924] 'process raft request' (duration: 155.83234ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:21:35.350917Z","caller":"traceutil/trace.go:171","msg":"trace[923018207] transaction","detail":"{read_only:false; response_revision:11874; number_of_response:1; }","duration":"272.623798ms","start":"2026-05-24T13:21:35.07826Z","end":"2026-05-24T13:21:35.350884Z","steps":["trace[923018207] 'process raft request' (duration: 263.26849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:21:35.84145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.33731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:665"} {"level":"info","ts":"2026-05-24T13:21:35.84154Z","caller":"traceutil/trace.go:171","msg":"trace[1379704913] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11878; }","duration":"113.481733ms","start":"2026-05-24T13:21:35.728041Z","end":"2026-05-24T13:21:35.841523Z","steps":["trace[1379704913] 'range keys from in-memory index tree' (duration: 113.139846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:21:35.841598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.796899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:21:35.841677Z","caller":"traceutil/trace.go:171","msg":"trace[163730255] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11878; }","duration":"113.920131ms","start":"2026-05-24T13:21:35.727739Z","end":"2026-05-24T13:21:35.841659Z","steps":["trace[163730255] 'range keys from in-memory index tree' (duration: 113.673276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:21:35.84171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.912031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:21:35.841779Z","caller":"traceutil/trace.go:171","msg":"trace[1111129541] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11878; }","duration":"114.015633ms","start":"2026-05-24T13:21:35.727739Z","end":"2026-05-24T13:21:35.841754Z","steps":["trace[1111129541] 'range keys from in-memory index tree' (duration: 113.808929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:21:35.841794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.87483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:21:35.841841Z","caller":"traceutil/trace.go:171","msg":"trace[1235871920] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11878; }","duration":"113.935182ms","start":"2026-05-24T13:21:35.727891Z","end":"2026-05-24T13:21:35.841826Z","steps":["trace[1235871920] 'range keys from in-memory index tree' (duration: 113.822199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:21:35.841923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.107734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:21:35.841944Z","caller":"traceutil/trace.go:171","msg":"trace[912778344] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11878; }","duration":"114.170756ms","start":"2026-05-24T13:21:35.727767Z","end":"2026-05-24T13:21:35.841938Z","steps":["trace[912778344] 'range keys from in-memory index tree' (duration: 114.006252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:21:35.841889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.230938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-24T13:21:35.842029Z","caller":"traceutil/trace.go:171","msg":"trace[1283850521] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11878; }","duration":"105.431831ms","start":"2026-05-24T13:21:35.736577Z","end":"2026-05-24T13:21:35.842009Z","steps":["trace[1283850521] 'range keys from in-memory index tree' (duration: 104.88323ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:21:36.545339Z","caller":"traceutil/trace.go:171","msg":"trace[913654464] linearizableReadLoop","detail":"{readStateIndex:12295; appliedIndex:12294; }","duration":"110.283048ms","start":"2026-05-24T13:21:36.435035Z","end":"2026-05-24T13:21:36.545318Z","steps":["trace[913654464] 'read index received' (duration: 37.295997ms)","trace[913654464] 'applied index is now lower than readState.Index' (duration: 72.985961ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:21:36.545422Z","caller":"traceutil/trace.go:171","msg":"trace[1252445205] transaction","detail":"{read_only:false; response_revision:11881; number_of_response:1; }","duration":"125.754267ms","start":"2026-05-24T13:21:36.41963Z","end":"2026-05-24T13:21:36.545384Z","steps":["trace[1252445205] 'process raft request' (duration: 52.785616ms)","trace[1252445205] 'compare' (duration: 72.813548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:21:36.545528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.487682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-86df86dfb7-fkvkr.18b2830608d356a0\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-05-24T13:21:36.545615Z","caller":"traceutil/trace.go:171","msg":"trace[1131423518] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-86df86dfb7-fkvkr.18b2830608d356a0; range_end:; response_count:1; response_revision:11881; }","duration":"110.623705ms","start":"2026-05-24T13:21:36.434981Z","end":"2026-05-24T13:21:36.545605Z","steps":["trace[1131423518] 'agreement among raft nodes before linearized reading' (duration: 110.41574ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:23:42.44609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10519} {"level":"info","ts":"2026-05-24T13:23:42.524167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10519,"took":"75.176098ms","hash":1297533433,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-24T13:23:42.524242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297533433,"revision":10519,"compact-revision":8162} {"level":"warn","ts":"2026-05-24T13:25:21.652317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.030155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:25:21.652468Z","caller":"traceutil/trace.go:171","msg":"trace[216502629] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13341; }","duration":"191.219028ms","start":"2026-05-24T13:25:21.461222Z","end":"2026-05-24T13:25:21.652441Z","steps":["trace[216502629] 'range keys from in-memory index tree' (duration: 190.949323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:28:11.204483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.682252ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T13:28:42.453555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12745} {"level":"info","ts":"2026-05-24T13:28:42.530879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12745,"took":"74.633491ms","hash":1750918535,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":29093888,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-24T13:28:42.530994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1750918535,"revision":12745,"compact-revision":10519} {"level":"info","ts":"2026-05-24T13:30:00.445102Z","caller":"traceutil/trace.go:171","msg":"trace[434498729] transaction","detail":"{read_only:false; response_revision:15782; number_of_response:1; }","duration":"151.783832ms","start":"2026-05-24T13:30:00.293288Z","end":"2026-05-24T13:30:00.445071Z","steps":["trace[434498729] 'process raft request' (duration: 97.571333ms)","trace[434498729] 'compare' (duration: 54.064646ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:30:00.657881Z","caller":"traceutil/trace.go:171","msg":"trace[277735346] linearizableReadLoop","detail":"{readStateIndex:16330; appliedIndex:16328; }","duration":"323.8176ms","start":"2026-05-24T13:30:00.334033Z","end":"2026-05-24T13:30:00.65785Z","steps":["trace[277735346] 'read index received' (duration: 56.849829ms)","trace[277735346] 'applied index is now lower than readState.Index' (duration: 266.966291ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:30:00.657954Z","caller":"traceutil/trace.go:171","msg":"trace[1322161797] transaction","detail":"{read_only:false; response_revision:15783; number_of_response:1; }","duration":"364.279947ms","start":"2026-05-24T13:30:00.293637Z","end":"2026-05-24T13:30:00.657917Z","steps":["trace[1322161797] 'process raft request' (duration: 337.326546ms)","trace[1322161797] 'compare' (duration: 26.765178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:30:00.658103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:30:00.293626Z","time spent":"364.39738ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T13:30:00.658134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.094416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hb77f\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-24T13:30:00.658136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.802815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:30:00.658174Z","caller":"traceutil/trace.go:171","msg":"trace[57470911] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hb77f; range_end:; response_count:1; response_revision:15783; }","duration":"324.151117ms","start":"2026-05-24T13:30:00.33401Z","end":"2026-05-24T13:30:00.658162Z","steps":["trace[57470911] 'agreement among raft nodes before linearized reading' (duration: 323.992564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:30:00.658218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:30:00.333964Z","time spent":"324.246479ms","remote":"127.0.0.1:46288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-hb77f\" "} {"level":"info","ts":"2026-05-24T13:30:00.658222Z","caller":"traceutil/trace.go:171","msg":"trace[1017314949] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:15783; }","duration":"139.937587ms","start":"2026-05-24T13:30:00.518263Z","end":"2026-05-24T13:30:00.6582Z","steps":["trace[1017314949] 'agreement among raft nodes before linearized reading' (duration: 139.795404ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:33:42.463467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15037} {"level":"info","ts":"2026-05-24T13:33:42.553572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15037,"took":"87.098641ms","hash":2928431206,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":31141888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-24T13:33:42.553624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2928431206,"revision":15037,"compact-revision":12745} {"level":"info","ts":"2026-05-24T13:35:43.163933Z","caller":"traceutil/trace.go:171","msg":"trace[1763749837] transaction","detail":"{read_only:false; response_revision:18323; number_of_response:1; }","duration":"122.694244ms","start":"2026-05-24T13:35:43.041213Z","end":"2026-05-24T13:35:43.163908Z","steps":["trace[1763749837] 'process raft request' (duration: 84.913604ms)","trace[1763749837] 'compare' (duration: 37.462963ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:35:43.62522Z","caller":"traceutil/trace.go:171","msg":"trace[337747310] transaction","detail":"{read_only:false; response_revision:18325; number_of_response:1; }","duration":"115.074443ms","start":"2026-05-24T13:35:43.510116Z","end":"2026-05-24T13:35:43.62519Z","steps":["trace[337747310] 'process raft request' (duration: 114.893219ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:43.664249Z","caller":"traceutil/trace.go:171","msg":"trace[2041713837] linearizableReadLoop","detail":"{readStateIndex:18958; appliedIndex:18956; }","duration":"142.889994ms","start":"2026-05-24T13:35:43.521236Z","end":"2026-05-24T13:35:43.664126Z","steps":["trace[2041713837] 'read index received' (duration: 103.767838ms)","trace[2041713837] 'applied index is now lower than readState.Index' (duration: 39.120536ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:35:43.664318Z","caller":"traceutil/trace.go:171","msg":"trace[722704105] transaction","detail":"{read_only:false; response_revision:18326; number_of_response:1; }","duration":"144.424664ms","start":"2026-05-24T13:35:43.519865Z","end":"2026-05-24T13:35:43.664289Z","steps":["trace[722704105] 'process raft request' (duration: 143.942314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:43.664602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.328752ms","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-05-24T13:35:43.664651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.218454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-24T13:35:43.66484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.891227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-m4k9v\" ","response":"range_response_count:1 size:20849"} {"level":"info","ts":"2026-05-24T13:35:43.664884Z","caller":"traceutil/trace.go:171","msg":"trace[1847528404] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-m4k9v; range_end:; response_count:1; response_revision:18326; }","duration":"123.972279ms","start":"2026-05-24T13:35:43.540895Z","end":"2026-05-24T13:35:43.664868Z","steps":["trace[1847528404] 'agreement among raft nodes before linearized reading' (duration: 123.810465ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:43.664961Z","caller":"traceutil/trace.go:171","msg":"trace[445891304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:18326; }","duration":"118.4872ms","start":"2026-05-24T13:35:43.546386Z","end":"2026-05-24T13:35:43.664873Z","steps":["trace[445891304] 'agreement among raft nodes before linearized reading' (duration: 118.188883ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:43.664767Z","caller":"traceutil/trace.go:171","msg":"trace[1558946726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18326; }","duration":"143.527627ms","start":"2026-05-24T13:35:43.521218Z","end":"2026-05-24T13:35:43.664745Z","steps":["trace[1558946726] 'agreement among raft nodes before linearized reading' (duration: 143.175089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:43.665421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.127779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-24T13:35:43.665492Z","caller":"traceutil/trace.go:171","msg":"trace[633879248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18326; }","duration":"137.447576ms","start":"2026-05-24T13:35:43.528026Z","end":"2026-05-24T13:35:43.665474Z","steps":["trace[633879248] 'agreement among raft nodes before linearized reading' (duration: 136.487086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:43.665166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.57348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-24T13:35:43.665745Z","caller":"traceutil/trace.go:171","msg":"trace[138036021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18326; }","duration":"124.08766ms","start":"2026-05-24T13:35:43.541506Z","end":"2026-05-24T13:35:43.665594Z","steps":["trace[138036021] 'agreement among raft nodes before linearized reading' (duration: 123.138951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:43.922423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.129001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-24T13:35:43.92269Z","caller":"traceutil/trace.go:171","msg":"trace[2070402966] transaction","detail":"{read_only:false; response_revision:18327; number_of_response:1; }","duration":"253.394416ms","start":"2026-05-24T13:35:43.669252Z","end":"2026-05-24T13:35:43.922647Z","steps":["trace[2070402966] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 244.575851ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:43.922732Z","caller":"traceutil/trace.go:171","msg":"trace[1084843828] transaction","detail":"{read_only:false; response_revision:18329; number_of_response:1; }","duration":"251.540809ms","start":"2026-05-24T13:35:43.671172Z","end":"2026-05-24T13:35:43.922713Z","steps":["trace[1084843828] 'process raft request' (duration: 251.488798ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:43.922826Z","caller":"traceutil/trace.go:171","msg":"trace[1922300720] transaction","detail":"{read_only:false; response_revision:18328; number_of_response:1; }","duration":"253.167771ms","start":"2026-05-24T13:35:43.669627Z","end":"2026-05-24T13:35:43.922795Z","steps":["trace[1922300720] 'process raft request' (duration: 252.927727ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:43.934653Z","caller":"traceutil/trace.go:171","msg":"trace[1024649235] linearizableReadLoop","detail":"{readStateIndex:18962; appliedIndex:18961; }","duration":"232.94767ms","start":"2026-05-24T13:35:43.701688Z","end":"2026-05-24T13:35:43.934636Z","steps":["trace[1024649235] 'read index received' (duration: 232.887819ms)","trace[1024649235] 'applied index is now lower than readState.Index' (duration: 58.781µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:35:43.93471Z","caller":"traceutil/trace.go:171","msg":"trace[807182897] transaction","detail":"{read_only:false; response_revision:18330; number_of_response:1; }","duration":"254.557759ms","start":"2026-05-24T13:35:43.68013Z","end":"2026-05-24T13:35:43.934688Z","steps":["trace[807182897] 'process raft request' (duration: 254.378115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:43.93496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.262756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-24T13:35:43.935008Z","caller":"traceutil/trace.go:171","msg":"trace[151602410] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18330; }","duration":"233.358938ms","start":"2026-05-24T13:35:43.701638Z","end":"2026-05-24T13:35:43.934997Z","steps":["trace[151602410] 'agreement among raft nodes before linearized reading' (duration: 233.088503ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:35:44.188996Z","caller":"traceutil/trace.go:171","msg":"trace[2113043407] linearizableReadLoop","detail":"{readStateIndex:18963; appliedIndex:18962; }","duration":"254.251743ms","start":"2026-05-24T13:35:43.934699Z","end":"2026-05-24T13:35:44.188951Z","steps":["trace[2113043407] 'read index received' (duration: 237.372058ms)","trace[2113043407] 'applied index is now lower than readState.Index' (duration: 16.877245ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:35:44.189048Z","caller":"traceutil/trace.go:171","msg":"trace[1245971325] transaction","detail":"{read_only:false; response_revision:18331; number_of_response:1; }","duration":"349.275977ms","start":"2026-05-24T13:35:43.839728Z","end":"2026-05-24T13:35:44.189004Z","steps":["trace[1245971325] 'process raft request' (duration: 332.486714ms)","trace[1245971325] 'compare' (duration: 16.356514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T13:35:44.189259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:35:43.839701Z","time spent":"349.423801ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T13:35:44.189334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.671628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-24T13:35:44.189388Z","caller":"traceutil/trace.go:171","msg":"trace[1773951029] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:18331; }","duration":"219.81934ms","start":"2026-05-24T13:35:43.969552Z","end":"2026-05-24T13:35:44.189372Z","steps":["trace[1773951029] 'agreement among raft nodes before linearized reading' (duration: 219.673417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:44.189368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.736448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-24T13:35:44.189456Z","caller":"traceutil/trace.go:171","msg":"trace[197742775] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18331; }","duration":"394.893242ms","start":"2026-05-24T13:35:43.79454Z","end":"2026-05-24T13:35:44.189433Z","steps":["trace[197742775] 'agreement among raft nodes before linearized reading' (duration: 394.563535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:35:44.189506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T13:35:43.794512Z","time spent":"394.985464ms","remote":"127.0.0.1:46288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-24T13:38:38.9218Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-24T13:38:38.92824Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-24T13:38:38.928411Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-24T13:38:42.471374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17492} {"level":"info","ts":"2026-05-24T13:38:42.575989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17492,"took":"101.535324ms","hash":678878905,"current-db-size-bytes":58609664,"current-db-size":"59 MB","current-db-size-in-use-bytes":30011392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-24T13:38:42.576051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":678878905,"revision":17492,"compact-revision":15037}