{"level":"warn","ts":"2026-07-09T03:32:45.159125Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T03:32:45.159224Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229: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.19.213.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T03:32:45.159588Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T03:32:45.159605Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-07-09T03:32:45.159626Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T03:32:45.1604Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-07-09T03:32:45.160476Z","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.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T03:32:45.164346Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.665532ms"} {"level":"info","ts":"2026-07-09T03:32:45.170278Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-07-09T03:32:45.170359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T03:32:45.170385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-07-09T03:32:45.170396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T03:32:45.17041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-07-09T03:32:45.170455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-07-09T03:32:45.176299Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T03:32:45.179485Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T03:32:45.180742Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T03:32:45.18376Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T03:32:45.184112Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T03:32:45.184184Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T03:32:45.184304Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T03:32:45.184355Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T03:32:45.18569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-07-09T03:32:45.188138Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-07-09T03:32:45.191376Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-07-09T03:32:45.191432Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-07-09T03:32:45.191228Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T03:32:45.192031Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T03:32:45.192081Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T03:32:45.471228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T03:32:45.471322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T03:32:45.471363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-07-09T03:32:45.471384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-07-09T03:32:45.471401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-07-09T03:32:45.471422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-07-09T03:32:45.471436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-07-09T03:32:45.472329Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T03:32:45.472434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T03:32:45.472526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T03:32:45.472741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T03:32:45.472822Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T03:32:45.472773Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:32:45.474282Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:32:45.474428Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:32:45.474502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T03:32:45.476533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-07-09T03:32:45.476534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T03:33:05.118983Z","caller":"traceutil/trace.go:171","msg":"trace[130989858] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"155.685879ms","start":"2026-07-09T03:33:04.963271Z","end":"2026-07-09T03:33:05.118957Z","steps":["trace[130989858] 'process raft request' (duration: 155.537655ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:33:05.293122Z","caller":"traceutil/trace.go:171","msg":"trace[725600006] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"110.491139ms","start":"2026-07-09T03:33:05.182591Z","end":"2026-07-09T03:33:05.293082Z","steps":["trace[725600006] 'process raft request' (duration: 104.611509ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:33:28.339315Z","caller":"traceutil/trace.go:171","msg":"trace[1204667211] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"161.014392ms","start":"2026-07-09T03:33:28.178268Z","end":"2026-07-09T03:33:28.339283Z","steps":["trace[1204667211] 'process raft request' (duration: 111.381487ms)","trace[1204667211] 'compare' (duration: 49.505053ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:33:28.358043Z","caller":"traceutil/trace.go:171","msg":"trace[1046941786] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"178.172009ms","start":"2026-07-09T03:33:28.17982Z","end":"2026-07-09T03:33:28.357992Z","steps":["trace[1046941786] 'process raft request' (duration: 177.941594ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:33:28.358133Z","caller":"traceutil/trace.go:171","msg":"trace[384110173] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"121.631733ms","start":"2026-07-09T03:33:28.236478Z","end":"2026-07-09T03:33:28.35811Z","steps":["trace[384110173] 'read index received' (duration: 53.189064ms)","trace[384110173] 'applied index is now lower than readState.Index' (duration: 68.440999ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:33:28.358173Z","caller":"traceutil/trace.go:171","msg":"trace[1643394429] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"141.183264ms","start":"2026-07-09T03:33:28.216962Z","end":"2026-07-09T03:33:28.358145Z","steps":["trace[1643394429] 'process raft request' (duration: 140.951899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:33:28.358345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.807877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfvnf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-09T03:33:28.358457Z","caller":"traceutil/trace.go:171","msg":"trace[502339090] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfvnf; range_end:; response_count:1; response_revision:632; }","duration":"121.995722ms","start":"2026-07-09T03:33:28.236447Z","end":"2026-07-09T03:33:28.358442Z","steps":["trace[502339090] 'agreement among raft nodes before linearized reading' (duration: 121.735396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:33:28.725529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.346824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T03:33:28.725614Z","caller":"traceutil/trace.go:171","msg":"trace[1826978231] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:633; }","duration":"129.497437ms","start":"2026-07-09T03:33:28.596096Z","end":"2026-07-09T03:33:28.725594Z","steps":["trace[1826978231] 'range keys from in-memory index tree' (duration: 129.15225ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:33:29.060435Z","caller":"traceutil/trace.go:171","msg":"trace[2064054822] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"279.490736ms","start":"2026-07-09T03:33:28.78092Z","end":"2026-07-09T03:33:29.06041Z","steps":["trace[2064054822] 'process raft request' (duration: 275.495648ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:33:29.222547Z","caller":"traceutil/trace.go:171","msg":"trace[972303927] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"101.438268ms","start":"2026-07-09T03:33:29.121078Z","end":"2026-07-09T03:33:29.222516Z","steps":["trace[972303927] 'process raft request' (duration: 92.148883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:33:43.450366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.676297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T03:33:43.450512Z","caller":"traceutil/trace.go:171","msg":"trace[1164800106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"123.884032ms","start":"2026-07-09T03:33:43.326605Z","end":"2026-07-09T03:33:43.450489Z","steps":["trace[1164800106] 'range keys from in-memory index tree' (duration: 123.479283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:33:43.451039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.13267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rsp6b\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-09T03:33:43.451136Z","caller":"traceutil/trace.go:171","msg":"trace[130476930] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rsp6b; range_end:; response_count:1; response_revision:795; }","duration":"120.258123ms","start":"2026-07-09T03:33:43.330857Z","end":"2026-07-09T03:33:43.451115Z","steps":["trace[130476930] 'range keys from in-memory index tree' (duration: 119.881765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:34:52.32469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.168465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:34:52.324842Z","caller":"traceutil/trace.go:171","msg":"trace[437336117] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"311.113741ms","start":"2026-07-09T03:34:52.01371Z","end":"2026-07-09T03:34:52.324823Z","steps":["trace[437336117] 'read index received' (duration: 128.084887ms)","trace[437336117] 'applied index is now lower than readState.Index' (duration: 183.024684ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:34:52.324874Z","caller":"traceutil/trace.go:171","msg":"trace[1227301338] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"321.578492ms","start":"2026-07-09T03:34:52.003259Z","end":"2026-07-09T03:34:52.324838Z","steps":["trace[1227301338] 'process raft request' (duration: 138.520607ms)","trace[1227301338] 'compare' (duration: 181.847237ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:34:52.325047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:34:52.003228Z","time spent":"321.728835ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:34:52.325271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.553481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:34:52.325352Z","caller":"traceutil/trace.go:171","msg":"trace[772038014] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1825; }","duration":"311.683704ms","start":"2026-07-09T03:34:52.013655Z","end":"2026-07-09T03:34:52.325339Z","steps":["trace[772038014] 'agreement among raft nodes before linearized reading' (duration: 311.223884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:34:52.325429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:34:52.013632Z","time spent":"311.783785ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-09T03:35:38.403878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.528059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T03:35:38.40404Z","caller":"traceutil/trace.go:171","msg":"trace[915579269] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"322.504876ms","start":"2026-07-09T03:35:38.081508Z","end":"2026-07-09T03:35:38.404013Z","steps":["trace[915579269] 'process raft request' (duration: 193.684561ms)","trace[915579269] 'compare' (duration: 128.234752ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:35:38.404196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:35:38.081481Z","time spent":"322.598949ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T03:37:06.19972Z","caller":"traceutil/trace.go:171","msg":"trace[1363054800] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"152.014837ms","start":"2026-07-09T03:37:06.047676Z","end":"2026-07-09T03:37:06.19969Z","steps":["trace[1363054800] 'process raft request' (duration: 151.827163ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:37:11.348405Z","caller":"traceutil/trace.go:171","msg":"trace[1473839004] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"138.916411ms","start":"2026-07-09T03:37:11.209451Z","end":"2026-07-09T03:37:11.348367Z","steps":["trace[1473839004] 'process raft request' (duration: 138.711717ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:37:11.458864Z","caller":"traceutil/trace.go:171","msg":"trace[2141941150] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"192.770179ms","start":"2026-07-09T03:37:11.266063Z","end":"2026-07-09T03:37:11.458833Z","steps":["trace[2141941150] 'process raft request' (duration: 191.680226ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:01.048799Z","caller":"traceutil/trace.go:171","msg":"trace[332989449] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"130.602619ms","start":"2026-07-09T03:41:00.918167Z","end":"2026-07-09T03:41:01.04877Z","steps":["trace[332989449] 'process raft request' (duration: 124.572615ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:01.057582Z","caller":"traceutil/trace.go:171","msg":"trace[113385507] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"113.846158ms","start":"2026-07-09T03:41:00.943702Z","end":"2026-07-09T03:41:01.057548Z","steps":["trace[113385507] 'process raft request' (duration: 113.623593ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:08.769291Z","caller":"traceutil/trace.go:171","msg":"trace[538049645] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"107.197512ms","start":"2026-07-09T03:41:08.662037Z","end":"2026-07-09T03:41:08.769235Z","steps":["trace[538049645] 'process raft request' (duration: 36.534508ms)","trace[538049645] 'compare' (duration: 70.54155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:41:09.099712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.139993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-07-09T03:41:09.099735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.150613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7582"} {"level":"info","ts":"2026-07-09T03:41:09.099793Z","caller":"traceutil/trace.go:171","msg":"trace[820003804] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4722; }","duration":"218.233826ms","start":"2026-07-09T03:41:08.88154Z","end":"2026-07-09T03:41:09.099774Z","steps":["trace[820003804] 'range keys from in-memory index tree' (duration: 218.036201ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:09.099803Z","caller":"traceutil/trace.go:171","msg":"trace[1631931539] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4722; }","duration":"227.240386ms","start":"2026-07-09T03:41:08.872544Z","end":"2026-07-09T03:41:09.099785Z","steps":["trace[1631931539] 'range keys from in-memory index tree' (duration: 227.035161ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:09.099812Z","caller":"traceutil/trace.go:171","msg":"trace[1366672591] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"194.182775ms","start":"2026-07-09T03:41:08.905578Z","end":"2026-07-09T03:41:09.099761Z","steps":["trace[1366672591] 'process raft request' (duration: 177.014365ms)","trace[1366672591] 'compare' (duration: 16.964065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:41:09.099945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.771884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qnd79\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-07-09T03:41:09.099989Z","caller":"traceutil/trace.go:171","msg":"trace[1299999760] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qnd79; range_end:; response_count:1; response_revision:4722; }","duration":"217.173893ms","start":"2026-07-09T03:41:08.882804Z","end":"2026-07-09T03:41:09.099978Z","steps":["trace[1299999760] 'range keys from in-memory index tree' (duration: 216.559409ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:11.738024Z","caller":"traceutil/trace.go:171","msg":"trace[2026541592] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"219.001363ms","start":"2026-07-09T03:41:11.51899Z","end":"2026-07-09T03:41:11.737991Z","steps":["trace[2026541592] 'process raft request' (duration: 148.956254ms)","trace[2026541592] 'compare' (duration: 69.933437ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:41:33.225868Z","caller":"traceutil/trace.go:171","msg":"trace[260451909] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"182.685621ms","start":"2026-07-09T03:41:33.043161Z","end":"2026-07-09T03:41:33.225846Z","steps":["trace[260451909] 'process raft request' (duration: 182.64344ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:33.225868Z","caller":"traceutil/trace.go:171","msg":"trace[708038132] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"247.863493ms","start":"2026-07-09T03:41:32.977978Z","end":"2026-07-09T03:41:33.225842Z","steps":["trace[708038132] 'process raft request' (duration: 247.769581ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:33.225888Z","caller":"traceutil/trace.go:171","msg":"trace[161022139] linearizableReadLoop","detail":"{readStateIndex:5475; appliedIndex:5472; }","duration":"246.078793ms","start":"2026-07-09T03:41:32.979781Z","end":"2026-07-09T03:41:33.22586Z","steps":["trace[161022139] 'read index received' (duration: 43.590153ms)","trace[161022139] 'applied index is now lower than readState.Index' (duration: 202.48648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:41:33.226065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.259267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:41:33.226128Z","caller":"traceutil/trace.go:171","msg":"trace[686140894] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5272; }","duration":"246.36932ms","start":"2026-07-09T03:41:32.979746Z","end":"2026-07-09T03:41:33.226115Z","steps":["trace[686140894] 'agreement among raft nodes before linearized reading' (duration: 246.196926ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:33.22587Z","caller":"traceutil/trace.go:171","msg":"trace[423523815] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"247.864283ms","start":"2026-07-09T03:41:32.977947Z","end":"2026-07-09T03:41:33.225812Z","steps":["trace[423523815] 'process raft request' (duration: 153.999276ms)","trace[423523815] 'compare' (duration: 93.684483ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:41:33.226478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.030022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.18c08210750c5caa\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-07-09T03:41:33.226558Z","caller":"traceutil/trace.go:171","msg":"trace[1153729344] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.18c08210750c5caa; range_end:; response_count:1; response_revision:5272; }","duration":"199.207856ms","start":"2026-07-09T03:41:33.027304Z","end":"2026-07-09T03:41:33.226512Z","steps":["trace[1153729344] 'agreement among raft nodes before linearized reading' (duration: 198.91329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:41:33.226617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.075836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5edb08fa-bb9d-445e-8f63-2a5d863e1172\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-09T03:41:33.226694Z","caller":"traceutil/trace.go:171","msg":"trace[313489378] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5edb08fa-bb9d-445e-8f63-2a5d863e1172; range_end:; response_count:1; response_revision:5272; }","duration":"167.220779ms","start":"2026-07-09T03:41:33.059455Z","end":"2026-07-09T03:41:33.226676Z","steps":["trace[313489378] 'agreement among raft nodes before linearized reading' (duration: 166.994524ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:33.471561Z","caller":"traceutil/trace.go:171","msg":"trace[72380177] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"240.832206ms","start":"2026-07-09T03:41:33.230697Z","end":"2026-07-09T03:41:33.471529Z","steps":["trace[72380177] 'process raft request' (duration: 237.708618ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:33.471766Z","caller":"traceutil/trace.go:171","msg":"trace[1629720808] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"240.703973ms","start":"2026-07-09T03:41:33.231038Z","end":"2026-07-09T03:41:33.471742Z","steps":["trace[1629720808] 'process raft request' (duration: 240.396945ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:41:33.471642Z","caller":"traceutil/trace.go:171","msg":"trace[431338192] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5477; }","duration":"205.414452ms","start":"2026-07-09T03:41:33.266203Z","end":"2026-07-09T03:41:33.471617Z","steps":["trace[431338192] 'read index received' (duration: 202.12963ms)","trace[431338192] 'applied index is now lower than readState.Index' (duration: 3.283952ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:41:33.472186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.096518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:63313"} {"level":"info","ts":"2026-07-09T03:41:33.472238Z","caller":"traceutil/trace.go:171","msg":"trace[1636495270] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5276; }","duration":"239.173711ms","start":"2026-07-09T03:41:33.233051Z","end":"2026-07-09T03:41:33.472225Z","steps":["trace[1636495270] 'agreement among raft nodes before linearized reading' (duration: 238.946486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:41:33.472446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.847444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-07-09T03:41:33.472521Z","caller":"traceutil/trace.go:171","msg":"trace[1144243250] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5276; }","duration":"201.965687ms","start":"2026-07-09T03:41:33.270541Z","end":"2026-07-09T03:41:33.472507Z","steps":["trace[1144243250] 'agreement among raft nodes before linearized reading' (duration: 201.777593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:41:33.472841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.794265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18c0821074cf245e\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-07-09T03:41:33.473074Z","caller":"traceutil/trace.go:171","msg":"trace[441605261] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.18c0821074cf245e; range_end:; response_count:1; response_revision:5276; }","duration":"203.047871ms","start":"2026-07-09T03:41:33.270008Z","end":"2026-07-09T03:41:33.473056Z","steps":["trace[441605261] 'agreement among raft nodes before linearized reading' (duration: 202.211493ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:42:45.512917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-07-09T03:42:45.579511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"65.033731ms","hash":3921665174,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":33406976,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-09T03:42:45.579622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3921665174,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-07-09T03:47:25.677191Z","caller":"traceutil/trace.go:171","msg":"trace[1945059238] transaction","detail":"{read_only:false; response_revision:8038; number_of_response:1; }","duration":"238.262439ms","start":"2026-07-09T03:47:25.438896Z","end":"2026-07-09T03:47:25.677159Z","steps":["trace[1945059238] 'process raft request' (duration: 238.055875ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:25.812407Z","caller":"traceutil/trace.go:171","msg":"trace[1803584900] transaction","detail":"{read_only:false; response_revision:8039; number_of_response:1; }","duration":"157.423678ms","start":"2026-07-09T03:47:25.654947Z","end":"2026-07-09T03:47:25.812371Z","steps":["trace[1803584900] 'process raft request' (duration: 155.937045ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:37.325439Z","caller":"traceutil/trace.go:171","msg":"trace[288658129] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"109.099459ms","start":"2026-07-09T03:47:37.21627Z","end":"2026-07-09T03:47:37.325369Z","steps":["trace[288658129] 'process raft request' (duration: 108.920785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:38.137643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.549027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:47:38.137759Z","caller":"traceutil/trace.go:171","msg":"trace[2128502339] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8105; }","duration":"197.731071ms","start":"2026-07-09T03:47:37.940005Z","end":"2026-07-09T03:47:38.137736Z","steps":["trace[2128502339] 'count revisions from in-memory index tree' (duration: 197.438365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:38.137768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.315476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-09T03:47:38.137868Z","caller":"traceutil/trace.go:171","msg":"trace[1970267963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8105; }","duration":"173.427448ms","start":"2026-07-09T03:47:37.964418Z","end":"2026-07-09T03:47:38.137845Z","steps":["trace[1970267963] 'range keys from in-memory index tree' (duration: 173.134182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:38.138057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.565165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T03:47:38.138151Z","caller":"traceutil/trace.go:171","msg":"trace[847961582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8105; }","duration":"264.719348ms","start":"2026-07-09T03:47:37.87341Z","end":"2026-07-09T03:47:38.138129Z","steps":["trace[847961582] 'range keys from in-memory index tree' (duration: 264.34304ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:38.79257Z","caller":"traceutil/trace.go:171","msg":"trace[603788960] linearizableReadLoop","detail":"{readStateIndex:8401; appliedIndex:8400; }","duration":"111.192755ms","start":"2026-07-09T03:47:38.681352Z","end":"2026-07-09T03:47:38.792545Z","steps":["trace[603788960] 'read index received' (duration: 111.061552ms)","trace[603788960] 'applied index is now lower than readState.Index' (duration: 129.583µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:47:38.792845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.471431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T03:47:38.792887Z","caller":"traceutil/trace.go:171","msg":"trace[2063109752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8107; }","duration":"111.556193ms","start":"2026-07-09T03:47:38.681319Z","end":"2026-07-09T03:47:38.792875Z","steps":["trace[2063109752] 'agreement among raft nodes before linearized reading' (duration: 111.401789ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:38.904832Z","caller":"traceutil/trace.go:171","msg":"trace[1901808578] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"106.480082ms","start":"2026-07-09T03:47:38.798318Z","end":"2026-07-09T03:47:38.904798Z","steps":["trace[1901808578] 'process raft request' (duration: 94.923099ms)","trace[1901808578] 'compare' (duration: 11.337248ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:38.905146Z","caller":"traceutil/trace.go:171","msg":"trace[1732820108] linearizableReadLoop","detail":"{readStateIndex:8405; appliedIndex:8402; }","duration":"103.713362ms","start":"2026-07-09T03:47:38.801403Z","end":"2026-07-09T03:47:38.905117Z","steps":["trace[1732820108] 'read index received' (duration: 91.79319ms)","trace[1732820108] 'applied index is now lower than readState.Index' (duration: 11.918842ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:47:38.905174Z","caller":"traceutil/trace.go:171","msg":"trace[1991037514] transaction","detail":"{read_only:false; response_revision:8110; number_of_response:1; }","duration":"106.550764ms","start":"2026-07-09T03:47:38.798573Z","end":"2026-07-09T03:47:38.905124Z","steps":["trace[1991037514] 'process raft request' (duration: 106.173415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:47:38.905453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.025678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-09T03:47:38.905481Z","caller":"traceutil/trace.go:171","msg":"trace[1960066593] transaction","detail":"{read_only:false; response_revision:8111; number_of_response:1; }","duration":"106.780949ms","start":"2026-07-09T03:47:38.798682Z","end":"2026-07-09T03:47:38.905463Z","steps":["trace[1960066593] 'process raft request' (duration: 106.304139ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:38.905502Z","caller":"traceutil/trace.go:171","msg":"trace[23633612] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8111; }","duration":"104.124421ms","start":"2026-07-09T03:47:38.801365Z","end":"2026-07-09T03:47:38.90549Z","steps":["trace[23633612] 'agreement among raft nodes before linearized reading' (duration: 103.876765ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:47:45.520087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5952} {"level":"info","ts":"2026-07-09T03:47:45.601355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5952,"took":"79.213715ms","hash":3324307496,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":26959872,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-09T03:47:45.601439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3324307496,"revision":5952,"compact-revision":2862} {"level":"info","ts":"2026-07-09T03:48:05.604172Z","caller":"traceutil/trace.go:171","msg":"trace[635907259] linearizableReadLoop","detail":"{readStateIndex:8606; appliedIndex:8605; }","duration":"173.680014ms","start":"2026-07-09T03:48:05.430439Z","end":"2026-07-09T03:48:05.604119Z","steps":["trace[635907259] 'read index received' (duration: 173.49667ms)","trace[635907259] 'applied index is now lower than readState.Index' (duration: 182.154µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T03:48:05.604219Z","caller":"traceutil/trace.go:171","msg":"trace[1561886911] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"185.125464ms","start":"2026-07-09T03:48:05.419059Z","end":"2026-07-09T03:48:05.604185Z","steps":["trace[1561886911] 'process raft request' (duration: 184.812428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:05.604334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.866508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T03:48:05.604372Z","caller":"traceutil/trace.go:171","msg":"trace[955685443] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8305; }","duration":"173.927379ms","start":"2026-07-09T03:48:05.430434Z","end":"2026-07-09T03:48:05.604361Z","steps":["trace[955685443] 'agreement among raft nodes before linearized reading' (duration: 173.828347ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:18.358962Z","caller":"traceutil/trace.go:171","msg":"trace[594522094] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8412; }","duration":"102.878235ms","start":"2026-07-09T03:48:18.256054Z","end":"2026-07-09T03:48:18.358933Z","steps":["trace[594522094] 'process raft request' (duration: 102.741962ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:18.35911Z","caller":"traceutil/trace.go:171","msg":"trace[828818357] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"104.737566ms","start":"2026-07-09T03:48:18.254242Z","end":"2026-07-09T03:48:18.358979Z","steps":["trace[828818357] 'process raft request' (duration: 91.184369ms)","trace[828818357] 'compare' (duration: 13.23897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:48:19.24428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.711193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T03:48:19.244369Z","caller":"traceutil/trace.go:171","msg":"trace[1892315857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8418; }","duration":"145.820695ms","start":"2026-07-09T03:48:19.098529Z","end":"2026-07-09T03:48:19.24435Z","steps":["trace[1892315857] 'range keys from in-memory index tree' (duration: 145.591791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:19.24428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.927401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T03:48:19.244439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.302594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T03:48:19.244471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.022414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414319"} {"level":"info","ts":"2026-07-09T03:48:19.244503Z","caller":"traceutil/trace.go:171","msg":"trace[1667171746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8418; }","duration":"148.154816ms","start":"2026-07-09T03:48:19.096289Z","end":"2026-07-09T03:48:19.244444Z","steps":["trace[1667171746] 'range keys from in-memory index tree' (duration: 147.764568ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:19.244507Z","caller":"traceutil/trace.go:171","msg":"trace[1208800544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8418; }","duration":"155.402415ms","start":"2026-07-09T03:48:19.089088Z","end":"2026-07-09T03:48:19.244491Z","steps":["trace[1208800544] 'range keys from in-memory index tree' (duration: 155.18062ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:19.24453Z","caller":"traceutil/trace.go:171","msg":"trace[911246908] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8418; }","duration":"153.122465ms","start":"2026-07-09T03:48:19.091395Z","end":"2026-07-09T03:48:19.244517Z","steps":["trace[911246908] 'range keys from in-memory index tree' (duration: 152.591324ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:19.351044Z","caller":"traceutil/trace.go:171","msg":"trace[959134152] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"122.540305ms","start":"2026-07-09T03:48:19.228487Z","end":"2026-07-09T03:48:19.351027Z","steps":["trace[959134152] 'process raft request' (duration: 122.420232ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:19.37919Z","caller":"traceutil/trace.go:171","msg":"trace[777589583] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"129.178832ms","start":"2026-07-09T03:48:19.249991Z","end":"2026-07-09T03:48:19.379169Z","steps":["trace[777589583] 'process raft request' (duration: 129.144942ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:19.379274Z","caller":"traceutil/trace.go:171","msg":"trace[533901242] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"130.083772ms","start":"2026-07-09T03:48:19.249171Z","end":"2026-07-09T03:48:19.379255Z","steps":["trace[533901242] 'process raft request' (duration: 129.937879ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:19.379244Z","caller":"traceutil/trace.go:171","msg":"trace[1829976120] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"130.104752ms","start":"2026-07-09T03:48:19.249092Z","end":"2026-07-09T03:48:19.379196Z","steps":["trace[1829976120] 'process raft request' (duration: 129.880086ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:20.195524Z","caller":"traceutil/trace.go:171","msg":"trace[1901257028] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"247.065215ms","start":"2026-07-09T03:48:19.948436Z","end":"2026-07-09T03:48:20.195501Z","steps":["trace[1901257028] 'process raft request' (duration: 246.927642ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:48:20.21634Z","caller":"traceutil/trace.go:171","msg":"trace[549218567] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"203.646003ms","start":"2026-07-09T03:48:20.012656Z","end":"2026-07-09T03:48:20.216302Z","steps":["trace[549218567] 'process raft request' (duration: 203.457869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:48:20.642507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.096646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3640"} {"level":"info","ts":"2026-07-09T03:48:20.642679Z","caller":"traceutil/trace.go:171","msg":"trace[604604446] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8432; }","duration":"101.282211ms","start":"2026-07-09T03:48:20.541374Z","end":"2026-07-09T03:48:20.642656Z","steps":["trace[604604446] 'range keys from in-memory index tree' (duration: 100.935723ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:49:02.99384Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e1.wal"} {"level":"info","ts":"2026-07-09T03:50:40.168804Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T03:50:40.174416Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-09T03:50:40.174558Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-09T03:52:45.527429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8149} {"level":"info","ts":"2026-07-09T03:52:45.607986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8149,"took":"77.960104ms","hash":1656604473,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":26664960,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-09T03:52:45.608046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1656604473,"revision":8149,"compact-revision":5952} {"level":"warn","ts":"2026-07-09T03:53:03.148387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.174592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T03:53:03.148487Z","caller":"traceutil/trace.go:171","msg":"trace[1187352506] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:10962; }","duration":"123.306855ms","start":"2026-07-09T03:53:03.025152Z","end":"2026-07-09T03:53:03.148459Z","steps":["trace[1187352506] 'range keys from in-memory index tree' (duration: 123.039009ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:53:03.267887Z","caller":"traceutil/trace.go:171","msg":"trace[1076956487] transaction","detail":"{read_only:false; response_revision:10963; number_of_response:1; }","duration":"114.480322ms","start":"2026-07-09T03:53:03.153384Z","end":"2026-07-09T03:53:03.267864Z","steps":["trace[1076956487] 'process raft request' (duration: 114.326489ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:53:03.289326Z","caller":"traceutil/trace.go:171","msg":"trace[1939397153] transaction","detail":"{read_only:false; response_revision:10964; number_of_response:1; }","duration":"101.996559ms","start":"2026-07-09T03:53:03.187262Z","end":"2026-07-09T03:53:03.289259Z","steps":["trace[1939397153] 'process raft request' (duration: 101.232893ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:57:45.533688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10866} {"level":"info","ts":"2026-07-09T03:57:45.620291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10866,"took":"84.054264ms","hash":3700615961,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":27557888,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-09T03:57:45.620362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3700615961,"revision":10866,"compact-revision":8149} {"level":"info","ts":"2026-07-09T03:59:07.381423Z","caller":"traceutil/trace.go:171","msg":"trace[1852456992] transaction","detail":"{read_only:false; response_revision:13433; number_of_response:1; }","duration":"213.025828ms","start":"2026-07-09T03:59:07.168376Z","end":"2026-07-09T03:59:07.381402Z","steps":["trace[1852456992] 'process raft request' (duration: 212.869145ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:07.382271Z","caller":"traceutil/trace.go:171","msg":"trace[1585244219] transaction","detail":"{read_only:false; response_revision:13434; number_of_response:1; }","duration":"213.677662ms","start":"2026-07-09T03:59:07.168573Z","end":"2026-07-09T03:59:07.382251Z","steps":["trace[1585244219] 'process raft request' (duration: 213.576629ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:22.202845Z","caller":"traceutil/trace.go:171","msg":"trace[1434601993] transaction","detail":"{read_only:false; response_revision:13527; number_of_response:1; }","duration":"166.177875ms","start":"2026-07-09T03:59:22.036641Z","end":"2026-07-09T03:59:22.202818Z","steps":["trace[1434601993] 'process raft request' (duration: 166.038012ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:30.767605Z","caller":"traceutil/trace.go:171","msg":"trace[1425576140] transaction","detail":"{read_only:false; response_revision:13679; number_of_response:1; }","duration":"115.52793ms","start":"2026-07-09T03:59:30.652029Z","end":"2026-07-09T03:59:30.767557Z","steps":["trace[1425576140] 'process raft request' (duration: 115.290115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T03:59:30.767799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.137168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3000643-4314-4c17-a7de-067731c19cd6\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-07-09T03:59:30.767883Z","caller":"traceutil/trace.go:171","msg":"trace[118609448] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3000643-4314-4c17-a7de-067731c19cd6; range_end:; response_count:1; response_revision:13679; }","duration":"135.24409ms","start":"2026-07-09T03:59:30.632618Z","end":"2026-07-09T03:59:30.767862Z","steps":["trace[118609448] 'agreement among raft nodes before linearized reading' (duration: 134.970964ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:31.042483Z","caller":"traceutil/trace.go:171","msg":"trace[1336277124] transaction","detail":"{read_only:false; response_revision:13680; number_of_response:1; }","duration":"270.308128ms","start":"2026-07-09T03:59:30.772142Z","end":"2026-07-09T03:59:31.04245Z","steps":["trace[1336277124] 'process raft request' (duration: 270.099733ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:31.119556Z","caller":"traceutil/trace.go:171","msg":"trace[91024276] transaction","detail":"{read_only:false; response_revision:13681; number_of_response:1; }","duration":"346.99188ms","start":"2026-07-09T03:59:30.772534Z","end":"2026-07-09T03:59:31.119526Z","steps":["trace[91024276] 'process raft request' (duration: 281.527692ms)","trace[91024276] 'compare' (duration: 65.267464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:59:31.119736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:59:30.772519Z","time spent":"347.129663ms","remote":"127.0.0.1:54980","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-07-09T03:59:31.119994Z","caller":"traceutil/trace.go:171","msg":"trace[1440267938] transaction","detail":"{read_only:false; response_revision:13683; number_of_response:1; }","duration":"234.611009ms","start":"2026-07-09T03:59:30.885371Z","end":"2026-07-09T03:59:31.119982Z","steps":["trace[1440267938] 'process raft request' (duration: 234.449485ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:31.11999Z","caller":"traceutil/trace.go:171","msg":"trace[912307335] transaction","detail":"{read_only:false; response_revision:13682; number_of_response:1; }","duration":"347.189696ms","start":"2026-07-09T03:59:30.772773Z","end":"2026-07-09T03:59:31.119963Z","steps":["trace[912307335] 'process raft request' (duration: 346.712784ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T03:59:31.120058Z","caller":"traceutil/trace.go:171","msg":"trace[989227139] linearizableReadLoop","detail":"{readStateIndex:14153; appliedIndex:14150; }","duration":"283.542057ms","start":"2026-07-09T03:59:30.836478Z","end":"2026-07-09T03:59:31.12002Z","steps":["trace[989227139] 'read index received' (duration: 205.912663ms)","trace[989227139] 'applied index is now lower than readState.Index' (duration: 77.624294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T03:59:31.120098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T03:59:30.772757Z","time spent":"347.286887ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T03:59:31.120243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.734331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T03:59:31.120338Z","caller":"traceutil/trace.go:171","msg":"trace[1242586670] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:13684; }","duration":"283.888874ms","start":"2026-07-09T03:59:30.836421Z","end":"2026-07-09T03:59:31.12031Z","steps":["trace[1242586670] 'agreement among raft nodes before linearized reading' (duration: 283.70564ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:01:19.26568Z","caller":"traceutil/trace.go:171","msg":"trace[1816230762] transaction","detail":"{read_only:false; response_revision:14805; number_of_response:1; }","duration":"128.886796ms","start":"2026-07-09T04:01:19.136764Z","end":"2026-07-09T04:01:19.26565Z","steps":["trace[1816230762] 'process raft request' (duration: 128.700572ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:01:20.337545Z","caller":"traceutil/trace.go:171","msg":"trace[235734371] linearizableReadLoop","detail":"{readStateIndex:15321; appliedIndex:15320; }","duration":"135.51741ms","start":"2026-07-09T04:01:20.201981Z","end":"2026-07-09T04:01:20.337498Z","steps":["trace[235734371] 'read index received' (duration: 89.130307ms)","trace[235734371] 'applied index is now lower than readState.Index' (duration: 46.385993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:01:20.338087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.214542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-09T04:01:20.33758Z","caller":"traceutil/trace.go:171","msg":"trace[1885791755] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"139.468356ms","start":"2026-07-09T04:01:20.198078Z","end":"2026-07-09T04:01:20.337546Z","steps":["trace[1885791755] 'process raft request' (duration: 93.080073ms)","trace[1885791755] 'compare' (duration: 46.189539ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:01:20.338255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.820605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T04:01:20.338303Z","caller":"traceutil/trace.go:171","msg":"trace[997601491] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14812; }","duration":"118.922787ms","start":"2026-07-09T04:01:20.219368Z","end":"2026-07-09T04:01:20.338291Z","steps":["trace[997601491] 'agreement among raft nodes before linearized reading' (duration: 118.810065ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:01:20.338215Z","caller":"traceutil/trace.go:171","msg":"trace[63575008] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14812; }","duration":"118.371785ms","start":"2026-07-09T04:01:20.219821Z","end":"2026-07-09T04:01:20.338193Z","steps":["trace[63575008] 'agreement among raft nodes before linearized reading' (duration: 118.094349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:01:20.338477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.914677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-09T04:01:20.338512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.329183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T04:01:20.338582Z","caller":"traceutil/trace.go:171","msg":"trace[1861067782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14812; }","duration":"101.417825ms","start":"2026-07-09T04:01:20.237144Z","end":"2026-07-09T04:01:20.338561Z","steps":["trace[1861067782] 'agreement among raft nodes before linearized reading' (duration: 101.219671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:01:20.338577Z","caller":"traceutil/trace.go:171","msg":"trace[784887325] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14812; }","duration":"119.101211ms","start":"2026-07-09T04:01:20.219452Z","end":"2026-07-09T04:01:20.338553Z","steps":["trace[784887325] 'agreement among raft nodes before linearized reading' (duration: 118.661782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:01:20.338571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.580804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:752626"} {"level":"info","ts":"2026-07-09T04:01:20.338702Z","caller":"traceutil/trace.go:171","msg":"trace[144921531] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:14812; }","duration":"136.740517ms","start":"2026-07-09T04:01:20.201939Z","end":"2026-07-09T04:01:20.338679Z","steps":["trace[144921531] 'agreement among raft nodes before linearized reading' (duration: 135.671004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:01:20.338751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.619844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:752626"} {"level":"info","ts":"2026-07-09T04:01:20.338799Z","caller":"traceutil/trace.go:171","msg":"trace[1481008874] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:14812; }","duration":"136.703276ms","start":"2026-07-09T04:01:20.202073Z","end":"2026-07-09T04:01:20.338776Z","steps":["trace[1481008874] 'agreement among raft nodes before linearized reading' (duration: 135.683014ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:02:45.539397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12903} {"level":"info","ts":"2026-07-09T04:02:45.61778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12903,"took":"76.431739ms","hash":3498154194,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":30584832,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-09T04:02:45.617856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3498154194,"revision":12903,"compact-revision":10866} {"level":"info","ts":"2026-07-09T04:06:53.470243Z","caller":"traceutil/trace.go:171","msg":"trace[1552778770] linearizableReadLoop","detail":"{readStateIndex:17966; appliedIndex:17965; }","duration":"140.403608ms","start":"2026-07-09T04:06:53.329808Z","end":"2026-07-09T04:06:53.470212Z","steps":["trace[1552778770] 'read index received' (duration: 140.099111ms)","trace[1552778770] 'applied index is now lower than readState.Index' (duration: 303.447µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:06:53.470563Z","caller":"traceutil/trace.go:171","msg":"trace[72707176] transaction","detail":"{read_only:false; response_revision:17376; number_of_response:1; }","duration":"239.505213ms","start":"2026-07-09T04:06:53.231015Z","end":"2026-07-09T04:06:53.47052Z","steps":["trace[72707176] 'process raft request' (duration: 238.981381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:06:53.470821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.973771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T04:06:53.470882Z","caller":"traceutil/trace.go:171","msg":"trace[1977646932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17376; }","duration":"141.104284ms","start":"2026-07-09T04:06:53.329761Z","end":"2026-07-09T04:06:53.470865Z","steps":["trace[1977646932] 'agreement among raft nodes before linearized reading' (duration: 140.860488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:06:53.471408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.757985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-09T04:06:53.471449Z","caller":"traceutil/trace.go:171","msg":"trace[981167369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17376; }","duration":"140.815037ms","start":"2026-07-09T04:06:53.330617Z","end":"2026-07-09T04:06:53.471432Z","steps":["trace[981167369] 'agreement among raft nodes before linearized reading' (duration: 140.710845ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:06:53.696323Z","caller":"traceutil/trace.go:171","msg":"trace[507400545] linearizableReadLoop","detail":"{readStateIndex:17968; appliedIndex:17966; }","duration":"203.311311ms","start":"2026-07-09T04:06:53.492975Z","end":"2026-07-09T04:06:53.696286Z","steps":["trace[507400545] 'read index received' (duration: 158.40265ms)","trace[507400545] 'applied index is now lower than readState.Index' (duration: 44.907601ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:06:53.696782Z","caller":"traceutil/trace.go:171","msg":"trace[1847431467] transaction","detail":"{read_only:false; response_revision:17377; number_of_response:1; }","duration":"221.169512ms","start":"2026-07-09T04:06:53.475599Z","end":"2026-07-09T04:06:53.696769Z","steps":["trace[1847431467] 'process raft request' (duration: 175.76099ms)","trace[1847431467] 'compare' (duration: 44.608835ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:06:53.696954Z","caller":"traceutil/trace.go:171","msg":"trace[528292483] transaction","detail":"{read_only:false; response_revision:17378; number_of_response:1; }","duration":"214.345343ms","start":"2026-07-09T04:06:53.482602Z","end":"2026-07-09T04:06:53.696947Z","steps":["trace[528292483] 'process raft request' (duration: 213.640498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:06:53.697189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.199381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-senlin-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-09T04:06:53.697221Z","caller":"traceutil/trace.go:171","msg":"trace[130740130] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-senlin-default-user; range_end:; response_count:1; response_revision:17378; }","duration":"204.289544ms","start":"2026-07-09T04:06:53.492924Z","end":"2026-07-09T04:06:53.697213Z","steps":["trace[130740130] 'agreement among raft nodes before linearized reading' (duration: 204.145181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:06:53.697381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.578932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2qlgm\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-07-09T04:06:53.697401Z","caller":"traceutil/trace.go:171","msg":"trace[1992718984] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2qlgm; range_end:; response_count:1; response_revision:17378; }","duration":"189.658154ms","start":"2026-07-09T04:06:53.507738Z","end":"2026-07-09T04:06:53.697396Z","steps":["trace[1992718984] 'agreement among raft nodes before linearized reading' (duration: 189.590092ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:07:45.549154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15378} {"level":"info","ts":"2026-07-09T04:07:45.655627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15378,"took":"103.761697ms","hash":3831793922,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":33099776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-09T04:07:45.655724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831793922,"revision":15378,"compact-revision":12903} {"level":"info","ts":"2026-07-09T04:10:15.0888Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T04:10:15.094562Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-09T04:10:15.094735Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-09T04:12:45.555042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17969} {"level":"info","ts":"2026-07-09T04:12:45.666676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17969,"took":"108.542056ms","hash":1259087639,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":31870976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T04:12:45.666736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1259087639,"revision":17969,"compact-revision":15378} {"level":"warn","ts":"2026-07-09T04:13:45.438136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.920073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:13:45.438216Z","caller":"traceutil/trace.go:171","msg":"trace[2056694862] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:20805; }","duration":"134.049836ms","start":"2026-07-09T04:13:45.30415Z","end":"2026-07-09T04:13:45.4382Z","steps":["trace[2056694862] 'count revisions from in-memory index tree' (duration: 133.82343ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:13:45.560364Z","caller":"traceutil/trace.go:171","msg":"trace[386657349] transaction","detail":"{read_only:false; response_revision:20806; number_of_response:1; }","duration":"104.860567ms","start":"2026-07-09T04:13:45.455476Z","end":"2026-07-09T04:13:45.560336Z","steps":["trace[386657349] 'process raft request' (duration: 104.700593ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:13:45.97639Z","caller":"traceutil/trace.go:171","msg":"trace[1282139999] transaction","detail":"{read_only:false; response_revision:20808; number_of_response:1; }","duration":"217.568735ms","start":"2026-07-09T04:13:45.758796Z","end":"2026-07-09T04:13:45.976365Z","steps":["trace[1282139999] 'process raft request' (duration: 201.137995ms)","trace[1282139999] 'compare' (duration: 16.312877ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:13:46.261331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.431378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:13:46.261471Z","caller":"traceutil/trace.go:171","msg":"trace[1436492232] linearizableReadLoop","detail":"{readStateIndex:21507; appliedIndex:21506; }","duration":"186.701309ms","start":"2026-07-09T04:13:46.074748Z","end":"2026-07-09T04:13:46.261449Z","steps":["trace[1436492232] 'read index received' (duration: 54.973994ms)","trace[1436492232] 'applied index is now lower than readState.Index' (duration: 131.725405ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:13:46.261583Z","caller":"traceutil/trace.go:171","msg":"trace[1420317473] transaction","detail":"{read_only:false; response_revision:20809; number_of_response:1; }","duration":"295.90067ms","start":"2026-07-09T04:13:45.965668Z","end":"2026-07-09T04:13:46.261569Z","steps":["trace[1420317473] 'process raft request' (duration: 164.106624ms)","trace[1420317473] 'compare' (duration: 131.160373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:13:46.261791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.021315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-09T04:13:46.261839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.034786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-09T04:13:46.261874Z","caller":"traceutil/trace.go:171","msg":"trace[897246914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20809; }","duration":"187.151298ms","start":"2026-07-09T04:13:46.074705Z","end":"2026-07-09T04:13:46.261856Z","steps":["trace[897246914] 'agreement among raft nodes before linearized reading' (duration: 186.919413ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:13:46.261926Z","caller":"traceutil/trace.go:171","msg":"trace[939997936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20809; }","duration":"187.172269ms","start":"2026-07-09T04:13:46.074734Z","end":"2026-07-09T04:13:46.261906Z","steps":["trace[939997936] 'agreement among raft nodes before linearized reading' (duration: 186.896493ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:13:46.420112Z","caller":"traceutil/trace.go:171","msg":"trace[861080059] transaction","detail":"{read_only:false; response_revision:20810; number_of_response:1; }","duration":"153.47816ms","start":"2026-07-09T04:13:46.266515Z","end":"2026-07-09T04:13:46.419924Z","steps":["trace[861080059] 'process raft request' (duration: 102.871542ms)","trace[861080059] 'compare' (duration: 50.214689ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:13:46.420711Z","caller":"traceutil/trace.go:171","msg":"trace[1885613122] transaction","detail":"{read_only:false; response_revision:20811; number_of_response:1; }","duration":"154.129015ms","start":"2026-07-09T04:13:46.266553Z","end":"2026-07-09T04:13:46.420682Z","steps":["trace[1885613122] 'process raft request' (duration: 153.323136ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:13:46.4208Z","caller":"traceutil/trace.go:171","msg":"trace[1233927021] linearizableReadLoop","detail":"{readStateIndex:21510; appliedIndex:21507; }","duration":"135.624281ms","start":"2026-07-09T04:13:46.28516Z","end":"2026-07-09T04:13:46.420784Z","steps":["trace[1233927021] 'read index received' (duration: 84.239175ms)","trace[1233927021] 'applied index is now lower than readState.Index' (duration: 51.383616ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:13:46.420843Z","caller":"traceutil/trace.go:171","msg":"trace[523111518] transaction","detail":"{read_only:false; response_revision:20812; number_of_response:1; }","duration":"153.405009ms","start":"2026-07-09T04:13:46.267419Z","end":"2026-07-09T04:13:46.420824Z","steps":["trace[523111518] 'process raft request' (duration: 153.213225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:13:46.42111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.950987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-09T04:13:46.421163Z","caller":"traceutil/trace.go:171","msg":"trace[511525375] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20812; }","duration":"136.03394ms","start":"2026-07-09T04:13:46.285113Z","end":"2026-07-09T04:13:46.421147Z","steps":["trace[511525375] 'agreement among raft nodes before linearized reading' (duration: 135.762954ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:17:23.317316Z","caller":"traceutil/trace.go:171","msg":"trace[488990683] transaction","detail":"{read_only:false; response_revision:23182; number_of_response:1; }","duration":"233.233137ms","start":"2026-07-09T04:17:23.084057Z","end":"2026-07-09T04:17:23.31729Z","steps":["trace[488990683] 'process raft request' (duration: 233.068224ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:17:23.328162Z","caller":"traceutil/trace.go:171","msg":"trace[1204286484] transaction","detail":"{read_only:false; response_revision:23183; number_of_response:1; }","duration":"168.702976ms","start":"2026-07-09T04:17:23.159376Z","end":"2026-07-09T04:17:23.328079Z","steps":["trace[1204286484] 'process raft request' (duration: 168.145834ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:17:23.328144Z","caller":"traceutil/trace.go:171","msg":"trace[646848291] transaction","detail":"{read_only:false; response_revision:23184; number_of_response:1; }","duration":"102.606688ms","start":"2026-07-09T04:17:23.225516Z","end":"2026-07-09T04:17:23.328123Z","steps":["trace[646848291] 'process raft request' (duration: 102.154798ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:17:45.562066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20473} {"level":"info","ts":"2026-07-09T04:17:45.671763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20473,"took":"105.92811ms","hash":3619530133,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":43868160,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-09T04:17:45.671826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3619530133,"revision":20473,"compact-revision":17969} {"level":"info","ts":"2026-07-09T04:20:31.258519Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000655f.wal"} {"level":"info","ts":"2026-07-09T04:20:42.918658Z","caller":"traceutil/trace.go:171","msg":"trace[373118315] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"213.581002ms","start":"2026-07-09T04:20:42.705054Z","end":"2026-07-09T04:20:42.918635Z","steps":["trace[373118315] 'process raft request' (duration: 213.228244ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:42.919228Z","caller":"traceutil/trace.go:171","msg":"trace[892812125] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"150.305268ms","start":"2026-07-09T04:20:42.76888Z","end":"2026-07-09T04:20:42.919185Z","steps":["trace[892812125] 'process raft request' (duration: 150.096743ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:42.919523Z","caller":"traceutil/trace.go:171","msg":"trace[2059093350] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"149.732975ms","start":"2026-07-09T04:20:42.769592Z","end":"2026-07-09T04:20:42.919325Z","steps":["trace[2059093350] 'process raft request' (duration: 149.455539ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:43.494978Z","caller":"traceutil/trace.go:171","msg":"trace[636338275] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"131.64144ms","start":"2026-07-09T04:20:43.363303Z","end":"2026-07-09T04:20:43.494944Z","steps":["trace[636338275] 'process raft request' (duration: 131.333393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:47.863531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.088696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:20:47.863981Z","caller":"traceutil/trace.go:171","msg":"trace[1428598973] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"265.070059ms","start":"2026-07-09T04:20:47.598889Z","end":"2026-07-09T04:20:47.86396Z","steps":["trace[1428598973] 'process raft request' (duration: 59.465091ms)","trace[1428598973] 'compare' (duration: 204.905212ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:47.864093Z","caller":"traceutil/trace.go:171","msg":"trace[872492909] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"168.187039ms","start":"2026-07-09T04:20:47.6959Z","end":"2026-07-09T04:20:47.864087Z","steps":["trace[872492909] 'process raft request' (duration: 167.769609ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:48.685004Z","caller":"traceutil/trace.go:171","msg":"trace[1072394377] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"281.428287ms","start":"2026-07-09T04:20:48.40354Z","end":"2026-07-09T04:20:48.684968Z","steps":["trace[1072394377] 'process raft request' (duration: 281.198072ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:48.684944Z","caller":"traceutil/trace.go:171","msg":"trace[1433444569] linearizableReadLoop","detail":"{readStateIndex:26150; appliedIndex:26149; }","duration":"277.566163ms","start":"2026-07-09T04:20:48.407353Z","end":"2026-07-09T04:20:48.684919Z","steps":["trace[1433444569] 'read index received' (duration: 277.330877ms)","trace[1433444569] 'applied index is now lower than readState.Index' (duration: 234.246µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:48.685168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.783568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:48.685246Z","caller":"traceutil/trace.go:171","msg":"trace[1390986187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25327; }","duration":"277.912251ms","start":"2026-07-09T04:20:48.407315Z","end":"2026-07-09T04:20:48.685227Z","steps":["trace[1390986187] 'agreement among raft nodes before linearized reading' (duration: 277.752558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:48.685493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.265263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:20:48.685501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.682402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T04:20:48.685565Z","caller":"traceutil/trace.go:171","msg":"trace[601169230] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25327; }","duration":"217.380386ms","start":"2026-07-09T04:20:48.468166Z","end":"2026-07-09T04:20:48.685546Z","steps":["trace[601169230] 'agreement among raft nodes before linearized reading' (duration: 217.253563ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:48.685574Z","caller":"traceutil/trace.go:171","msg":"trace[194864845] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25327; }","duration":"201.834725ms","start":"2026-07-09T04:20:48.483722Z","end":"2026-07-09T04:20:48.685557Z","steps":["trace[194864845] 'agreement among raft nodes before linearized reading' (duration: 201.665801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:48.685676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.279312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:48.685727Z","caller":"traceutil/trace.go:171","msg":"trace[154739904] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25327; }","duration":"248.400544ms","start":"2026-07-09T04:20:48.437307Z","end":"2026-07-09T04:20:48.685708Z","steps":["trace[154739904] 'agreement among raft nodes before linearized reading' (duration: 248.281812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:48.685751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.682633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-09T04:20:48.685844Z","caller":"traceutil/trace.go:171","msg":"trace[714381238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25327; }","duration":"116.843247ms","start":"2026-07-09T04:20:48.568979Z","end":"2026-07-09T04:20:48.685823Z","steps":["trace[714381238] 'agreement among raft nodes before linearized reading' (duration: 116.475619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:48.854079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.44719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:20:48.854355Z","caller":"traceutil/trace.go:171","msg":"trace[1142195232] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"161.35512ms","start":"2026-07-09T04:20:48.692981Z","end":"2026-07-09T04:20:48.854336Z","steps":["trace[1142195232] 'process raft request' (duration: 161.284789ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:48.854464Z","caller":"traceutil/trace.go:171","msg":"trace[350544377] linearizableReadLoop","detail":"{readStateIndex:26152; appliedIndex:26150; }","duration":"161.488553ms","start":"2026-07-09T04:20:48.692954Z","end":"2026-07-09T04:20:48.854443Z","steps":["trace[350544377] 'read index received' (duration: 22.535093ms)","trace[350544377] 'applied index is now lower than readState.Index' (duration: 138.95199ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:48.854459Z","caller":"traceutil/trace.go:171","msg":"trace[529543744] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"164.039478ms","start":"2026-07-09T04:20:48.690394Z","end":"2026-07-09T04:20:48.854433Z","steps":["trace[529543744] 'process raft request' (duration: 25.155919ms)","trace[529543744] 'compare' (duration: 138.338267ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:48.854498Z","caller":"traceutil/trace.go:171","msg":"trace[1903713440] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"163.165819ms","start":"2026-07-09T04:20:48.691305Z","end":"2026-07-09T04:20:48.85447Z","steps":["trace[1903713440] 'process raft request' (duration: 162.904243ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:48.854626Z","caller":"traceutil/trace.go:171","msg":"trace[1841649278] transaction","detail":"{read_only:false; response_revision:25331; number_of_response:1; }","duration":"160.93717ms","start":"2026-07-09T04:20:48.693671Z","end":"2026-07-09T04:20:48.854608Z","steps":["trace[1841649278] 'process raft request' (duration: 160.631233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:48.855334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.327001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:48.855442Z","caller":"traceutil/trace.go:171","msg":"trace[464441392] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25331; }","duration":"162.486645ms","start":"2026-07-09T04:20:48.69291Z","end":"2026-07-09T04:20:48.855397Z","steps":["trace[464441392] 'agreement among raft nodes before linearized reading' (duration: 161.646987ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:49.150856Z","caller":"traceutil/trace.go:171","msg":"trace[193001757] linearizableReadLoop","detail":"{readStateIndex:26157; appliedIndex:26156; }","duration":"212.179952ms","start":"2026-07-09T04:20:48.938656Z","end":"2026-07-09T04:20:49.150836Z","steps":["trace[193001757] 'read index received' (duration: 212.08799ms)","trace[193001757] 'applied index is now lower than readState.Index' (duration: 91.042µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:49.150902Z","caller":"traceutil/trace.go:171","msg":"trace[1461807350] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"213.170663ms","start":"2026-07-09T04:20:48.937701Z","end":"2026-07-09T04:20:49.150872Z","steps":["trace[1461807350] 'process raft request' (duration: 212.98987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:49.151038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.390647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-09T04:20:49.151068Z","caller":"traceutil/trace.go:171","msg":"trace[1154850246] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25333; }","duration":"212.486619ms","start":"2026-07-09T04:20:48.938574Z","end":"2026-07-09T04:20:49.15106Z","steps":["trace[1154850246] 'agreement among raft nodes before linearized reading' (duration: 212.364946ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:49.314191Z","caller":"traceutil/trace.go:171","msg":"trace[515414333] linearizableReadLoop","detail":"{readStateIndex:26159; appliedIndex:26157; }","duration":"163.262062ms","start":"2026-07-09T04:20:49.15091Z","end":"2026-07-09T04:20:49.314172Z","steps":["trace[515414333] 'read index received' (duration: 75.329568ms)","trace[515414333] 'applied index is now lower than readState.Index' (duration: 87.931804ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:49.314226Z","caller":"traceutil/trace.go:171","msg":"trace[84548979] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"374.39966ms","start":"2026-07-09T04:20:48.939789Z","end":"2026-07-09T04:20:49.314189Z","steps":["trace[84548979] 'process raft request' (duration: 286.423926ms)","trace[84548979] 'compare' (duration: 87.78083ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:49.314316Z","caller":"traceutil/trace.go:171","msg":"trace[1456161699] transaction","detail":"{read_only:false; response_revision:25335; number_of_response:1; }","duration":"374.093714ms","start":"2026-07-09T04:20:48.940175Z","end":"2026-07-09T04:20:49.314269Z","steps":["trace[1456161699] 'process raft request' (duration: 373.950961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:49.31437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:48.939772Z","time spent":"374.523412ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:20:49.314546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:48.940137Z","time spent":"374.293978ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:20:49.31454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.165935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-09T04:20:49.314621Z","caller":"traceutil/trace.go:171","msg":"trace[1114104625] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25335; }","duration":"161.292928ms","start":"2026-07-09T04:20:49.15331Z","end":"2026-07-09T04:20:49.314603Z","steps":["trace[1114104625] 'agreement among raft nodes before linearized reading' (duration: 161.074473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:49.314633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.619484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:49.314718Z","caller":"traceutil/trace.go:171","msg":"trace[849818714] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25335; }","duration":"352.812898ms","start":"2026-07-09T04:20:48.96189Z","end":"2026-07-09T04:20:49.314703Z","steps":["trace[849818714] 'agreement among raft nodes before linearized reading' (duration: 352.608174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:49.314731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.547671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"warn","ts":"2026-07-09T04:20:49.314792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:48.961875Z","time spent":"352.893951ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"info","ts":"2026-07-09T04:20:49.314797Z","caller":"traceutil/trace.go:171","msg":"trace[1952346078] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25335; }","duration":"133.649513ms","start":"2026-07-09T04:20:49.181128Z","end":"2026-07-09T04:20:49.314777Z","steps":["trace[1952346078] 'agreement among raft nodes before linearized reading' (duration: 133.457209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:49.314835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.295554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:35 size:293324"} {"level":"info","ts":"2026-07-09T04:20:49.314872Z","caller":"traceutil/trace.go:171","msg":"trace[1146313919] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:35; response_revision:25335; }","duration":"329.385696ms","start":"2026-07-09T04:20:48.985475Z","end":"2026-07-09T04:20:49.314861Z","steps":["trace[1146313919] 'agreement among raft nodes before linearized reading' (duration: 328.812143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:49.314905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:48.985452Z","time spent":"329.446366ms","remote":"127.0.0.1:55170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":35,"response size":293347,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-07-09T04:20:49.832522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.598299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:20:49.832639Z","caller":"traceutil/trace.go:171","msg":"trace[1115211968] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"232.890914ms","start":"2026-07-09T04:20:49.599718Z","end":"2026-07-09T04:20:49.832609Z","steps":["trace[1115211968] 'process raft request' (duration: 101.112001ms)","trace[1115211968] 'compare' (duration: 131.419225ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:50.170896Z","caller":"traceutil/trace.go:171","msg":"trace[1288410234] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"302.883916ms","start":"2026-07-09T04:20:49.867978Z","end":"2026-07-09T04:20:50.170862Z","steps":["trace[1288410234] 'process raft request' (duration: 283.39335ms)","trace[1288410234] 'compare' (duration: 19.243391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:50.171078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:49.867967Z","time spent":"303.019779ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:50.173483Z","caller":"traceutil/trace.go:171","msg":"trace[2136851267] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"304.633024ms","start":"2026-07-09T04:20:49.868827Z","end":"2026-07-09T04:20:50.17346Z","steps":["trace[2136851267] 'process raft request' (duration: 304.44348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:50.173628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:49.868808Z","time spent":"304.745987ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:50.173717Z","caller":"traceutil/trace.go:171","msg":"trace[1058254343] linearizableReadLoop","detail":"{readStateIndex:26164; appliedIndex:26161; }","duration":"241.499683ms","start":"2026-07-09T04:20:49.932198Z","end":"2026-07-09T04:20:50.173698Z","steps":["trace[1058254343] 'read index received' (duration: 219.183615ms)","trace[1058254343] 'applied index is now lower than readState.Index' (duration: 22.315048ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:50.173816Z","caller":"traceutil/trace.go:171","msg":"trace[883701192] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"304.260987ms","start":"2026-07-09T04:20:49.869546Z","end":"2026-07-09T04:20:50.173807Z","steps":["trace[883701192] 'process raft request' (duration: 303.875098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:50.173899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:49.86954Z","time spent":"304.305017ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:20:50.173996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.790109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:50.174045Z","caller":"traceutil/trace.go:171","msg":"trace[134548091] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25340; }","duration":"241.86317ms","start":"2026-07-09T04:20:49.932167Z","end":"2026-07-09T04:20:50.17403Z","steps":["trace[134548091] 'agreement among raft nodes before linearized reading' (duration: 241.790569ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:51.006798Z","caller":"traceutil/trace.go:171","msg":"trace[841698506] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"116.841305ms","start":"2026-07-09T04:20:50.889904Z","end":"2026-07-09T04:20:51.006745Z","steps":["trace[841698506] 'process raft request' (duration: 116.691292ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:51.257286Z","caller":"traceutil/trace.go:171","msg":"trace[1025306823] linearizableReadLoop","detail":"{readStateIndex:26170; appliedIndex:26166; }","duration":"353.063313ms","start":"2026-07-09T04:20:50.904205Z","end":"2026-07-09T04:20:51.257268Z","steps":["trace[1025306823] 'read index received' (duration: 102.44963ms)","trace[1025306823] 'applied index is now lower than readState.Index' (duration: 250.612803ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:51.257488Z","caller":"traceutil/trace.go:171","msg":"trace[240775580] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"366.198012ms","start":"2026-07-09T04:20:50.891277Z","end":"2026-07-09T04:20:51.257475Z","steps":["trace[240775580] 'process raft request' (duration: 343.911614ms)","trace[240775580] 'compare' (duration: 21.177313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:51.257673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:50.891251Z","time spent":"366.298993ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:51.257809Z","caller":"traceutil/trace.go:171","msg":"trace[756839159] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"366.439157ms","start":"2026-07-09T04:20:50.891363Z","end":"2026-07-09T04:20:51.257802Z","steps":["trace[756839159] 'process raft request' (duration: 365.809913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.257849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:50.891358Z","time spent":"366.470627ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:51.257925Z","caller":"traceutil/trace.go:171","msg":"trace[660503732] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"366.521268ms","start":"2026-07-09T04:20:50.891397Z","end":"2026-07-09T04:20:51.257919Z","steps":["trace[660503732] 'process raft request' (duration: 365.813183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.257961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:50.891393Z","time spent":"366.551489ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:51.258048Z","caller":"traceutil/trace.go:171","msg":"trace[1512748474] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"255.340126ms","start":"2026-07-09T04:20:51.002702Z","end":"2026-07-09T04:20:51.258042Z","steps":["trace[1512748474] 'process raft request' (duration: 254.533779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.258183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.975863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-769bf68f7d-6kfl5.18c082693a1cc582\" ","response":"range_response_count:1 size:1138"} {"level":"info","ts":"2026-07-09T04:20:51.258211Z","caller":"traceutil/trace.go:171","msg":"trace[1796818283] range","detail":"{range_begin:/registry/events/openstack/barbican-api-769bf68f7d-6kfl5.18c082693a1cc582; range_end:; response_count:1; response_revision:25347; }","duration":"354.053434ms","start":"2026-07-09T04:20:50.90415Z","end":"2026-07-09T04:20:51.258204Z","steps":["trace[1796818283] 'agreement among raft nodes before linearized reading' (duration: 353.959842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.258231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:50.904139Z","time spent":"354.087036ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1161,"request content":"key:\"/registry/events/openstack/barbican-api-769bf68f7d-6kfl5.18c082693a1cc582\" "} {"level":"info","ts":"2026-07-09T04:20:51.519859Z","caller":"traceutil/trace.go:171","msg":"trace[2106188132] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"381.340613ms","start":"2026-07-09T04:20:51.138478Z","end":"2026-07-09T04:20:51.519819Z","steps":["trace[2106188132] 'process raft request' (duration: 380.968314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.520099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.138469Z","time spent":"381.502507ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:51.520219Z","caller":"traceutil/trace.go:171","msg":"trace[1058274252] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26171; }","duration":"262.83361ms","start":"2026-07-09T04:20:51.257346Z","end":"2026-07-09T04:20:51.520179Z","steps":["trace[1058274252] 'read index received' (duration: 262.154734ms)","trace[1058274252] 'applied index is now lower than readState.Index' (duration: 676.476µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:51.520307Z","caller":"traceutil/trace.go:171","msg":"trace[1291906870] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"364.536145ms","start":"2026-07-09T04:20:51.155727Z","end":"2026-07-09T04:20:51.520263Z","steps":["trace[1291906870] 'process raft request' (duration: 364.029914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.520356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.694156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:20:51.520402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.115921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:51.520436Z","caller":"traceutil/trace.go:171","msg":"trace[1534233786] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25349; }","duration":"400.804678ms","start":"2026-07-09T04:20:51.119612Z","end":"2026-07-09T04:20:51.520417Z","steps":["trace[1534233786] 'agreement among raft nodes before linearized reading' (duration: 400.606584ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:51.520448Z","caller":"traceutil/trace.go:171","msg":"trace[446732503] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25349; }","duration":"516.209403ms","start":"2026-07-09T04:20:51.004219Z","end":"2026-07-09T04:20:51.520429Z","steps":["trace[446732503] 'agreement among raft nodes before linearized reading' (duration: 516.105801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.520448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.155703Z","time spent":"364.662257ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:20:51.520477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.119563Z","time spent":"400.90562ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:20:51.520488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.004204Z","time spent":"516.272634ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:20:51.520704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.77393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-09T04:20:51.520875Z","caller":"traceutil/trace.go:171","msg":"trace[1152711997] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25349; }","duration":"396.938934ms","start":"2026-07-09T04:20:51.123853Z","end":"2026-07-09T04:20:51.520792Z","steps":["trace[1152711997] 'agreement among raft nodes before linearized reading' (duration: 396.343101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.52108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.123843Z","time spent":"397.103298ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":141,"response size":32,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:20:51.70334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.565261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:20:51.703867Z","caller":"traceutil/trace.go:171","msg":"trace[2128429186] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"442.738386ms","start":"2026-07-09T04:20:51.261073Z","end":"2026-07-09T04:20:51.703812Z","steps":["trace[2128429186] 'process raft request' (duration: 330.567722ms)","trace[2128429186] 'compare' (duration: 111.254874ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:51.703958Z","caller":"traceutil/trace.go:171","msg":"trace[350294836] linearizableReadLoop","detail":"{readStateIndex:26176; appliedIndex:26173; }","duration":"183.642847ms","start":"2026-07-09T04:20:51.520292Z","end":"2026-07-09T04:20:51.703935Z","steps":["trace[350294836] 'read index received' (duration: 71.471944ms)","trace[350294836] 'applied index is now lower than readState.Index' (duration: 112.169873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:51.704062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.261048Z","time spent":"442.899219ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:51.704054Z","caller":"traceutil/trace.go:171","msg":"trace[621093138] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"385.016012ms","start":"2026-07-09T04:20:51.319001Z","end":"2026-07-09T04:20:51.704017Z","steps":["trace[621093138] 'process raft request' (duration: 384.503991ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:51.704141Z","caller":"traceutil/trace.go:171","msg":"trace[2115882078] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"383.327166ms","start":"2026-07-09T04:20:51.320792Z","end":"2026-07-09T04:20:51.704119Z","steps":["trace[2115882078] 'process raft request' (duration: 383.070111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.704176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.651049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-09T04:20:51.704211Z","caller":"traceutil/trace.go:171","msg":"trace[1904801783] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25352; }","duration":"424.718291ms","start":"2026-07-09T04:20:51.279482Z","end":"2026-07-09T04:20:51.7042Z","steps":["trace[1904801783] 'agreement among raft nodes before linearized reading' (duration: 424.545177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.704214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.247654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-09T04:20:51.704225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.318986Z","time spent":"385.150206ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:20:51.704243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.320773Z","time spent":"383.411128ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:51.704261Z","caller":"traceutil/trace.go:171","msg":"trace[1538222786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25352; }","duration":"367.412217ms","start":"2026-07-09T04:20:51.336838Z","end":"2026-07-09T04:20:51.704251Z","steps":["trace[1538222786] 'agreement among raft nodes before linearized reading' (duration: 367.255324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.704256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.279464Z","time spent":"424.779752ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-09T04:20:51.70429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.336818Z","time spent":"367.465359ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:20:51.70406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.479273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:51.704379Z","caller":"traceutil/trace.go:171","msg":"trace[30554135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25352; }","duration":"295.863282ms","start":"2026-07-09T04:20:51.408506Z","end":"2026-07-09T04:20:51.70437Z","steps":["trace[30554135] 'agreement among raft nodes before linearized reading' (duration: 295.465413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.704378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.745353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T04:20:51.704412Z","caller":"traceutil/trace.go:171","msg":"trace[1273896583] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25352; }","duration":"414.800224ms","start":"2026-07-09T04:20:51.289603Z","end":"2026-07-09T04:20:51.704403Z","steps":["trace[1273896583] 'agreement among raft nodes before linearized reading' (duration: 414.724293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:51.704438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:51.289594Z","time spent":"414.838345ms","remote":"127.0.0.1:55100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-07-09T04:20:52.310813Z","caller":"traceutil/trace.go:171","msg":"trace[605054571] linearizableReadLoop","detail":"{readStateIndex:26179; appliedIndex:26178; }","duration":"133.724526ms","start":"2026-07-09T04:20:52.177061Z","end":"2026-07-09T04:20:52.310785Z","steps":["trace[605054571] 'read index received' (duration: 105.192882ms)","trace[605054571] 'applied index is now lower than readState.Index' (duration: 28.530604ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:52.310949Z","caller":"traceutil/trace.go:171","msg":"trace[1765377115] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"135.068204ms","start":"2026-07-09T04:20:52.175838Z","end":"2026-07-09T04:20:52.310907Z","steps":["trace[1765377115] 'process raft request' (duration: 106.378337ms)","trace[1765377115] 'compare' (duration: 28.143665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:52.311033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.96581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-09T04:20:52.311072Z","caller":"traceutil/trace.go:171","msg":"trace[154701202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25355; }","duration":"134.060173ms","start":"2026-07-09T04:20:52.177001Z","end":"2026-07-09T04:20:52.311061Z","steps":["trace[154701202] 'agreement among raft nodes before linearized reading' (duration: 133.899529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:52.536502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.933351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:20:52.536652Z","caller":"traceutil/trace.go:171","msg":"trace[1092285305] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"221.567918ms","start":"2026-07-09T04:20:52.315072Z","end":"2026-07-09T04:20:52.53664Z","steps":["trace[1092285305] 'process raft request' (duration: 221.513586ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:52.536709Z","caller":"traceutil/trace.go:171","msg":"trace[1168489687] linearizableReadLoop","detail":"{readStateIndex:26180; appliedIndex:26179; }","duration":"225.8063ms","start":"2026-07-09T04:20:52.310876Z","end":"2026-07-09T04:20:52.536682Z","steps":["trace[1168489687] 'read index received' (duration: 106.583591ms)","trace[1168489687] 'applied index is now lower than readState.Index' (duration: 119.220929ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:20:52.53692Z","caller":"traceutil/trace.go:171","msg":"trace[118584751] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"359.080586ms","start":"2026-07-09T04:20:52.177794Z","end":"2026-07-09T04:20:52.536874Z","steps":["trace[118584751] 'process raft request' (duration: 239.689564ms)","trace[118584751] 'compare' (duration: 118.772938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:52.537204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:52.177779Z","time spent":"359.186929ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:20:52.537289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.636819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:20:52.537375Z","caller":"traceutil/trace.go:171","msg":"trace[316146005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25357; }","duration":"125.767932ms","start":"2026-07-09T04:20:52.411596Z","end":"2026-07-09T04:20:52.537364Z","steps":["trace[316146005] 'agreement among raft nodes before linearized reading' (duration: 125.645669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:52.537365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.600659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T04:20:52.537467Z","caller":"traceutil/trace.go:171","msg":"trace[817427125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25357; }","duration":"344.724761ms","start":"2026-07-09T04:20:52.192712Z","end":"2026-07-09T04:20:52.537436Z","steps":["trace[817427125] 'agreement among raft nodes before linearized reading' (duration: 344.440855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:52.537532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:52.192701Z","time spent":"344.818064ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T04:20:52.92414Z","caller":"traceutil/trace.go:171","msg":"trace[1611175625] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"180.452138ms","start":"2026-07-09T04:20:52.743666Z","end":"2026-07-09T04:20:52.924118Z","steps":["trace[1611175625] 'process raft request' (duration: 180.316375ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:53.392375Z","caller":"traceutil/trace.go:171","msg":"trace[1579819643] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"122.811576ms","start":"2026-07-09T04:20:53.269536Z","end":"2026-07-09T04:20:53.392348Z","steps":["trace[1579819643] 'process raft request' (duration: 122.613932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:53.669258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.801459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:20:53.670204Z","caller":"traceutil/trace.go:171","msg":"trace[1460223182] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"400.497321ms","start":"2026-07-09T04:20:53.26968Z","end":"2026-07-09T04:20:53.670177Z","steps":["trace[1460223182] 'process raft request' (duration: 248.67808ms)","trace[1460223182] 'compare' (duration: 150.515913ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:53.670327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:53.269662Z","time spent":"400.604794ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:53.670346Z","caller":"traceutil/trace.go:171","msg":"trace[234099518] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"143.642393ms","start":"2026-07-09T04:20:53.526688Z","end":"2026-07-09T04:20:53.67033Z","steps":["trace[234099518] 'process raft request' (duration: 143.601762ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:53.67043Z","caller":"traceutil/trace.go:171","msg":"trace[1639824334] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"400.662335ms","start":"2026-07-09T04:20:53.269741Z","end":"2026-07-09T04:20:53.670403Z","steps":["trace[1639824334] 'process raft request' (duration: 400.144344ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:53.670488Z","caller":"traceutil/trace.go:171","msg":"trace[384077001] linearizableReadLoop","detail":"{readStateIndex:26187; appliedIndex:26185; }","duration":"261.326697ms","start":"2026-07-09T04:20:53.409153Z","end":"2026-07-09T04:20:53.67048Z","steps":["trace[384077001] 'read index received' (duration: 109.271551ms)","trace[384077001] 'applied index is now lower than readState.Index' (duration: 152.054386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:20:53.670663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.516741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:20:53.670669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:20:53.269712Z","time spent":"400.785717ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:20:53.670691Z","caller":"traceutil/trace.go:171","msg":"trace[862978520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25364; }","duration":"261.586482ms","start":"2026-07-09T04:20:53.409097Z","end":"2026-07-09T04:20:53.670684Z","steps":["trace[862978520] 'agreement among raft nodes before linearized reading' (duration: 261.515491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:20:53.670908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.353027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T04:20:53.670955Z","caller":"traceutil/trace.go:171","msg":"trace[358780803] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25364; }","duration":"128.430949ms","start":"2026-07-09T04:20:53.542511Z","end":"2026-07-09T04:20:53.670942Z","steps":["trace[358780803] 'agreement among raft nodes before linearized reading' (duration: 128.308006ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:53.953474Z","caller":"traceutil/trace.go:171","msg":"trace[1243413125] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"244.076469ms","start":"2026-07-09T04:20:53.709372Z","end":"2026-07-09T04:20:53.953448Z","steps":["trace[1243413125] 'process raft request' (duration: 243.878395ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:20:53.954665Z","caller":"traceutil/trace.go:171","msg":"trace[265694552] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"243.129969ms","start":"2026-07-09T04:20:53.711512Z","end":"2026-07-09T04:20:53.954642Z","steps":["trace[265694552] 'process raft request' (duration: 242.935475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:02.331389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.856451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:02.331484Z","caller":"traceutil/trace.go:171","msg":"trace[1706600715] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25425; }","duration":"204.019593ms","start":"2026-07-09T04:21:02.127437Z","end":"2026-07-09T04:21:02.331456Z","steps":["trace[1706600715] 'count revisions from in-memory index tree' (duration: 203.781168ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:02.600284Z","caller":"traceutil/trace.go:171","msg":"trace[1365002799] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"250.725226ms","start":"2026-07-09T04:21:02.349536Z","end":"2026-07-09T04:21:02.600261Z","steps":["trace[1365002799] 'process raft request' (duration: 250.302847ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:02.600532Z","caller":"traceutil/trace.go:171","msg":"trace[1210245888] linearizableReadLoop","detail":"{readStateIndex:26252; appliedIndex:26251; }","duration":"192.550392ms","start":"2026-07-09T04:21:02.407969Z","end":"2026-07-09T04:21:02.600519Z","steps":["trace[1210245888] 'read index received' (duration: 191.919339ms)","trace[1210245888] 'applied index is now lower than readState.Index' (duration: 629.623µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:02.600675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.696386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:02.600709Z","caller":"traceutil/trace.go:171","msg":"trace[2107001003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25426; }","duration":"192.741537ms","start":"2026-07-09T04:21:02.407956Z","end":"2026-07-09T04:21:02.600697Z","steps":["trace[2107001003] 'agreement among raft nodes before linearized reading' (duration: 192.675076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.090028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.637065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:03.090459Z","caller":"traceutil/trace.go:171","msg":"trace[1006115829] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"520.774823ms","start":"2026-07-09T04:21:02.569659Z","end":"2026-07-09T04:21:03.090434Z","steps":["trace[1006115829] 'process raft request' (duration: 225.635587ms)","trace[1006115829] 'compare' (duration: 294.087562ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:03.090589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:02.569639Z","time spent":"520.879665ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:03.090575Z","caller":"traceutil/trace.go:171","msg":"trace[645379685] linearizableReadLoop","detail":"{readStateIndex:26253; appliedIndex:26252; }","duration":"489.55973ms","start":"2026-07-09T04:21:02.600565Z","end":"2026-07-09T04:21:03.090125Z","steps":["trace[645379685] 'read index received' (duration: 194.798332ms)","trace[645379685] 'applied index is now lower than readState.Index' (duration: 294.759718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:03.090712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.502795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-09T04:21:03.090799Z","caller":"traceutil/trace.go:171","msg":"trace[1005481350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25427; }","duration":"514.625128ms","start":"2026-07-09T04:21:02.576151Z","end":"2026-07-09T04:21:03.090776Z","steps":["trace[1005481350] 'agreement among raft nodes before linearized reading' (duration: 514.380683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.090809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.052159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:21:03.090842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:02.576135Z","time spent":"514.697419ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T04:21:03.090847Z","caller":"traceutil/trace.go:171","msg":"trace[592672478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25427; }","duration":"489.120151ms","start":"2026-07-09T04:21:02.601716Z","end":"2026-07-09T04:21:03.090836Z","steps":["trace[592672478] 'agreement among raft nodes before linearized reading' (duration: 488.991958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.090893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.081285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-09T04:21:03.090926Z","caller":"traceutil/trace.go:171","msg":"trace[484380662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25427; }","duration":"119.155426ms","start":"2026-07-09T04:21:02.971758Z","end":"2026-07-09T04:21:03.090913Z","steps":["trace[484380662] 'agreement among raft nodes before linearized reading' (duration: 119.046404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.090969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.300868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T04:21:03.090997Z","caller":"traceutil/trace.go:171","msg":"trace[251178686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25427; }","duration":"470.36228ms","start":"2026-07-09T04:21:02.620627Z","end":"2026-07-09T04:21:03.09099Z","steps":["trace[251178686] 'agreement among raft nodes before linearized reading' (duration: 470.237667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.09103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:02.620608Z","time spent":"470.415331ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T04:21:03.091016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:02.601704Z","time spent":"489.213062ms","remote":"127.0.0.1:54756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T04:21:03.385661Z","caller":"traceutil/trace.go:171","msg":"trace[197241966] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"290.975286ms","start":"2026-07-09T04:21:03.09466Z","end":"2026-07-09T04:21:03.385635Z","steps":["trace[197241966] 'process raft request' (duration: 228.246674ms)","trace[197241966] 'compare' (duration: 62.432455ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:03.665084Z","caller":"traceutil/trace.go:171","msg":"trace[1504592877] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"569.292574ms","start":"2026-07-09T04:21:03.095768Z","end":"2026-07-09T04:21:03.66506Z","steps":["trace[1504592877] 'process raft request' (duration: 568.427875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.665238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.09574Z","time spent":"569.412017ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:03.665288Z","caller":"traceutil/trace.go:171","msg":"trace[1755267760] linearizableReadLoop","detail":"{readStateIndex:26256; appliedIndex:26253; }","duration":"516.266294ms","start":"2026-07-09T04:21:03.148998Z","end":"2026-07-09T04:21:03.665264Z","steps":["trace[1755267760] 'read index received' (duration: 173.923535ms)","trace[1755267760] 'applied index is now lower than readState.Index' (duration: 342.340919ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:03.665356Z","caller":"traceutil/trace.go:171","msg":"trace[225806712] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"569.012918ms","start":"2026-07-09T04:21:03.096285Z","end":"2026-07-09T04:21:03.665298Z","steps":["trace[225806712] 'process raft request' (duration: 568.691651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.665509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.096263Z","time spent":"569.167061ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:03.665963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.013201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:03.666075Z","caller":"traceutil/trace.go:171","msg":"trace[339771306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25430; }","duration":"256.169964ms","start":"2026-07-09T04:21:03.409867Z","end":"2026-07-09T04:21:03.666037Z","steps":["trace[339771306] 'agreement among raft nodes before linearized reading' (duration: 255.5466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.666218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.206654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-09T04:21:03.666258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.103879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-09T04:21:03.666304Z","caller":"traceutil/trace.go:171","msg":"trace[1693351174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25430; }","duration":"517.329777ms","start":"2026-07-09T04:21:03.148953Z","end":"2026-07-09T04:21:03.666283Z","steps":["trace[1693351174] 'agreement among raft nodes before linearized reading' (duration: 516.390537ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:03.666313Z","caller":"traceutil/trace.go:171","msg":"trace[626259050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25430; }","duration":"228.202691ms","start":"2026-07-09T04:21:03.438093Z","end":"2026-07-09T04:21:03.666296Z","steps":["trace[626259050] 'agreement among raft nodes before linearized reading' (duration: 227.324912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:03.666362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.148929Z","time spent":"517.419749ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T04:21:04.069055Z","caller":"traceutil/trace.go:171","msg":"trace[1060900816] linearizableReadLoop","detail":"{readStateIndex:26259; appliedIndex:26258; }","duration":"318.61602ms","start":"2026-07-09T04:21:03.750412Z","end":"2026-07-09T04:21:04.069029Z","steps":["trace[1060900816] 'read index received' (duration: 246.706358ms)","trace[1060900816] 'applied index is now lower than readState.Index' (duration: 71.908622ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:04.06919Z","caller":"traceutil/trace.go:171","msg":"trace[1127955591] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"346.160832ms","start":"2026-07-09T04:21:03.723017Z","end":"2026-07-09T04:21:04.069178Z","steps":["trace[1127955591] 'process raft request' (duration: 274.090726ms)","trace[1127955591] 'compare' (duration: 71.749559ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:04.069298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.722993Z","time spent":"346.229004ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:04.069425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.996679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-09T04:21:04.069533Z","caller":"traceutil/trace.go:171","msg":"trace[862673620] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25433; }","duration":"319.142392ms","start":"2026-07-09T04:21:03.75036Z","end":"2026-07-09T04:21:04.069502Z","steps":["trace[862673620] 'agreement among raft nodes before linearized reading' (duration: 318.899507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:04.069961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.750339Z","time spent":"319.603343ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-09T04:21:04.379891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.028148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:04.380241Z","caller":"traceutil/trace.go:171","msg":"trace[1795081056] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"383.769116ms","start":"2026-07-09T04:21:03.996441Z","end":"2026-07-09T04:21:04.38021Z","steps":["trace[1795081056] 'process raft request' (duration: 383.550171ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:04.38034Z","caller":"traceutil/trace.go:171","msg":"trace[1047230373] linearizableReadLoop","detail":"{readStateIndex:26262; appliedIndex:26259; }","duration":"311.164547ms","start":"2026-07-09T04:21:04.069146Z","end":"2026-07-09T04:21:04.380311Z","steps":["trace[1047230373] 'read index received' (duration: 189.588727ms)","trace[1047230373] 'applied index is now lower than readState.Index' (duration: 121.57202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:04.380385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.996434Z","time spent":"383.868118ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:04.380465Z","caller":"traceutil/trace.go:171","msg":"trace[1462163720] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"306.360202ms","start":"2026-07-09T04:21:04.07408Z","end":"2026-07-09T04:21:04.380441Z","steps":["trace[1462163720] 'process raft request' (duration: 306.026984ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:04.380488Z","caller":"traceutil/trace.go:171","msg":"trace[23833347] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"383.841367ms","start":"2026-07-09T04:21:03.996632Z","end":"2026-07-09T04:21:04.380473Z","steps":["trace[23833347] 'process raft request' (duration: 383.435278ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:04.380554Z","caller":"traceutil/trace.go:171","msg":"trace[262918969] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"384.214095ms","start":"2026-07-09T04:21:03.996327Z","end":"2026-07-09T04:21:04.380542Z","steps":["trace[262918969] 'process raft request' (duration: 262.454921ms)","trace[262918969] 'compare' (duration: 120.893125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:04.380613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:04.074058Z","time spent":"306.462234ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:04.380637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.996307Z","time spent":"384.298837ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:04.380618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.996593Z","time spent":"383.927209ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:04.383379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.54795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-07-09T04:21:04.383447Z","caller":"traceutil/trace.go:171","msg":"trace[1049066535] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25437; }","duration":"191.649852ms","start":"2026-07-09T04:21:04.191776Z","end":"2026-07-09T04:21:04.383426Z","steps":["trace[1049066535] 'agreement among raft nodes before linearized reading' (duration: 191.463578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:04.383754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.441407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:21:04.383762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.200455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-09T04:21:04.383805Z","caller":"traceutil/trace.go:171","msg":"trace[20723734] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25437; }","duration":"547.525069ms","start":"2026-07-09T04:21:03.836263Z","end":"2026-07-09T04:21:04.383788Z","steps":["trace[20723734] 'agreement among raft nodes before linearized reading' (duration: 544.373019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:04.383884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.836241Z","time spent":"547.62037ms","remote":"127.0.0.1:41848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:21:04.383371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.970172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T04:21:04.383817Z","caller":"traceutil/trace.go:171","msg":"trace[1169052168] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25437; }","duration":"629.356489ms","start":"2026-07-09T04:21:03.754442Z","end":"2026-07-09T04:21:04.383798Z","steps":["trace[1169052168] 'agreement among raft nodes before linearized reading' (duration: 626.095197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:04.384054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.754423Z","time spent":"629.614474ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-09T04:21:04.384019Z","caller":"traceutil/trace.go:171","msg":"trace[1390214503] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25437; }","duration":"406.660548ms","start":"2026-07-09T04:21:03.977312Z","end":"2026-07-09T04:21:04.383973Z","steps":["trace[1390214503] 'agreement among raft nodes before linearized reading' (duration: 403.307453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:04.384158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:03.977291Z","time spent":"406.812301ms","remote":"127.0.0.1:40398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:21:08.341553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.67575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa21a11b-3d1a-4fc2-b5bc-841aa59298de\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-09T04:21:08.34161Z","caller":"traceutil/trace.go:171","msg":"trace[1959618959] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa21a11b-3d1a-4fc2-b5bc-841aa59298de; range_end:; response_count:1; response_revision:25524; }","duration":"136.789291ms","start":"2026-07-09T04:21:08.204807Z","end":"2026-07-09T04:21:08.341596Z","steps":["trace[1959618959] 'range keys from in-memory index tree' (duration: 136.508626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.341622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.033354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:08.34171Z","caller":"traceutil/trace.go:171","msg":"trace[1463890560] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25524; }","duration":"140.207437ms","start":"2026-07-09T04:21:08.201479Z","end":"2026-07-09T04:21:08.341687Z","steps":["trace[1463890560] 'count revisions from in-memory index tree' (duration: 139.827309ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:08.499655Z","caller":"traceutil/trace.go:171","msg":"trace[1366857942] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"152.010025ms","start":"2026-07-09T04:21:08.347623Z","end":"2026-07-09T04:21:08.499634Z","steps":["trace[1366857942] 'process raft request' (duration: 151.796191ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:08.716022Z","caller":"traceutil/trace.go:171","msg":"trace[1111762679] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"320.89759ms","start":"2026-07-09T04:21:08.395104Z","end":"2026-07-09T04:21:08.716002Z","steps":["trace[1111762679] 'process raft request' (duration: 320.732077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.716137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:08.395086Z","time spent":"320.995173ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:08.716308Z","caller":"traceutil/trace.go:171","msg":"trace[194648823] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"319.600142ms","start":"2026-07-09T04:21:08.3967Z","end":"2026-07-09T04:21:08.7163Z","steps":["trace[194648823] 'process raft request' (duration: 319.264005ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:08.716298Z","caller":"traceutil/trace.go:171","msg":"trace[1454158450] linearizableReadLoop","detail":"{readStateIndex:26355; appliedIndex:26351; }","duration":"313.158521ms","start":"2026-07-09T04:21:08.403122Z","end":"2026-07-09T04:21:08.71628Z","steps":["trace[1454158450] 'read index received' (duration: 96.406ms)","trace[1454158450] 'applied index is now lower than readState.Index' (duration: 216.751621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:08.716353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:08.396694Z","time spent":"319.635902ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:08.716379Z","caller":"traceutil/trace.go:171","msg":"trace[1546027030] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"319.599502ms","start":"2026-07-09T04:21:08.396735Z","end":"2026-07-09T04:21:08.716334Z","steps":["trace[1546027030] 'process raft request' (duration: 319.468079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.716504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.370906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-07-09T04:21:08.716535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:08.396729Z","time spent":"319.724964ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:08.716563Z","caller":"traceutil/trace.go:171","msg":"trace[2044658486] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25528; }","duration":"313.476018ms","start":"2026-07-09T04:21:08.403073Z","end":"2026-07-09T04:21:08.716549Z","steps":["trace[2044658486] 'agreement among raft nodes before linearized reading' (duration: 313.289714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.716606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:08.403051Z","time spent":"313.545209ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-09T04:21:08.759746Z","caller":"traceutil/trace.go:171","msg":"trace[1331235895] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"133.042411ms","start":"2026-07-09T04:21:08.626671Z","end":"2026-07-09T04:21:08.759714Z","steps":["trace[1331235895] 'process raft request' (duration: 132.062499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.759878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.553929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:08.759937Z","caller":"traceutil/trace.go:171","msg":"trace[1119253014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25529; }","duration":"350.650181ms","start":"2026-07-09T04:21:08.40927Z","end":"2026-07-09T04:21:08.75992Z","steps":["trace[1119253014] 'agreement among raft nodes before linearized reading' (duration: 350.549258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.759969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:08.409256Z","time spent":"350.707343ms","remote":"127.0.0.1:54720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T04:21:08.75996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.994876ms","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:6"} {"level":"warn","ts":"2026-07-09T04:21:08.759976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.870314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-09T04:21:08.76001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.965244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T04:21:08.760038Z","caller":"traceutil/trace.go:171","msg":"trace[400809375] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25529; }","duration":"232.123099ms","start":"2026-07-09T04:21:08.527892Z","end":"2026-07-09T04:21:08.760015Z","steps":["trace[400809375] 'agreement among raft nodes before linearized reading' (duration: 231.925594ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:08.760055Z","caller":"traceutil/trace.go:171","msg":"trace[603099820] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25529; }","duration":"210.056165ms","start":"2026-07-09T04:21:08.549985Z","end":"2026-07-09T04:21:08.760041Z","steps":["trace[603099820] 'agreement among raft nodes before linearized reading' (duration: 209.79735ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:08.76005Z","caller":"traceutil/trace.go:171","msg":"trace[2114409072] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25529; }","duration":"354.957646ms","start":"2026-07-09T04:21:08.405071Z","end":"2026-07-09T04:21:08.760029Z","steps":["trace[2114409072] 'agreement among raft nodes before linearized reading' (duration: 354.728501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:08.760102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:08.405023Z","time spent":"355.070208ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-09T04:21:14.588927Z","caller":"traceutil/trace.go:171","msg":"trace[498130656] linearizableReadLoop","detail":"{readStateIndex:26416; appliedIndex:26415; }","duration":"146.74183ms","start":"2026-07-09T04:21:14.442151Z","end":"2026-07-09T04:21:14.588893Z","steps":["trace[498130656] 'read index received' (duration: 146.490565ms)","trace[498130656] 'applied index is now lower than readState.Index' (duration: 250.075µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:14.589003Z","caller":"traceutil/trace.go:171","msg":"trace[878808543] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"148.027368ms","start":"2026-07-09T04:21:14.440937Z","end":"2026-07-09T04:21:14.588964Z","steps":["trace[878808543] 'process raft request' (duration: 147.653939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:14.589165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.989726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fa21a11b-3d1a-4fc2-b5bc-841aa59298de\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-09T04:21:14.589199Z","caller":"traceutil/trace.go:171","msg":"trace[1565650416] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fa21a11b-3d1a-4fc2-b5bc-841aa59298de; range_end:; response_count:1; response_revision:25587; }","duration":"147.073278ms","start":"2026-07-09T04:21:14.442115Z","end":"2026-07-09T04:21:14.589189Z","steps":["trace[1565650416] 'agreement among raft nodes before linearized reading' (duration: 146.894173ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.758175Z","caller":"traceutil/trace.go:171","msg":"trace[16489847] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"162.500615ms","start":"2026-07-09T04:21:14.595647Z","end":"2026-07-09T04:21:14.758147Z","steps":["trace[16489847] 'process raft request' (duration: 162.326231ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.866557Z","caller":"traceutil/trace.go:171","msg":"trace[13769812] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"269.423255ms","start":"2026-07-09T04:21:14.597082Z","end":"2026-07-09T04:21:14.866505Z","steps":["trace[13769812] 'process raft request' (duration: 269.120897ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.866597Z","caller":"traceutil/trace.go:171","msg":"trace[351781189] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"268.968174ms","start":"2026-07-09T04:21:14.597608Z","end":"2026-07-09T04:21:14.866576Z","steps":["trace[351781189] 'process raft request' (duration: 268.77635ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.866744Z","caller":"traceutil/trace.go:171","msg":"trace[309594401] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"268.074395ms","start":"2026-07-09T04:21:14.598611Z","end":"2026-07-09T04:21:14.866685Z","steps":["trace[309594401] 'process raft request' (duration: 267.883731ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.866794Z","caller":"traceutil/trace.go:171","msg":"trace[1615723120] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"125.177639ms","start":"2026-07-09T04:21:14.74159Z","end":"2026-07-09T04:21:14.866767Z","steps":["trace[1615723120] 'process raft request' (duration: 125.113817ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.866795Z","caller":"traceutil/trace.go:171","msg":"trace[618835615] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"130.485274ms","start":"2026-07-09T04:21:14.736279Z","end":"2026-07-09T04:21:14.866764Z","steps":["trace[618835615] 'process raft request' (duration: 130.27227ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:14.866994Z","caller":"traceutil/trace.go:171","msg":"trace[1595347943] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"129.533614ms","start":"2026-07-09T04:21:14.737444Z","end":"2026-07-09T04:21:14.866977Z","steps":["trace[1595347943] 'process raft request' (duration: 129.181666ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:45.726929Z","caller":"traceutil/trace.go:171","msg":"trace[264058914] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26676; }","duration":"106.632933ms","start":"2026-07-09T04:21:45.620275Z","end":"2026-07-09T04:21:45.726908Z","steps":["trace[264058914] 'read index received' (duration: 106.531751ms)","trace[264058914] 'applied index is now lower than readState.Index' (duration: 100.382µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:45.727089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.790656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:45.727133Z","caller":"traceutil/trace.go:171","msg":"trace[636793961] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25841; }","duration":"106.886138ms","start":"2026-07-09T04:21:45.620237Z","end":"2026-07-09T04:21:45.727123Z","steps":["trace[636793961] 'agreement among raft nodes before linearized reading' (duration: 106.765525ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:45.727511Z","caller":"traceutil/trace.go:171","msg":"trace[541305196] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"147.955657ms","start":"2026-07-09T04:21:45.579544Z","end":"2026-07-09T04:21:45.7275Z","steps":["trace[541305196] 'process raft request' (duration: 147.18141ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:46.036907Z","caller":"traceutil/trace.go:171","msg":"trace[540323384] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"100.290063ms","start":"2026-07-09T04:21:45.936594Z","end":"2026-07-09T04:21:46.036885Z","steps":["trace[540323384] 'process raft request' (duration: 100.1692ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:46.176084Z","caller":"traceutil/trace.go:171","msg":"trace[1056135785] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"239.137421ms","start":"2026-07-09T04:21:45.936923Z","end":"2026-07-09T04:21:46.17606Z","steps":["trace[1056135785] 'process raft request' (duration: 224.612893ms)","trace[1056135785] 'compare' (duration: 14.366025ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:46.176429Z","caller":"traceutil/trace.go:171","msg":"trace[2142149786] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"239.180883ms","start":"2026-07-09T04:21:45.937226Z","end":"2026-07-09T04:21:46.176407Z","steps":["trace[2142149786] 'process raft request' (duration: 238.790104ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:46.17657Z","caller":"traceutil/trace.go:171","msg":"trace[46688410] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"237.880034ms","start":"2026-07-09T04:21:45.938654Z","end":"2026-07-09T04:21:46.176534Z","steps":["trace[46688410] 'process raft request' (duration: 237.6788ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:46.431802Z","caller":"traceutil/trace.go:171","msg":"trace[1712769933] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"174.007477ms","start":"2026-07-09T04:21:46.257764Z","end":"2026-07-09T04:21:46.431771Z","steps":["trace[1712769933] 'process raft request' (duration: 173.70457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.339234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.077615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:47.339601Z","caller":"traceutil/trace.go:171","msg":"trace[460474680] linearizableReadLoop","detail":"{readStateIndex:26687; appliedIndex:26683; }","duration":"356.138511ms","start":"2026-07-09T04:21:46.983421Z","end":"2026-07-09T04:21:47.339559Z","steps":["trace[460474680] 'read index received' (duration: 76.257868ms)","trace[460474680] 'applied index is now lower than readState.Index' (duration: 279.879422ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:47.339684Z","caller":"traceutil/trace.go:171","msg":"trace[689951834] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"356.426127ms","start":"2026-07-09T04:21:46.983245Z","end":"2026-07-09T04:21:47.339671Z","steps":["trace[689951834] 'process raft request' (duration: 356.188912ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:47.339668Z","caller":"traceutil/trace.go:171","msg":"trace[584092352] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"357.290366ms","start":"2026-07-09T04:21:46.982324Z","end":"2026-07-09T04:21:47.339615Z","steps":["trace[584092352] 'process raft request' (duration: 357.00585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.339754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:46.983234Z","time spent":"356.480388ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:47.339909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:46.982315Z","time spent":"357.476459ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:47.339995Z","caller":"traceutil/trace.go:171","msg":"trace[1092717765] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"338.069706ms","start":"2026-07-09T04:21:47.001881Z","end":"2026-07-09T04:21:47.339951Z","steps":["trace[1092717765] 'process raft request' (duration: 337.640626ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:47.340098Z","caller":"traceutil/trace.go:171","msg":"trace[728419764] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"358.539564ms","start":"2026-07-09T04:21:46.98149Z","end":"2026-07-09T04:21:47.34003Z","steps":["trace[728419764] 'process raft request' (duration: 211.558958ms)","trace[728419764] 'compare' (duration: 145.83224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:47.340139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:47.001856Z","time spent":"338.211148ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:47.340166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.715544ms","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-07-09T04:21:47.340244Z","caller":"traceutil/trace.go:171","msg":"trace[271313248] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25852; }","duration":"356.805706ms","start":"2026-07-09T04:21:46.983415Z","end":"2026-07-09T04:21:47.340221Z","steps":["trace[271313248] 'agreement among raft nodes before linearized reading' (duration: 356.511979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.340279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:46.981479Z","time spent":"358.708007ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:47.340291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:46.983382Z","time spent":"356.899248ms","remote":"127.0.0.1:54892","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":"warn","ts":"2026-07-09T04:21:47.350018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.644795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T04:21:47.350068Z","caller":"traceutil/trace.go:171","msg":"trace[441898557] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25853; }","duration":"230.727617ms","start":"2026-07-09T04:21:47.119326Z","end":"2026-07-09T04:21:47.350054Z","steps":["trace[441898557] 'agreement among raft nodes before linearized reading' (duration: 230.542443ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:47.350156Z","caller":"traceutil/trace.go:171","msg":"trace[1459167228] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"102.593533ms","start":"2026-07-09T04:21:47.247412Z","end":"2026-07-09T04:21:47.350006Z","steps":["trace[1459167228] 'process raft request' (duration: 102.229956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.350192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.504854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-09T04:21:47.350295Z","caller":"traceutil/trace.go:171","msg":"trace[1528767649] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25853; }","duration":"231.619326ms","start":"2026-07-09T04:21:47.118656Z","end":"2026-07-09T04:21:47.350275Z","steps":["trace[1528767649] 'agreement among raft nodes before linearized reading' (duration: 231.384121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.350219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.518659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:47.350341Z","caller":"traceutil/trace.go:171","msg":"trace[1050904463] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25853; }","duration":"201.677202ms","start":"2026-07-09T04:21:47.148654Z","end":"2026-07-09T04:21:47.350332Z","steps":["trace[1050904463] 'agreement among raft nodes before linearized reading' (duration: 201.536049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.619913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.50623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:47.620196Z","caller":"traceutil/trace.go:171","msg":"trace[754234614] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"263.137147ms","start":"2026-07-09T04:21:47.357024Z","end":"2026-07-09T04:21:47.620161Z","steps":["trace[754234614] 'process raft request' (duration: 129.102054ms)","trace[754234614] 'compare' (duration: 133.0197ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:47.620343Z","caller":"traceutil/trace.go:171","msg":"trace[1147096807] linearizableReadLoop","detail":"{readStateIndex:26691; appliedIndex:26689; }","duration":"212.444757ms","start":"2026-07-09T04:21:47.407882Z","end":"2026-07-09T04:21:47.620327Z","steps":["trace[1147096807] 'read index received' (duration: 78.159619ms)","trace[1147096807] 'applied index is now lower than readState.Index' (duration: 134.284288ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:47.620474Z","caller":"traceutil/trace.go:171","msg":"trace[79029209] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"263.404402ms","start":"2026-07-09T04:21:47.357057Z","end":"2026-07-09T04:21:47.620462Z","steps":["trace[79029209] 'process raft request' (duration: 263.009344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.620563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.619991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:47.62065Z","caller":"traceutil/trace.go:171","msg":"trace[2104707320] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25855; }","duration":"212.788915ms","start":"2026-07-09T04:21:47.407849Z","end":"2026-07-09T04:21:47.620638Z","steps":["trace[2104707320] 'agreement among raft nodes before linearized reading' (duration: 212.623851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.620554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.663342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:47.620719Z","caller":"traceutil/trace.go:171","msg":"trace[1712725925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25855; }","duration":"212.886026ms","start":"2026-07-09T04:21:47.407821Z","end":"2026-07-09T04:21:47.620707Z","steps":["trace[1712725925] 'agreement among raft nodes before linearized reading' (duration: 212.671482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:47.915354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.960435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-09T04:21:47.915439Z","caller":"traceutil/trace.go:171","msg":"trace[909994481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25855; }","duration":"184.099198ms","start":"2026-07-09T04:21:47.731319Z","end":"2026-07-09T04:21:47.915419Z","steps":["trace[909994481] 'range keys from in-memory index tree' (duration: 183.777871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:48.207677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.367505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:21:48.20778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.353079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T04:21:48.207778Z","caller":"traceutil/trace.go:171","msg":"trace[399376895] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25856; }","duration":"126.521398ms","start":"2026-07-09T04:21:48.08123Z","end":"2026-07-09T04:21:48.207751Z","steps":["trace[399376895] 'count revisions from in-memory index tree' (duration: 126.279282ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:48.20785Z","caller":"traceutil/trace.go:171","msg":"trace[2069858741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25856; }","duration":"161.461822ms","start":"2026-07-09T04:21:48.046369Z","end":"2026-07-09T04:21:48.207831Z","steps":["trace[2069858741] 'range keys from in-memory index tree' (duration: 161.049163ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:48.276809Z","caller":"traceutil/trace.go:171","msg":"trace[688656659] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"153.004278ms","start":"2026-07-09T04:21:48.123768Z","end":"2026-07-09T04:21:48.276772Z","steps":["trace[688656659] 'process raft request' (duration: 152.671521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:48.556579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.97423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:48.556755Z","caller":"traceutil/trace.go:171","msg":"trace[1222019241] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"274.482104ms","start":"2026-07-09T04:21:48.282255Z","end":"2026-07-09T04:21:48.556737Z","steps":["trace[1222019241] 'process raft request' (duration: 274.419453ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:48.556873Z","caller":"traceutil/trace.go:171","msg":"trace[296225993] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"345.839486ms","start":"2026-07-09T04:21:48.211023Z","end":"2026-07-09T04:21:48.556863Z","steps":["trace[296225993] 'process raft request' (duration: 137.503099ms)","trace[296225993] 'compare' (duration: 207.685513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:48.556929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:48.211005Z","time spent":"345.888607ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:48.557045Z","caller":"traceutil/trace.go:171","msg":"trace[1052135223] linearizableReadLoop","detail":"{readStateIndex:26694; appliedIndex:26693; }","duration":"280.322532ms","start":"2026-07-09T04:21:48.276679Z","end":"2026-07-09T04:21:48.557002Z","steps":["trace[1052135223] 'read index received' (duration: 71.860552ms)","trace[1052135223] 'applied index is now lower than readState.Index' (duration: 208.4609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:48.557545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.120008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-09T04:21:48.557587Z","caller":"traceutil/trace.go:171","msg":"trace[1272018812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25859; }","duration":"376.200439ms","start":"2026-07-09T04:21:48.181376Z","end":"2026-07-09T04:21:48.557576Z","steps":["trace[1272018812] 'agreement among raft nodes before linearized reading' (duration: 376.062836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:48.557622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:48.181356Z","time spent":"376.259011ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T04:21:48.557608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.135208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-09T04:21:48.557667Z","caller":"traceutil/trace.go:171","msg":"trace[199691477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25859; }","duration":"376.21657ms","start":"2026-07-09T04:21:48.181436Z","end":"2026-07-09T04:21:48.557652Z","steps":["trace[199691477] 'agreement among raft nodes before linearized reading' (duration: 376.010735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:48.557701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:48.181425Z","time spent":"376.270811ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T04:21:48.565998Z","caller":"traceutil/trace.go:171","msg":"trace[1046383303] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"128.990002ms","start":"2026-07-09T04:21:48.436992Z","end":"2026-07-09T04:21:48.565982Z","steps":["trace[1046383303] 'process raft request' (duration: 128.89386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:48.566119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.941324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:48.566192Z","caller":"traceutil/trace.go:171","msg":"trace[1894401764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25860; }","duration":"158.048477ms","start":"2026-07-09T04:21:48.408125Z","end":"2026-07-09T04:21:48.566174Z","steps":["trace[1894401764] 'agreement among raft nodes before linearized reading' (duration: 157.889513ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:48.906473Z","caller":"traceutil/trace.go:171","msg":"trace[697792079] linearizableReadLoop","detail":"{readStateIndex:26698; appliedIndex:26696; }","duration":"146.153988ms","start":"2026-07-09T04:21:48.760298Z","end":"2026-07-09T04:21:48.906452Z","steps":["trace[697792079] 'read index received' (duration: 60.923482ms)","trace[697792079] 'applied index is now lower than readState.Index' (duration: 85.228826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:48.906677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.366762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-09T04:21:48.906721Z","caller":"traceutil/trace.go:171","msg":"trace[331218666] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:25862; }","duration":"146.460444ms","start":"2026-07-09T04:21:48.760249Z","end":"2026-07-09T04:21:48.90671Z","steps":["trace[331218666] 'agreement among raft nodes before linearized reading' (duration: 146.295811ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:48.906856Z","caller":"traceutil/trace.go:171","msg":"trace[941354812] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"344.539317ms","start":"2026-07-09T04:21:48.562261Z","end":"2026-07-09T04:21:48.9068Z","steps":["trace[941354812] 'process raft request' (duration: 259.186619ms)","trace[941354812] 'compare' (duration: 84.47623ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:48.906966Z","caller":"traceutil/trace.go:171","msg":"trace[1576079933] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"343.496886ms","start":"2026-07-09T04:21:48.563448Z","end":"2026-07-09T04:21:48.906945Z","steps":["trace[1576079933] 'process raft request' (duration: 342.935913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:48.907042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:48.562242Z","time spent":"344.707262ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:48.907067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:48.56344Z","time spent":"343.578687ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:49.383901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.978742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:49.38405Z","caller":"traceutil/trace.go:171","msg":"trace[1032993010] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"288.839839ms","start":"2026-07-09T04:21:49.095197Z","end":"2026-07-09T04:21:49.384037Z","steps":["trace[1032993010] 'process raft request' (duration: 288.788378ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:49.384193Z","caller":"traceutil/trace.go:171","msg":"trace[1202041377] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"318.502337ms","start":"2026-07-09T04:21:49.06566Z","end":"2026-07-09T04:21:49.384162Z","steps":["trace[1202041377] 'process raft request' (duration: 151.184938ms)","trace[1202041377] 'compare' (duration: 166.855979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:49.384339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.263078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:49.384442Z","caller":"traceutil/trace.go:171","msg":"trace[931740129] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25864; }","duration":"289.334379ms","start":"2026-07-09T04:21:49.09505Z","end":"2026-07-09T04:21:49.384384Z","steps":["trace[931740129] 'agreement among raft nodes before linearized reading' (duration: 289.196466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:49.384343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:49.065641Z","time spent":"318.62485ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:49.384193Z","caller":"traceutil/trace.go:171","msg":"trace[902016884] linearizableReadLoop","detail":"{readStateIndex:26700; appliedIndex:26699; }","duration":"289.104914ms","start":"2026-07-09T04:21:49.095056Z","end":"2026-07-09T04:21:49.384161Z","steps":["trace[902016884] 'read index received' (duration: 121.871386ms)","trace[902016884] 'applied index is now lower than readState.Index' (duration: 167.228728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:49.627141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.967943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:49.627408Z","caller":"traceutil/trace.go:171","msg":"trace[1115032916] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"280.265062ms","start":"2026-07-09T04:21:49.347124Z","end":"2026-07-09T04:21:49.627389Z","steps":["trace[1115032916] 'process raft request' (duration: 280.133769ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:49.627523Z","caller":"traceutil/trace.go:171","msg":"trace[533584047] linearizableReadLoop","detail":"{readStateIndex:26705; appliedIndex:26701; }","duration":"242.954126ms","start":"2026-07-09T04:21:49.384554Z","end":"2026-07-09T04:21:49.627508Z","steps":["trace[533584047] 'read index received' (duration: 3.817264ms)","trace[533584047] 'applied index is now lower than readState.Index' (duration: 239.135882ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:49.627622Z","caller":"traceutil/trace.go:171","msg":"trace[540060718] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"280.590539ms","start":"2026-07-09T04:21:49.347002Z","end":"2026-07-09T04:21:49.627592Z","steps":["trace[540060718] 'process raft request' (duration: 177.094945ms)","trace[540060718] 'compare' (duration: 102.796209ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:49.627628Z","caller":"traceutil/trace.go:171","msg":"trace[2107320302] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"273.198277ms","start":"2026-07-09T04:21:49.354391Z","end":"2026-07-09T04:21:49.627589Z","steps":["trace[2107320302] 'process raft request' (duration: 272.943222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:49.62772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.636597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-09T04:21:49.627746Z","caller":"traceutil/trace.go:171","msg":"trace[354298719] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25868; }","duration":"241.674047ms","start":"2026-07-09T04:21:49.386064Z","end":"2026-07-09T04:21:49.627739Z","steps":["trace[354298719] 'agreement among raft nodes before linearized reading' (duration: 241.576205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:49.627779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.759475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:21:49.627763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.179129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:21:49.627799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.116836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:49.627827Z","caller":"traceutil/trace.go:171","msg":"trace[898307578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25868; }","duration":"217.847947ms","start":"2026-07-09T04:21:49.409964Z","end":"2026-07-09T04:21:49.627812Z","steps":["trace[898307578] 'agreement among raft nodes before linearized reading' (duration: 217.749075ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:49.627839Z","caller":"traceutil/trace.go:171","msg":"trace[2139819220] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25868; }","duration":"236.175296ms","start":"2026-07-09T04:21:49.391652Z","end":"2026-07-09T04:21:49.627827Z","steps":["trace[2139819220] 'agreement among raft nodes before linearized reading' (duration: 236.118355ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:49.627849Z","caller":"traceutil/trace.go:171","msg":"trace[846190037] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25868; }","duration":"269.313362ms","start":"2026-07-09T04:21:49.358512Z","end":"2026-07-09T04:21:49.627825Z","steps":["trace[846190037] 'agreement among raft nodes before linearized reading' (duration: 269.139339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:49.62779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.70266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-07-09T04:21:49.627933Z","caller":"traceutil/trace.go:171","msg":"trace[234884278] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25868; }","duration":"444.887883ms","start":"2026-07-09T04:21:49.18303Z","end":"2026-07-09T04:21:49.627918Z","steps":["trace[234884278] 'agreement among raft nodes before linearized reading' (duration: 444.584767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:49.627992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:49.183016Z","time spent":"444.941625ms","remote":"127.0.0.1:54862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-07-09T04:21:50.04323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.921786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T04:21:50.043367Z","caller":"traceutil/trace.go:171","msg":"trace[880028228] linearizableReadLoop","detail":"{readStateIndex:26708; appliedIndex:26706; }","duration":"343.454244ms","start":"2026-07-09T04:21:49.699901Z","end":"2026-07-09T04:21:50.043355Z","steps":["trace[880028228] 'read index received' (duration: 78.245412ms)","trace[880028228] 'applied index is now lower than readState.Index' (duration: 265.208232ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:50.043455Z","caller":"traceutil/trace.go:171","msg":"trace[1748247658] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"410.516061ms","start":"2026-07-09T04:21:49.632931Z","end":"2026-07-09T04:21:50.043447Z","steps":["trace[1748247658] 'process raft request' (duration: 145.295819ms)","trace[1748247658] 'compare' (duration: 264.69929ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:50.043598Z","caller":"traceutil/trace.go:171","msg":"trace[1437975860] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"410.501951ms","start":"2026-07-09T04:21:49.633083Z","end":"2026-07-09T04:21:50.043585Z","steps":["trace[1437975860] 'process raft request' (duration: 410.226465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:50.043675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:49.633073Z","time spent":"410.561872ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:50.043697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:49.632918Z","time spent":"410.554842ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:50.043675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.411634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T04:21:50.043732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.740796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-09T04:21:50.043784Z","caller":"traceutil/trace.go:171","msg":"trace[1586654611] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25871; }","duration":"408.551698ms","start":"2026-07-09T04:21:49.635208Z","end":"2026-07-09T04:21:50.043759Z","steps":["trace[1586654611] 'agreement among raft nodes before linearized reading' (duration: 408.324843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:50.043843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:49.6352Z","time spent":"408.632659ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-07-09T04:21:50.043791Z","caller":"traceutil/trace.go:171","msg":"trace[746615285] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25871; }","duration":"410.840358ms","start":"2026-07-09T04:21:49.632936Z","end":"2026-07-09T04:21:50.043777Z","steps":["trace[746615285] 'agreement among raft nodes before linearized reading' (duration: 410.653294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:50.044012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:49.63292Z","time spent":"410.992391ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-09T04:21:50.803542Z","caller":"traceutil/trace.go:171","msg":"trace[6384736] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"238.052228ms","start":"2026-07-09T04:21:50.565458Z","end":"2026-07-09T04:21:50.80351Z","steps":["trace[6384736] 'process raft request' (duration: 237.785542ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:50.821391Z","caller":"traceutil/trace.go:171","msg":"trace[866218512] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"255.338956ms","start":"2026-07-09T04:21:50.566021Z","end":"2026-07-09T04:21:50.821359Z","steps":["trace[866218512] 'process raft request' (duration: 254.973198ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:50.821409Z","caller":"traceutil/trace.go:171","msg":"trace[863873493] linearizableReadLoop","detail":"{readStateIndex:26712; appliedIndex:26709; }","duration":"212.666752ms","start":"2026-07-09T04:21:50.60872Z","end":"2026-07-09T04:21:50.821387Z","steps":["trace[863873493] 'read index received' (duration: 194.555287ms)","trace[863873493] 'applied index is now lower than readState.Index' (duration: 18.109985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:50.821524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.789486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:50.821507Z","caller":"traceutil/trace.go:171","msg":"trace[1572654516] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"250.52584ms","start":"2026-07-09T04:21:50.570946Z","end":"2026-07-09T04:21:50.821472Z","steps":["trace[1572654516] 'process raft request' (duration: 250.221974ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:50.821564Z","caller":"traceutil/trace.go:171","msg":"trace[484168835] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25875; }","duration":"212.885417ms","start":"2026-07-09T04:21:50.608667Z","end":"2026-07-09T04:21:50.821553Z","steps":["trace[484168835] 'agreement among raft nodes before linearized reading' (duration: 212.799835ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:51.105049Z","caller":"traceutil/trace.go:171","msg":"trace[1972345887] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"178.139118ms","start":"2026-07-09T04:21:50.926875Z","end":"2026-07-09T04:21:51.105014Z","steps":["trace[1972345887] 'process raft request' (duration: 177.985323ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:51.112255Z","caller":"traceutil/trace.go:171","msg":"trace[55720563] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"184.013496ms","start":"2026-07-09T04:21:50.928222Z","end":"2026-07-09T04:21:51.112235Z","steps":["trace[55720563] 'process raft request' (duration: 183.891274ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:51.696128Z","caller":"traceutil/trace.go:171","msg":"trace[1844975019] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"303.259305ms","start":"2026-07-09T04:21:51.392855Z","end":"2026-07-09T04:21:51.696115Z","steps":["trace[1844975019] 'process raft request' (duration: 303.09052ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:51.696013Z","caller":"traceutil/trace.go:171","msg":"trace[411485932] linearizableReadLoop","detail":"{readStateIndex:26717; appliedIndex:26714; }","duration":"287.458499ms","start":"2026-07-09T04:21:51.408532Z","end":"2026-07-09T04:21:51.695991Z","steps":["trace[411485932] 'read index received' (duration: 9.095469ms)","trace[411485932] 'applied index is now lower than readState.Index' (duration: 278.36224ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:51.696158Z","caller":"traceutil/trace.go:171","msg":"trace[1259759761] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"306.167658ms","start":"2026-07-09T04:21:51.389959Z","end":"2026-07-09T04:21:51.696126Z","steps":["trace[1259759761] 'process raft request' (duration: 305.82962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:51.696241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:51.392831Z","time spent":"303.348306ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T04:21:51.696243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.712944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:51.696275Z","caller":"traceutil/trace.go:171","msg":"trace[970201823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25880; }","duration":"287.786526ms","start":"2026-07-09T04:21:51.408478Z","end":"2026-07-09T04:21:51.696265Z","steps":["trace[970201823] 'agreement among raft nodes before linearized reading' (duration: 287.723045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:51.69629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:51.38993Z","time spent":"306.289361ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T04:21:51.86891Z","caller":"traceutil/trace.go:171","msg":"trace[371915951] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"243.63663ms","start":"2026-07-09T04:21:51.625244Z","end":"2026-07-09T04:21:51.868881Z","steps":["trace[371915951] 'process raft request' (duration: 232.291182ms)","trace[371915951] 'compare' (duration: 10.954469ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:51.868936Z","caller":"traceutil/trace.go:171","msg":"trace[2132929627] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"236.214537ms","start":"2026-07-09T04:21:51.63268Z","end":"2026-07-09T04:21:51.868895Z","steps":["trace[2132929627] 'process raft request' (duration: 236.081384ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:51.869191Z","caller":"traceutil/trace.go:171","msg":"trace[320382960] linearizableReadLoop","detail":"{readStateIndex:26721; appliedIndex:26717; }","duration":"172.999414ms","start":"2026-07-09T04:21:51.696181Z","end":"2026-07-09T04:21:51.869181Z","steps":["trace[320382960] 'read index received' (duration: 161.313189ms)","trace[320382960] 'applied index is now lower than readState.Index' (duration: 11.685155ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:51.869275Z","caller":"traceutil/trace.go:171","msg":"trace[703177776] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"229.597393ms","start":"2026-07-09T04:21:51.63967Z","end":"2026-07-09T04:21:51.869267Z","steps":["trace[703177776] 'process raft request' (duration: 229.424209ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:51.869462Z","caller":"traceutil/trace.go:171","msg":"trace[2026431671] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"236.32827ms","start":"2026-07-09T04:21:51.63312Z","end":"2026-07-09T04:21:51.869448Z","steps":["trace[2026431671] 'process raft request' (duration: 235.736427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:51.869536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.848049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-09T04:21:51.869591Z","caller":"traceutil/trace.go:171","msg":"trace[1560188029] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25884; }","duration":"379.948401ms","start":"2026-07-09T04:21:51.489634Z","end":"2026-07-09T04:21:51.869582Z","steps":["trace[1560188029] 'agreement among raft nodes before linearized reading' (duration: 379.712186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:51.869632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T04:21:51.489619Z","time spent":"380.001393ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":253,"response size":32,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-09T04:21:51.86982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.391102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:51.869855Z","caller":"traceutil/trace.go:171","msg":"trace[317196491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25884; }","duration":"172.464304ms","start":"2026-07-09T04:21:51.697382Z","end":"2026-07-09T04:21:51.869846Z","steps":["trace[317196491] 'agreement among raft nodes before linearized reading' (duration: 172.409002ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:52.277182Z","caller":"traceutil/trace.go:171","msg":"trace[1766654459] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26722; }","duration":"173.20974ms","start":"2026-07-09T04:21:52.103949Z","end":"2026-07-09T04:21:52.277159Z","steps":["trace[1766654459] 'read index received' (duration: 33.184216ms)","trace[1766654459] 'applied index is now lower than readState.Index' (duration: 140.024774ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:52.277274Z","caller":"traceutil/trace.go:171","msg":"trace[1921597740] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"200.978678ms","start":"2026-07-09T04:21:52.076257Z","end":"2026-07-09T04:21:52.277236Z","steps":["trace[1921597740] 'process raft request' (duration: 140.059354ms)","trace[1921597740] 'compare' (duration: 60.713299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:52.277337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.375444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-09T04:21:52.277365Z","caller":"traceutil/trace.go:171","msg":"trace[1328679239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25887; }","duration":"173.447285ms","start":"2026-07-09T04:21:52.103909Z","end":"2026-07-09T04:21:52.277356Z","steps":["trace[1328679239] 'agreement among raft nodes before linearized reading' (duration: 173.329033ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:52.468539Z","caller":"traceutil/trace.go:171","msg":"trace[1028665357] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"187.293297ms","start":"2026-07-09T04:21:52.28122Z","end":"2026-07-09T04:21:52.468513Z","steps":["trace[1028665357] 'process raft request' (duration: 131.083858ms)","trace[1028665357] 'compare' (duration: 55.612996ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:53.009328Z","caller":"traceutil/trace.go:171","msg":"trace[1535795083] linearizableReadLoop","detail":"{readStateIndex:26726; appliedIndex:26725; }","duration":"183.893952ms","start":"2026-07-09T04:21:52.825416Z","end":"2026-07-09T04:21:53.00931Z","steps":["trace[1535795083] 'read index received' (duration: 183.746679ms)","trace[1535795083] 'applied index is now lower than readState.Index' (duration: 146.353µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:53.009388Z","caller":"traceutil/trace.go:171","msg":"trace[176820485] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"197.813797ms","start":"2026-07-09T04:21:52.811549Z","end":"2026-07-09T04:21:53.009362Z","steps":["trace[176820485] 'process raft request' (duration: 197.638773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:53.009506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.072776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-09T04:21:53.00953Z","caller":"traceutil/trace.go:171","msg":"trace[1983849506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25889; }","duration":"184.132708ms","start":"2026-07-09T04:21:52.825391Z","end":"2026-07-09T04:21:53.009523Z","steps":["trace[1983849506] 'agreement among raft nodes before linearized reading' (duration: 184.029615ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:53.037888Z","caller":"traceutil/trace.go:171","msg":"trace[432100211] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"212.319624ms","start":"2026-07-09T04:21:52.825501Z","end":"2026-07-09T04:21:53.037821Z","steps":["trace[432100211] 'process raft request' (duration: 211.875594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:53.038065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.152167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T04:21:53.038178Z","caller":"traceutil/trace.go:171","msg":"trace[1322261557] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25890; }","duration":"108.284219ms","start":"2026-07-09T04:21:52.92984Z","end":"2026-07-09T04:21:53.038124Z","steps":["trace[1322261557] 'agreement among raft nodes before linearized reading' (duration: 108.101045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:53.223141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.051173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-09T04:21:53.22321Z","caller":"traceutil/trace.go:171","msg":"trace[1830339421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25891; }","duration":"107.153494ms","start":"2026-07-09T04:21:53.11604Z","end":"2026-07-09T04:21:53.223193Z","steps":["trace[1830339421] 'range keys from in-memory index tree' (duration: 106.92395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:53.223247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.036235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-09T04:21:53.223328Z","caller":"traceutil/trace.go:171","msg":"trace[1712082687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25891; }","duration":"114.169518ms","start":"2026-07-09T04:21:53.109141Z","end":"2026-07-09T04:21:53.22331Z","steps":["trace[1712082687] 'range keys from in-memory index tree' (duration: 113.868261ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:53.3505Z","caller":"traceutil/trace.go:171","msg":"trace[1811718125] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"122.753866ms","start":"2026-07-09T04:21:53.227722Z","end":"2026-07-09T04:21:53.350475Z","steps":["trace[1811718125] 'process raft request' (duration: 122.611183ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:53.351016Z","caller":"traceutil/trace.go:171","msg":"trace[623879720] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"122.833198ms","start":"2026-07-09T04:21:53.228152Z","end":"2026-07-09T04:21:53.350986Z","steps":["trace[623879720] 'process raft request' (duration: 122.537761ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:55.13491Z","caller":"traceutil/trace.go:171","msg":"trace[1025584903] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"117.285336ms","start":"2026-07-09T04:21:55.017597Z","end":"2026-07-09T04:21:55.134882Z","steps":["trace[1025584903] 'process raft request' (duration: 117.103982ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:55.247641Z","caller":"traceutil/trace.go:171","msg":"trace[246987164] linearizableReadLoop","detail":"{readStateIndex:26741; appliedIndex:26739; }","duration":"200.940955ms","start":"2026-07-09T04:21:55.046664Z","end":"2026-07-09T04:21:55.247605Z","steps":["trace[246987164] 'read index received' (duration: 88.047405ms)","trace[246987164] 'applied index is now lower than readState.Index' (duration: 112.89201ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T04:21:55.247753Z","caller":"traceutil/trace.go:171","msg":"trace[2040046052] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"204.655967ms","start":"2026-07-09T04:21:55.042956Z","end":"2026-07-09T04:21:55.247612Z","steps":["trace[2040046052] 'process raft request' (duration: 194.106947ms)","trace[2040046052] 'compare' (duration: 10.363446ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T04:21:55.247909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.847165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-07-09T04:21:55.247974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.421068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-07-09T04:21:55.247838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.455029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T04:21:55.248014Z","caller":"traceutil/trace.go:171","msg":"trace[2055949762] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25903; }","duration":"133.4995ms","start":"2026-07-09T04:21:55.114501Z","end":"2026-07-09T04:21:55.248001Z","steps":["trace[2055949762] 'agreement among raft nodes before linearized reading' (duration: 133.399098ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:55.248012Z","caller":"traceutil/trace.go:171","msg":"trace[1650426984] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25903; }","duration":"105.971517ms","start":"2026-07-09T04:21:55.142023Z","end":"2026-07-09T04:21:55.247994Z","steps":["trace[1650426984] 'agreement among raft nodes before linearized reading' (duration: 105.850994ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:21:55.248062Z","caller":"traceutil/trace.go:171","msg":"trace[1963042685] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25903; }","duration":"197.694194ms","start":"2026-07-09T04:21:55.050338Z","end":"2026-07-09T04:21:55.248032Z","steps":["trace[1963042685] 'agreement among raft nodes before linearized reading' (duration: 197.441898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T04:21:55.247858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.192341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-09T04:21:55.248441Z","caller":"traceutil/trace.go:171","msg":"trace[757529715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25903; }","duration":"201.802154ms","start":"2026-07-09T04:21:55.046614Z","end":"2026-07-09T04:21:55.248416Z","steps":["trace[757529715] 'agreement among raft nodes before linearized reading' (duration: 201.128139ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:22:45.568953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23430} {"level":"info","ts":"2026-07-09T04:22:45.715338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23430,"took":"141.728911ms","hash":3621078745,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":41328640,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-07-09T04:22:45.71542Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3621078745,"revision":23430,"compact-revision":20473} {"level":"info","ts":"2026-07-09T04:23:40.386375Z","caller":"traceutil/trace.go:171","msg":"trace[220061544] transaction","detail":"{read_only:false; response_revision:26961; number_of_response:1; }","duration":"100.451817ms","start":"2026-07-09T04:23:40.285901Z","end":"2026-07-09T04:23:40.386353Z","steps":["trace[220061544] 'process raft request' (duration: 97.350639ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:25:22.613568Z","caller":"traceutil/trace.go:171","msg":"trace[328141613] transaction","detail":"{read_only:false; response_revision:28116; number_of_response:1; }","duration":"107.309827ms","start":"2026-07-09T04:25:22.506232Z","end":"2026-07-09T04:25:22.613542Z","steps":["trace[328141613] 'process raft request' (duration: 106.660843ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T04:27:30.935332Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T04:27:30.939986Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-09T04:27:30.940103Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-09T04:27:45.575342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26458} {"level":"info","ts":"2026-07-09T04:27:45.720323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26458,"took":"140.600264ms","hash":1958141420,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":36016128,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-09T04:27:45.72042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1958141420,"revision":26458,"compact-revision":23430} {"level":"info","ts":"2026-07-09T04:32:45.581736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29192} {"level":"info","ts":"2026-07-09T04:32:45.724734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29192,"took":"139.539761ms","hash":34552242,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":31563776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T04:32:45.724818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34552242,"revision":29192,"compact-revision":26458} {"level":"info","ts":"2026-07-09T04:37:45.587527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31448} {"level":"info","ts":"2026-07-09T04:37:45.716493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31448,"took":"123.620512ms","hash":2922946672,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-09T04:37:45.716551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2922946672,"revision":31448,"compact-revision":29192} {"level":"info","ts":"2026-07-09T04:42:45.593009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33750} {"level":"info","ts":"2026-07-09T04:42:45.747502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33750,"took":"147.881942ms","hash":1520434982,"current-db-size-bytes":59174912,"current-db-size":"59 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-09T04:42:45.747623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1520434982,"revision":33750,"compact-revision":31448} {"level":"info","ts":"2026-07-09T04:47:45.600887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36084} {"level":"info","ts":"2026-07-09T04:47:45.746263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36084,"took":"139.812117ms","hash":4043156797,"current-db-size-bytes":60944384,"current-db-size":"61 MB","current-db-size-in-use-bytes":33046528,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-09T04:47:45.746329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4043156797,"revision":36084,"compact-revision":33750} {"level":"info","ts":"2026-07-09T04:48:44.257978Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T04:48:44.263204Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-09T04:48:44.263309Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}