{"level":"warn","ts":"2026-06-25T22:22:15.321546Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:22:15.321634Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146: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.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146: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-06-25T22:22:15.321698Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:22:15.321711Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-06-25T22:22:15.321733Z","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-06-25T22:22:15.322329Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-06-25T22:22:15.322416Z","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.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146: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-06-25T22:22:15.324859Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.224966ms"} {"level":"info","ts":"2026-06-25T22:22:15.329213Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-06-25T22:22:15.329307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-06-25T22:22:15.329331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-06-25T22:22:15.329348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-25T22:22:15.32936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-06-25T22:22:15.329398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-06-25T22:22:15.33351Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-25T22:22:15.334834Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-25T22:22:15.336027Z","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-06-25T22:22:15.338386Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-25T22:22:15.338592Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-25T22:22:15.33863Z","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-06-25T22:22:15.33879Z","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-06-25T22:22:15.338833Z","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-06-25T22:22:15.339906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-06-25T22:22:15.34014Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-06-25T22:22:15.344545Z","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-06-25T22:22:15.344685Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-06-25T22:22:15.344847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-06-25T22:22:15.345024Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-25T22:22:15.345077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-25T22:22:16.329871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-06-25T22:22:16.329963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-25T22:22:16.329988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-06-25T22:22:16.330009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-06-25T22:22:16.330021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-06-25T22:22:16.330157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-06-25T22:22:16.330214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-06-25T22:22:16.331531Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-06-25T22:22:16.331688Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:22:16.331759Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:22:16.33189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:16.331962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-25T22:22:16.332005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-25T22:22:16.334209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-25T22:22:16.334962Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:16.33503Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:16.335024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-06-25T22:22:16.335051Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:31.989198Z","caller":"traceutil/trace.go:171","msg":"trace[195980892] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"104.936504ms","start":"2026-06-25T22:22:31.884231Z","end":"2026-06-25T22:22:31.989167Z","steps":["trace[195980892] 'process raft request' (duration: 104.812251ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:52.228504Z","caller":"traceutil/trace.go:171","msg":"trace[279107131] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"135.218282ms","start":"2026-06-25T22:22:52.093264Z","end":"2026-06-25T22:22:52.228482Z","steps":["trace[279107131] 'process raft request' (duration: 135.077528ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:52.238689Z","caller":"traceutil/trace.go:171","msg":"trace[510213256] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"144.429435ms","start":"2026-06-25T22:22:52.094228Z","end":"2026-06-25T22:22:52.238657Z","steps":["trace[510213256] 'process raft request' (duration: 144.298441ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:58.217746Z","caller":"traceutil/trace.go:171","msg":"trace[907536482] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"115.910022ms","start":"2026-06-25T22:22:58.101802Z","end":"2026-06-25T22:22:58.217712Z","steps":["trace[907536482] 'read index received' (duration: 115.721537ms)","trace[907536482] 'applied index is now lower than readState.Index' (duration: 187.385µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:58.217792Z","caller":"traceutil/trace.go:171","msg":"trace[1595557] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"124.423145ms","start":"2026-06-25T22:22:58.093337Z","end":"2026-06-25T22:22:58.21776Z","steps":["trace[1595557] 'process raft request' (duration: 124.229599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:58.217977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.132668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wddvs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-25T22:22:58.218044Z","caller":"traceutil/trace.go:171","msg":"trace[2081381715] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wddvs; range_end:; response_count:1; response_revision:587; }","duration":"116.233822ms","start":"2026-06-25T22:22:58.101795Z","end":"2026-06-25T22:22:58.218029Z","steps":["trace[2081381715] 'agreement among raft nodes before linearized reading' (duration: 116.000385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:58.603347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.053901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:22:58.60346Z","caller":"traceutil/trace.go:171","msg":"trace[717470690] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"326.416166ms","start":"2026-06-25T22:22:58.277028Z","end":"2026-06-25T22:22:58.603444Z","steps":["trace[717470690] 'read index received' (duration: 13.147588ms)","trace[717470690] 'applied index is now lower than readState.Index' (duration: 313.267078ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:58.603626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.592042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-25T22:22:58.603684Z","caller":"traceutil/trace.go:171","msg":"trace[1285201015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"326.667143ms","start":"2026-06-25T22:22:58.277002Z","end":"2026-06-25T22:22:58.603669Z","steps":["trace[1285201015] 'agreement among raft nodes before linearized reading' (duration: 326.494258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:58.603722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:58.276994Z","time spent":"326.718715ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-25T22:22:58.603899Z","caller":"traceutil/trace.go:171","msg":"trace[545559792] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"327.312934ms","start":"2026-06-25T22:22:58.276438Z","end":"2026-06-25T22:22:58.603751Z","steps":["trace[545559792] 'process raft request' (duration: 39.14926ms)","trace[545559792] 'compare' (duration: 286.881756ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:58.60428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:58.276422Z","time spent":"327.607552ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:22:59.092978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.816663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:22:59.093142Z","caller":"traceutil/trace.go:171","msg":"trace[405800688] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"479.545871ms","start":"2026-06-25T22:22:58.613554Z","end":"2026-06-25T22:22:59.0931Z","steps":["trace[405800688] 'process raft request' (duration: 190.509072ms)","trace[405800688] 'compare' (duration: 288.430801ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:59.093226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:58.613531Z","time spent":"479.655085ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:22:59.111528Z","caller":"traceutil/trace.go:171","msg":"trace[1723774054] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"304.298352ms","start":"2026-06-25T22:22:58.807203Z","end":"2026-06-25T22:22:59.111502Z","steps":["trace[1723774054] 'process raft request' (duration: 304.052434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:59.111664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:58.807174Z","time spent":"304.425034ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:23:14.493311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.31907ms","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-06-25T22:23:14.493408Z","caller":"traceutil/trace.go:171","msg":"trace[1364395784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"127.432594ms","start":"2026-06-25T22:23:14.365956Z","end":"2026-06-25T22:23:14.493388Z","steps":["trace[1364395784] 'range keys from in-memory index tree' (duration: 127.145205ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:31.348198Z","caller":"traceutil/trace.go:171","msg":"trace[1268415555] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"249.124132ms","start":"2026-06-25T22:24:31.099034Z","end":"2026-06-25T22:24:31.348158Z","steps":["trace[1268415555] 'process raft request' (duration: 172.610196ms)","trace[1268415555] 'compare' (duration: 76.261559ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:24:44.966091Z","caller":"traceutil/trace.go:171","msg":"trace[1682597924] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"124.776533ms","start":"2026-06-25T22:24:44.841285Z","end":"2026-06-25T22:24:44.966061Z","steps":["trace[1682597924] 'process raft request' (duration: 124.462523ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:15.677925Z","caller":"traceutil/trace.go:171","msg":"trace[1876437102] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"109.483428ms","start":"2026-06-25T22:25:15.568413Z","end":"2026-06-25T22:25:15.677896Z","steps":["trace[1876437102] 'process raft request' (duration: 109.285952ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:21.476451Z","caller":"traceutil/trace.go:171","msg":"trace[609835050] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"138.055542ms","start":"2026-06-25T22:25:21.338368Z","end":"2026-06-25T22:25:21.476424Z","steps":["trace[609835050] 'process raft request' (duration: 137.874137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:44.852657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.988154ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-06-25T22:30:38.731654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.843712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T22:30:38.731764Z","caller":"traceutil/trace.go:171","msg":"trace[929817938] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4982; }","duration":"197.022058ms","start":"2026-06-25T22:30:38.534711Z","end":"2026-06-25T22:30:38.731733Z","steps":["trace[929817938] 'count revisions from in-memory index tree' (duration: 196.700048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:38.731826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.064293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6s669\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-06-25T22:30:38.731965Z","caller":"traceutil/trace.go:171","msg":"trace[62050609] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6s669; range_end:; response_count:1; response_revision:4982; }","duration":"219.279779ms","start":"2026-06-25T22:30:38.512658Z","end":"2026-06-25T22:30:38.731937Z","steps":["trace[62050609] 'range keys from in-memory index tree' (duration: 218.807006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:38.732051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.148383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:30:38.732145Z","caller":"traceutil/trace.go:171","msg":"trace[1825545197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4982; }","duration":"229.295118ms","start":"2026-06-25T22:30:38.502825Z","end":"2026-06-25T22:30:38.73212Z","steps":["trace[1825545197] 'range keys from in-memory index tree' (duration: 228.902366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:38.732158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.629139ms","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-06-25T22:30:38.732245Z","caller":"traceutil/trace.go:171","msg":"trace[976194621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4982; }","duration":"219.748703ms","start":"2026-06-25T22:30:38.512476Z","end":"2026-06-25T22:30:38.732224Z","steps":["trace[976194621] 'range keys from in-memory index tree' (duration: 219.451894ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:39.161993Z","caller":"traceutil/trace.go:171","msg":"trace[1443277645] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"171.662268ms","start":"2026-06-25T22:30:38.990297Z","end":"2026-06-25T22:30:39.16196Z","steps":["trace[1443277645] 'process raft request' (duration: 171.447861ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:50.05983Z","caller":"traceutil/trace.go:171","msg":"trace[514399688] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"108.064505ms","start":"2026-06-25T22:30:49.951733Z","end":"2026-06-25T22:30:50.059797Z","steps":["trace[514399688] 'process raft request' (duration: 107.770357ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:58.65297Z","caller":"traceutil/trace.go:171","msg":"trace[112016058] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5590; }","duration":"152.212266ms","start":"2026-06-25T22:30:58.500727Z","end":"2026-06-25T22:30:58.652939Z","steps":["trace[112016058] 'read index received' (duration: 151.972359ms)","trace[112016058] 'applied index is now lower than readState.Index' (duration: 238.457µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:30:58.65306Z","caller":"traceutil/trace.go:171","msg":"trace[820504922] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"161.213766ms","start":"2026-06-25T22:30:58.491803Z","end":"2026-06-25T22:30:58.653016Z","steps":["trace[820504922] 'process raft request' (duration: 160.911067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:58.653227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.099325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:30:58.653234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.477744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:30:58.653288Z","caller":"traceutil/trace.go:171","msg":"trace[1669798677] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5388; }","duration":"133.239271ms","start":"2026-06-25T22:30:58.520038Z","end":"2026-06-25T22:30:58.653277Z","steps":["trace[1669798677] 'agreement among raft nodes before linearized reading' (duration: 133.114396ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:58.653335Z","caller":"traceutil/trace.go:171","msg":"trace[1655301884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5388; }","duration":"152.605438ms","start":"2026-06-25T22:30:58.5007Z","end":"2026-06-25T22:30:58.653305Z","steps":["trace[1655301884] 'agreement among raft nodes before linearized reading' (duration: 152.419532ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:58.866658Z","caller":"traceutil/trace.go:171","msg":"trace[1252564] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5591; }","duration":"204.547764ms","start":"2026-06-25T22:30:58.66207Z","end":"2026-06-25T22:30:58.866617Z","steps":["trace[1252564] 'read index received' (duration: 204.254086ms)","trace[1252564] 'applied index is now lower than readState.Index' (duration: 289.748µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:30:58.866876Z","caller":"traceutil/trace.go:171","msg":"trace[638333100] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"205.345639ms","start":"2026-06-25T22:30:58.661496Z","end":"2026-06-25T22:30:58.866842Z","steps":["trace[638333100] 'process raft request' (duration: 204.953338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:58.867071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.893356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-25T22:30:58.867124Z","caller":"traceutil/trace.go:171","msg":"trace[1597423542] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5389; }","duration":"196.143643ms","start":"2026-06-25T22:30:58.670967Z","end":"2026-06-25T22:30:58.867111Z","steps":["trace[1597423542] 'agreement among raft nodes before linearized reading' (duration: 195.812323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:58.867219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.129712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18479"} {"level":"info","ts":"2026-06-25T22:30:58.867303Z","caller":"traceutil/trace.go:171","msg":"trace[63927322] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5389; }","duration":"205.248954ms","start":"2026-06-25T22:30:58.662034Z","end":"2026-06-25T22:30:58.867283Z","steps":["trace[63927322] 'agreement among raft nodes before linearized reading' (duration: 204.73996ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.079508Z","caller":"traceutil/trace.go:171","msg":"trace[2083800604] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"204.228835ms","start":"2026-06-25T22:30:58.875236Z","end":"2026-06-25T22:30:59.079465Z","steps":["trace[2083800604] 'process raft request' (duration: 201.187618ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.079565Z","caller":"traceutil/trace.go:171","msg":"trace[901439788] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"204.251796ms","start":"2026-06-25T22:30:58.87528Z","end":"2026-06-25T22:30:59.079531Z","steps":["trace[901439788] 'process raft request' (duration: 204.079641ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.079816Z","caller":"traceutil/trace.go:171","msg":"trace[448746614] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"204.421281ms","start":"2026-06-25T22:30:58.87537Z","end":"2026-06-25T22:30:59.079791Z","steps":["trace[448746614] 'process raft request' (duration: 204.080081ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.07991Z","caller":"traceutil/trace.go:171","msg":"trace[1564891024] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"204.247586ms","start":"2026-06-25T22:30:58.875639Z","end":"2026-06-25T22:30:59.079887Z","steps":["trace[1564891024] 'process raft request' (duration: 204.048391ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.079991Z","caller":"traceutil/trace.go:171","msg":"trace[689523235] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"200.096077ms","start":"2026-06-25T22:30:58.879876Z","end":"2026-06-25T22:30:59.079972Z","steps":["trace[689523235] 'process raft request' (duration: 199.974993ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.080081Z","caller":"traceutil/trace.go:171","msg":"trace[409308603] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"203.412232ms","start":"2026-06-25T22:30:58.876652Z","end":"2026-06-25T22:30:59.080064Z","steps":["trace[409308603] 'process raft request' (duration: 203.154715ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.080109Z","caller":"traceutil/trace.go:171","msg":"trace[1196873027] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"187.580575ms","start":"2026-06-25T22:30:58.892483Z","end":"2026-06-25T22:30:59.080063Z","steps":["trace[1196873027] 'process raft request' (duration: 187.418171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:59.080421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.736324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-06-25T22:30:59.080481Z","caller":"traceutil/trace.go:171","msg":"trace[1030898505] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5398; }","duration":"201.829027ms","start":"2026-06-25T22:30:58.878635Z","end":"2026-06-25T22:30:59.080464Z","steps":["trace[1030898505] 'agreement among raft nodes before linearized reading' (duration: 201.621711ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.080088Z","caller":"traceutil/trace.go:171","msg":"trace[291862743] linearizableReadLoop","detail":"{readStateIndex:5598; appliedIndex:5592; }","duration":"201.330982ms","start":"2026-06-25T22:30:58.87866Z","end":"2026-06-25T22:30:59.079991Z","steps":["trace[291862743] 'read index received' (duration: 58.435864ms)","trace[291862743] 'applied index is now lower than readState.Index' (duration: 142.893528ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:30:59.080816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.400141ms","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":"warn","ts":"2026-06-25T22:30:59.080815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.970476ms","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-06-25T22:30:59.080815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.151652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6s669\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-06-25T22:30:59.080875Z","caller":"traceutil/trace.go:171","msg":"trace[500325316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5398; }","duration":"195.487264ms","start":"2026-06-25T22:30:58.885368Z","end":"2026-06-25T22:30:59.080855Z","steps":["trace[500325316] 'agreement among raft nodes before linearized reading' (duration: 195.284778ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.080875Z","caller":"traceutil/trace.go:171","msg":"trace[2147256936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5398; }","duration":"197.064699ms","start":"2026-06-25T22:30:58.883796Z","end":"2026-06-25T22:30:59.080861Z","steps":["trace[2147256936] 'agreement among raft nodes before linearized reading' (duration: 196.854673ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:59.080894Z","caller":"traceutil/trace.go:171","msg":"trace[111043336] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6s669; range_end:; response_count:1; response_revision:5398; }","duration":"188.260005ms","start":"2026-06-25T22:30:58.892615Z","end":"2026-06-25T22:30:59.080875Z","steps":["trace[111043336] 'agreement among raft nodes before linearized reading' (duration: 188.032199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:30:59.081256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.795571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T22:30:59.081305Z","caller":"traceutil/trace.go:171","msg":"trace[875185981] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5398; }","duration":"103.851513ms","start":"2026-06-25T22:30:58.977441Z","end":"2026-06-25T22:30:59.081292Z","steps":["trace[875185981] 'agreement among raft nodes before linearized reading' (duration: 103.271427ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:31:04.900452Z","caller":"traceutil/trace.go:171","msg":"trace[937059070] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5696; }","duration":"118.052733ms","start":"2026-06-25T22:31:04.782375Z","end":"2026-06-25T22:31:04.900428Z","steps":["trace[937059070] 'read index received' (duration: 117.814366ms)","trace[937059070] 'applied index is now lower than readState.Index' (duration: 235.027µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:31:04.900546Z","caller":"traceutil/trace.go:171","msg":"trace[1360424870] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"126.485277ms","start":"2026-06-25T22:31:04.77402Z","end":"2026-06-25T22:31:04.900505Z","steps":["trace[1360424870] 'process raft request' (duration: 126.244419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:31:04.900696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.29177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-25T22:31:04.900751Z","caller":"traceutil/trace.go:171","msg":"trace[991598826] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5491; }","duration":"118.407573ms","start":"2026-06-25T22:31:04.782325Z","end":"2026-06-25T22:31:04.900733Z","steps":["trace[991598826] 'agreement among raft nodes before linearized reading' (duration: 118.200617ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:32:16.382278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-06-25T22:32:16.450816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"67.430584ms","hash":253256601,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":34271232,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-25T22:32:16.450894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":253256601,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-06-25T22:37:05.632778Z","caller":"traceutil/trace.go:171","msg":"trace[1038832608] linearizableReadLoop","detail":"{readStateIndex:8513; appliedIndex:8512; }","duration":"172.896863ms","start":"2026-06-25T22:37:05.459856Z","end":"2026-06-25T22:37:05.632753Z","steps":["trace[1038832608] 'read index received' (duration: 172.709808ms)","trace[1038832608] 'applied index is now lower than readState.Index' (duration: 186.315µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:37:05.63307Z","caller":"traceutil/trace.go:171","msg":"trace[698537399] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"234.468528ms","start":"2026-06-25T22:37:05.398588Z","end":"2026-06-25T22:37:05.633056Z","steps":["trace[698537399] 'process raft request' (duration: 234.032576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:37:05.633347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.481911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-25T22:37:05.633393Z","caller":"traceutil/trace.go:171","msg":"trace[1810173973] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8221; }","duration":"173.580003ms","start":"2026-06-25T22:37:05.4598Z","end":"2026-06-25T22:37:05.63338Z","steps":["trace[1810173973] 'agreement among raft nodes before linearized reading' (duration: 173.438939ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:37:05.814688Z","caller":"traceutil/trace.go:171","msg":"trace[1148375736] linearizableReadLoop","detail":"{readStateIndex:8515; appliedIndex:8513; }","duration":"181.801428ms","start":"2026-06-25T22:37:05.632861Z","end":"2026-06-25T22:37:05.814662Z","steps":["trace[1148375736] 'read index received' (duration: 127.069369ms)","trace[1148375736] 'applied index is now lower than readState.Index' (duration: 54.731079ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:37:05.814835Z","caller":"traceutil/trace.go:171","msg":"trace[2053105113] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"195.293465ms","start":"2026-06-25T22:37:05.61949Z","end":"2026-06-25T22:37:05.814783Z","steps":["trace[2053105113] 'process raft request' (duration: 195.12106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:37:05.814966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.351298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-25T22:37:05.815048Z","caller":"traceutil/trace.go:171","msg":"trace[589187364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8223; }","duration":"187.488471ms","start":"2026-06-25T22:37:05.627546Z","end":"2026-06-25T22:37:05.815035Z","steps":["trace[589187364] 'agreement among raft nodes before linearized reading' (duration: 187.247775ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:37:05.815203Z","caller":"traceutil/trace.go:171","msg":"trace[2050250384] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"335.547704ms","start":"2026-06-25T22:37:05.479186Z","end":"2026-06-25T22:37:05.814733Z","steps":["trace[2050250384] 'process raft request' (duration: 280.774364ms)","trace[2050250384] 'compare' (duration: 54.512633ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:37:05.815374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:37:05.479156Z","time spent":"336.086058ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:37:05.823712Z","caller":"traceutil/trace.go:171","msg":"trace[1743769364] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"185.510584ms","start":"2026-06-25T22:37:05.638185Z","end":"2026-06-25T22:37:05.823696Z","steps":["trace[1743769364] 'process raft request' (duration: 185.35097ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:37:16.391282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6092} {"level":"info","ts":"2026-06-25T22:37:16.487038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6092,"took":"93.661113ms","hash":2831633562,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":23326720,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-25T22:37:16.487249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2831633562,"revision":6092,"compact-revision":2964} {"level":"info","ts":"2026-06-25T22:37:19.272701Z","caller":"traceutil/trace.go:171","msg":"trace[1443734758] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"123.595111ms","start":"2026-06-25T22:37:19.149021Z","end":"2026-06-25T22:37:19.272617Z","steps":["trace[1443734758] 'process raft request' (duration: 100.8519ms)","trace[1443734758] 'compare' (duration: 22.561576ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:37:59.399406Z","caller":"traceutil/trace.go:171","msg":"trace[2054800835] linearizableReadLoop","detail":"{readStateIndex:8910; appliedIndex:8909; }","duration":"113.977976ms","start":"2026-06-25T22:37:59.285404Z","end":"2026-06-25T22:37:59.399382Z","steps":["trace[2054800835] 'read index received' (duration: 113.347587ms)","trace[2054800835] 'applied index is now lower than readState.Index' (duration: 629.479µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:37:59.39969Z","caller":"traceutil/trace.go:171","msg":"trace[421592424] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"143.935904ms","start":"2026-06-25T22:37:59.25574Z","end":"2026-06-25T22:37:59.399676Z","steps":["trace[421592424] 'process raft request' (duration: 142.965666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:37:59.399965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.553593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-25T22:37:59.400005Z","caller":"traceutil/trace.go:171","msg":"trace[1763460208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8602; }","duration":"114.644165ms","start":"2026-06-25T22:37:59.28535Z","end":"2026-06-25T22:37:59.399994Z","steps":["trace[1763460208] 'agreement among raft nodes before linearized reading' (duration: 114.511411ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:37:59.521547Z","caller":"traceutil/trace.go:171","msg":"trace[124589114] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"115.410876ms","start":"2026-06-25T22:37:59.406113Z","end":"2026-06-25T22:37:59.521524Z","steps":["trace[124589114] 'process raft request' (duration: 105.175333ms)","trace[124589114] 'compare' (duration: 10.065288ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:37:59.521914Z","caller":"traceutil/trace.go:171","msg":"trace[939792763] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"115.109498ms","start":"2026-06-25T22:37:59.406789Z","end":"2026-06-25T22:37:59.521899Z","steps":["trace[939792763] 'process raft request' (duration: 114.668715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:38:00.36045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.019071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:38:00.361732Z","caller":"traceutil/trace.go:171","msg":"trace[1642586980] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8916; }","duration":"264.800426ms","start":"2026-06-25T22:38:00.09691Z","end":"2026-06-25T22:38:00.36171Z","steps":["trace[1642586980] 'read index received' (duration: 63.461057ms)","trace[1642586980] 'applied index is now lower than readState.Index' (duration: 201.336279ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:38:00.361728Z","caller":"traceutil/trace.go:171","msg":"trace[1711143193] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"193.508044ms","start":"2026-06-25T22:38:00.168193Z","end":"2026-06-25T22:38:00.361701Z","steps":["trace[1711143193] 'process raft request' (duration: 193.37152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:38:00.361928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.018422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-25T22:38:00.36196Z","caller":"traceutil/trace.go:171","msg":"trace[1775991987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8609; }","duration":"265.052462ms","start":"2026-06-25T22:38:00.096895Z","end":"2026-06-25T22:38:00.361948Z","steps":["trace[1775991987] 'agreement among raft nodes before linearized reading' (duration: 264.902748ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:38:00.361945Z","caller":"traceutil/trace.go:171","msg":"trace[1474236638] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"333.69259ms","start":"2026-06-25T22:38:00.028207Z","end":"2026-06-25T22:38:00.361899Z","steps":["trace[1474236638] 'process raft request' (duration: 132.156276ms)","trace[1474236638] 'compare' (duration: 199.777283ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:38:00.362172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:38:00.028185Z","time spent":"333.864714ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:38:00.503997Z","caller":"traceutil/trace.go:171","msg":"trace[1129867954] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"327.082641ms","start":"2026-06-25T22:38:00.176888Z","end":"2026-06-25T22:38:00.503971Z","steps":["trace[1129867954] 'process raft request' (duration: 289.302978ms)","trace[1129867954] 'compare' (duration: 36.919728ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:38:00.504136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:38:00.176874Z","time spent":"327.185924ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:38:00.504208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.065783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-25T22:38:00.504005Z","caller":"traceutil/trace.go:171","msg":"trace[1986683826] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"300.475778ms","start":"2026-06-25T22:38:00.203507Z","end":"2026-06-25T22:38:00.503983Z","steps":["trace[1986683826] 'process raft request' (duration: 300.393526ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:38:00.504274Z","caller":"traceutil/trace.go:171","msg":"trace[1179820002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8611; }","duration":"384.173296ms","start":"2026-06-25T22:38:00.120087Z","end":"2026-06-25T22:38:00.50426Z","steps":["trace[1179820002] 'agreement among raft nodes before linearized reading' (duration: 383.95803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:38:00.504318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:38:00.120076Z","time spent":"384.232108ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-25T22:38:00.504312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.421258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j6dmc\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-06-25T22:38:00.504407Z","caller":"traceutil/trace.go:171","msg":"trace[89665039] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-j6dmc; range_end:; response_count:1; response_revision:8611; }","duration":"336.563032ms","start":"2026-06-25T22:38:00.167824Z","end":"2026-06-25T22:38:00.504387Z","steps":["trace[89665039] 'agreement among raft nodes before linearized reading' (duration: 336.288795ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:38:00.503989Z","caller":"traceutil/trace.go:171","msg":"trace[1944052567] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8918; }","duration":"142.190083ms","start":"2026-06-25T22:38:00.361773Z","end":"2026-06-25T22:38:00.503964Z","steps":["trace[1944052567] 'read index received' (duration: 104.434571ms)","trace[1944052567] 'applied index is now lower than readState.Index' (duration: 37.754592ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:38:00.504465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:38:00.167806Z","time spent":"336.647084ms","remote":"127.0.0.1:36000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4674,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j6dmc\" "} {"level":"warn","ts":"2026-06-25T22:38:00.504474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:38:00.203494Z","time spent":"300.810428ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:38:00.517457Z","caller":"traceutil/trace.go:171","msg":"trace[859207839] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"151.828431ms","start":"2026-06-25T22:38:00.365611Z","end":"2026-06-25T22:38:00.51744Z","steps":["trace[859207839] 'process raft request' (duration: 151.713137ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:38:16.083278Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a1.wal"} {"level":"info","ts":"2026-06-25T22:39:57.112819Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T22:39:57.118634Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-25T22:39:57.118755Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-25T22:42:16.399466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8280} {"level":"info","ts":"2026-06-25T22:42:16.500387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8280,"took":"98.802243ms","hash":362501896,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":25415680,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-25T22:42:16.500469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":362501896,"revision":8280,"compact-revision":6092} {"level":"info","ts":"2026-06-25T22:47:16.406355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10770} {"level":"info","ts":"2026-06-25T22:47:16.484142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10770,"took":"74.661404ms","hash":2178077797,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":27758592,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-25T22:47:16.484226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2178077797,"revision":10770,"compact-revision":8280} {"level":"warn","ts":"2026-06-25T22:49:26.115477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.30385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:49:26.115565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.153397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:49:26.115581Z","caller":"traceutil/trace.go:171","msg":"trace[812934655] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:13806; }","duration":"113.479615ms","start":"2026-06-25T22:49:26.002079Z","end":"2026-06-25T22:49:26.115559Z","steps":["trace[812934655] 'count revisions from in-memory index tree' (duration: 113.202468ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:49:26.115646Z","caller":"traceutil/trace.go:171","msg":"trace[1059268600] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13806; }","duration":"140.271931ms","start":"2026-06-25T22:49:25.975351Z","end":"2026-06-25T22:49:26.115623Z","steps":["trace[1059268600] 'range keys from in-memory index tree' (duration: 140.065895ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:49:27.137965Z","caller":"traceutil/trace.go:171","msg":"trace[594437764] linearizableReadLoop","detail":"{readStateIndex:14286; appliedIndex:14285; }","duration":"173.855361ms","start":"2026-06-25T22:49:26.964088Z","end":"2026-06-25T22:49:27.137943Z","steps":["trace[594437764] 'read index received' (duration: 173.600834ms)","trace[594437764] 'applied index is now lower than readState.Index' (duration: 253.427µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:49:27.138021Z","caller":"traceutil/trace.go:171","msg":"trace[808060674] transaction","detail":"{read_only:false; response_revision:13810; number_of_response:1; }","duration":"200.378081ms","start":"2026-06-25T22:49:26.937616Z","end":"2026-06-25T22:49:27.137994Z","steps":["trace[808060674] 'process raft request' (duration: 200.137174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:49:27.138194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.096699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6s669\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-06-25T22:49:27.138242Z","caller":"traceutil/trace.go:171","msg":"trace[482618186] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6s669; range_end:; response_count:1; response_revision:13810; }","duration":"174.195041ms","start":"2026-06-25T22:49:26.964033Z","end":"2026-06-25T22:49:27.138228Z","steps":["trace[482618186] 'agreement among raft nodes before linearized reading' (duration: 174.050107ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:49:28.203524Z","caller":"traceutil/trace.go:171","msg":"trace[1883520976] transaction","detail":"{read_only:false; response_revision:13818; number_of_response:1; }","duration":"105.300542ms","start":"2026-06-25T22:49:28.098189Z","end":"2026-06-25T22:49:28.20349Z","steps":["trace[1883520976] 'process raft request' (duration: 105.045095ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:49:49.357683Z","caller":"traceutil/trace.go:171","msg":"trace[1510705740] transaction","detail":"{read_only:false; response_revision:14114; number_of_response:1; }","duration":"140.484587ms","start":"2026-06-25T22:49:49.217179Z","end":"2026-06-25T22:49:49.357664Z","steps":["trace[1510705740] 'process raft request' (duration: 140.284602ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:49:49.373905Z","caller":"traceutil/trace.go:171","msg":"trace[1903942193] linearizableReadLoop","detail":"{readStateIndex:14607; appliedIndex:14604; }","duration":"121.508794ms","start":"2026-06-25T22:49:49.252379Z","end":"2026-06-25T22:49:49.373888Z","steps":["trace[1903942193] 'read index received' (duration: 105.167147ms)","trace[1903942193] 'applied index is now lower than readState.Index' (duration: 16.340147ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:49:49.373953Z","caller":"traceutil/trace.go:171","msg":"trace[1053610561] transaction","detail":"{read_only:false; response_revision:14115; number_of_response:1; }","duration":"156.746502ms","start":"2026-06-25T22:49:49.217173Z","end":"2026-06-25T22:49:49.37392Z","steps":["trace[1053610561] 'process raft request' (duration: 156.573867ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:49:49.374015Z","caller":"traceutil/trace.go:171","msg":"trace[1673195705] transaction","detail":"{read_only:false; response_revision:14116; number_of_response:1; }","duration":"152.065507ms","start":"2026-06-25T22:49:49.22194Z","end":"2026-06-25T22:49:49.374005Z","steps":["trace[1673195705] 'process raft request' (duration: 151.901863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:49:49.374359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.971217ms","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-06-25T22:49:49.374402Z","caller":"traceutil/trace.go:171","msg":"trace[87279236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14116; }","duration":"122.060629ms","start":"2026-06-25T22:49:49.252331Z","end":"2026-06-25T22:49:49.374392Z","steps":["trace[87279236] 'agreement among raft nodes before linearized reading' (duration: 121.902785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:49:49.374511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.331591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-25T22:49:49.374553Z","caller":"traceutil/trace.go:171","msg":"trace[1281916436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14116; }","duration":"120.408253ms","start":"2026-06-25T22:49:49.254134Z","end":"2026-06-25T22:49:49.374542Z","steps":["trace[1281916436] 'agreement among raft nodes before linearized reading' (duration: 120.266329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:49:49.374952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.551337ms","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-06-25T22:49:49.374994Z","caller":"traceutil/trace.go:171","msg":"trace[1921393948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14116; }","duration":"120.604549ms","start":"2026-06-25T22:49:49.254381Z","end":"2026-06-25T22:49:49.374986Z","steps":["trace[1921393948] 'agreement among raft nodes before linearized reading' (duration: 120.526386ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:52:16.414757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12943} {"level":"info","ts":"2026-06-25T22:52:16.505795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12943,"took":"88.639082ms","hash":1323084595,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":29872128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-25T22:52:16.505844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1323084595,"revision":12943,"compact-revision":10770} {"level":"warn","ts":"2026-06-25T22:56:59.681143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.83928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T22:56:59.68128Z","caller":"traceutil/trace.go:171","msg":"trace[436639820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17484; }","duration":"178.059537ms","start":"2026-06-25T22:56:59.50319Z","end":"2026-06-25T22:56:59.68125Z","steps":["trace[436639820] 'range keys from in-memory index tree' (duration: 177.724547ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:56:59.682805Z","caller":"traceutil/trace.go:171","msg":"trace[208419482] transaction","detail":"{read_only:false; response_revision:17485; number_of_response:1; }","duration":"124.566443ms","start":"2026-06-25T22:56:59.558209Z","end":"2026-06-25T22:56:59.682776Z","steps":["trace[208419482] 'process raft request' (duration: 104.181057ms)","trace[208419482] 'compare' (duration: 18.631805ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:57:00.517842Z","caller":"traceutil/trace.go:171","msg":"trace[1415234055] linearizableReadLoop","detail":"{readStateIndex:18089; appliedIndex:18088; }","duration":"146.963236ms","start":"2026-06-25T22:57:00.370855Z","end":"2026-06-25T22:57:00.517818Z","steps":["trace[1415234055] 'read index received' (duration: 146.723249ms)","trace[1415234055] 'applied index is now lower than readState.Index' (duration: 238.977µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:57:00.51806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.181732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18621"} {"level":"info","ts":"2026-06-25T22:57:00.518088Z","caller":"traceutil/trace.go:171","msg":"trace[1909126304] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17491; }","duration":"147.243643ms","start":"2026-06-25T22:57:00.370833Z","end":"2026-06-25T22:57:00.518077Z","steps":["trace[1909126304] 'agreement among raft nodes before linearized reading' (duration: 147.075758ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:57:00.518557Z","caller":"traceutil/trace.go:171","msg":"trace[531881935] transaction","detail":"{read_only:false; response_revision:17491; number_of_response:1; }","duration":"149.000463ms","start":"2026-06-25T22:57:00.369542Z","end":"2026-06-25T22:57:00.518542Z","steps":["trace[531881935] 'process raft request' (duration: 148.136029ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:57:00.793168Z","caller":"traceutil/trace.go:171","msg":"trace[1743083512] linearizableReadLoop","detail":"{readStateIndex:18090; appliedIndex:18089; }","duration":"219.256918ms","start":"2026-06-25T22:57:00.573884Z","end":"2026-06-25T22:57:00.793141Z","steps":["trace[1743083512] 'read index received' (duration: 219.030802ms)","trace[1743083512] 'applied index is now lower than readState.Index' (duration: 222.256µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:57:00.793336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.439583ms","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-06-25T22:57:00.793401Z","caller":"traceutil/trace.go:171","msg":"trace[1906755870] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17492; }","duration":"219.522225ms","start":"2026-06-25T22:57:00.573865Z","end":"2026-06-25T22:57:00.793388Z","steps":["trace[1906755870] 'agreement among raft nodes before linearized reading' (duration: 219.399572ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:57:00.793234Z","caller":"traceutil/trace.go:171","msg":"trace[527092605] transaction","detail":"{read_only:false; response_revision:17492; number_of_response:1; }","duration":"246.266373ms","start":"2026-06-25T22:57:00.546913Z","end":"2026-06-25T22:57:00.793179Z","steps":["trace[527092605] 'process raft request' (duration: 246.071247ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:57:16.420736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15304} {"level":"info","ts":"2026-06-25T22:57:16.531262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15304,"took":"107.607216ms","hash":4095352477,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":31977472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T22:57:16.531377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4095352477,"revision":15304,"compact-revision":12943} {"level":"info","ts":"2026-06-25T23:00:33.847732Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T23:00:33.852984Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-25T23:00:33.853108Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-25T23:02:16.428544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17737} {"level":"info","ts":"2026-06-25T23:02:16.53313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17737,"took":"100.254277ms","hash":603286636,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":34205696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-25T23:02:16.533199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":603286636,"revision":17737,"compact-revision":15304} {"level":"warn","ts":"2026-06-25T23:02:33.859439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.248106ms","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-06-25T23:02:33.859586Z","caller":"traceutil/trace.go:171","msg":"trace[2060067929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20167; }","duration":"175.433601ms","start":"2026-06-25T23:02:33.684123Z","end":"2026-06-25T23:02:33.859557Z","steps":["trace[2060067929] 'range keys from in-memory index tree' (duration: 175.052871ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:02:34.023482Z","caller":"traceutil/trace.go:171","msg":"trace[791023853] linearizableReadLoop","detail":"{readStateIndex:20850; appliedIndex:20849; }","duration":"139.090637ms","start":"2026-06-25T23:02:33.884347Z","end":"2026-06-25T23:02:34.023438Z","steps":["trace[791023853] 'read index received' (duration: 138.708656ms)","trace[791023853] 'applied index is now lower than readState.Index' (duration: 380.091µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:02:34.023614Z","caller":"traceutil/trace.go:171","msg":"trace[1787910268] transaction","detail":"{read_only:false; response_revision:20168; number_of_response:1; }","duration":"159.448956ms","start":"2026-06-25T23:02:33.864115Z","end":"2026-06-25T23:02:34.023564Z","steps":["trace[1787910268] 'process raft request' (duration: 158.973823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:02:34.023777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.389344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:02:34.023854Z","caller":"traceutil/trace.go:171","msg":"trace[225923296] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:20168; }","duration":"139.540919ms","start":"2026-06-25T23:02:33.884289Z","end":"2026-06-25T23:02:34.02383Z","steps":["trace[225923296] 'agreement among raft nodes before linearized reading' (duration: 139.378114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:02:34.027512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.368477ms","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-06-25T23:02:34.02761Z","caller":"traceutil/trace.go:171","msg":"trace[1248742165] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:20169; }","duration":"117.51293ms","start":"2026-06-25T23:02:33.910078Z","end":"2026-06-25T23:02:34.027591Z","steps":["trace[1248742165] 'agreement among raft nodes before linearized reading' (duration: 117.317685ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:02:39.600148Z","caller":"traceutil/trace.go:171","msg":"trace[558504183] transaction","detail":"{read_only:false; response_revision:20198; number_of_response:1; }","duration":"173.344681ms","start":"2026-06-25T23:02:39.426773Z","end":"2026-06-25T23:02:39.600117Z","steps":["trace[558504183] 'process raft request' (duration: 146.921559ms)","trace[558504183] 'compare' (duration: 25.99341ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:02:39.754978Z","caller":"traceutil/trace.go:171","msg":"trace[765472081] transaction","detail":"{read_only:false; response_revision:20199; number_of_response:1; }","duration":"149.327638ms","start":"2026-06-25T23:02:39.605616Z","end":"2026-06-25T23:02:39.754944Z","steps":["trace[765472081] 'process raft request' (duration: 139.029484ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:02:40.06943Z","caller":"traceutil/trace.go:171","msg":"trace[1065478247] linearizableReadLoop","detail":"{readStateIndex:20886; appliedIndex:20883; }","duration":"462.525738ms","start":"2026-06-25T23:02:39.606876Z","end":"2026-06-25T23:02:40.069402Z","steps":["trace[1065478247] 'read index received' (duration: 137.698956ms)","trace[1065478247] 'applied index is now lower than readState.Index' (duration: 324.825331ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:02:40.069467Z","caller":"traceutil/trace.go:171","msg":"trace[21493825] transaction","detail":"{read_only:false; response_revision:20200; number_of_response:1; }","duration":"463.734212ms","start":"2026-06-25T23:02:39.605693Z","end":"2026-06-25T23:02:40.069428Z","steps":["trace[21493825] 'process raft request' (duration: 405.229854ms)","trace[21493825] 'compare' (duration: 58.181399ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:02:40.069646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:02:39.605678Z","time spent":"463.892397ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:02:40.070031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.122384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-25T23:02:40.070386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.109143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-25T23:02:40.069602Z","caller":"traceutil/trace.go:171","msg":"trace[84591627] transaction","detail":"{read_only:false; response_revision:20201; number_of_response:1; }","duration":"463.409013ms","start":"2026-06-25T23:02:39.606181Z","end":"2026-06-25T23:02:40.06959Z","steps":["trace[84591627] 'process raft request' (duration: 463.121295ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:02:40.070464Z","caller":"traceutil/trace.go:171","msg":"trace[1429425634] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20201; }","duration":"410.221427ms","start":"2026-06-25T23:02:39.660214Z","end":"2026-06-25T23:02:40.070436Z","steps":["trace[1429425634] 'agreement among raft nodes before linearized reading' (duration: 410.089823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:02:40.070521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:02:39.660189Z","time spent":"410.322299ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-06-25T23:02:40.070415Z","caller":"traceutil/trace.go:171","msg":"trace[65372893] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:20201; }","duration":"463.525515ms","start":"2026-06-25T23:02:39.606866Z","end":"2026-06-25T23:02:40.070392Z","steps":["trace[65372893] 'agreement among raft nodes before linearized reading' (duration: 462.96596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:02:40.07057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:02:39.606145Z","time spent":"464.327189ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:02:40.070141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.036334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7697"} {"level":"warn","ts":"2026-06-25T23:02:40.070688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:02:39.60682Z","time spent":"463.850195ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-06-25T23:02:40.07081Z","caller":"traceutil/trace.go:171","msg":"trace[1484716244] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:20201; }","duration":"361.716883ms","start":"2026-06-25T23:02:39.70901Z","end":"2026-06-25T23:02:40.070727Z","steps":["trace[1484716244] 'agreement among raft nodes before linearized reading' (duration: 360.828438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:02:40.070978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:02:39.708988Z","time spent":"361.937719ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7721,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-06-25T23:02:40.345013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.618851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:02:40.345263Z","caller":"traceutil/trace.go:171","msg":"trace[768186617] transaction","detail":"{read_only:false; response_revision:20202; number_of_response:1; }","duration":"266.231511ms","start":"2026-06-25T23:02:40.078997Z","end":"2026-06-25T23:02:40.345228Z","steps":["trace[768186617] 'process raft request' (duration: 119.291441ms)","trace[768186617] 'compare' (duration: 146.487657ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:02:40.467914Z","caller":"traceutil/trace.go:171","msg":"trace[643095994] transaction","detail":"{read_only:false; response_revision:20205; number_of_response:1; }","duration":"151.076457ms","start":"2026-06-25T23:02:40.316811Z","end":"2026-06-25T23:02:40.467888Z","steps":["trace[643095994] 'process raft request' (duration: 151.013506ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:02:40.468011Z","caller":"traceutil/trace.go:171","msg":"trace[1574280430] transaction","detail":"{read_only:false; response_revision:20204; number_of_response:1; }","duration":"152.203719ms","start":"2026-06-25T23:02:40.31575Z","end":"2026-06-25T23:02:40.467953Z","steps":["trace[1574280430] 'process raft request' (duration: 145.302973ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:02:41.005593Z","caller":"traceutil/trace.go:171","msg":"trace[764275910] transaction","detail":"{read_only:false; response_revision:20207; number_of_response:1; }","duration":"355.939509ms","start":"2026-06-25T23:02:40.649625Z","end":"2026-06-25T23:02:41.005564Z","steps":["trace[764275910] 'process raft request' (duration: 355.744094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:02:41.005783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:02:40.649587Z","time spent":"356.071913ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:02:41.149496Z","caller":"traceutil/trace.go:171","msg":"trace[250707349] transaction","detail":"{read_only:false; response_revision:20208; number_of_response:1; }","duration":"202.559055ms","start":"2026-06-25T23:02:40.946907Z","end":"2026-06-25T23:02:41.149466Z","steps":["trace[250707349] 'process raft request' (duration: 176.016078ms)","trace[250707349] 'compare' (duration: 26.418463ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:06:29.875798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.999227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:06:29.876199Z","caller":"traceutil/trace.go:171","msg":"trace[1920615300] transaction","detail":"{read_only:false; response_revision:22654; number_of_response:1; }","duration":"334.331672ms","start":"2026-06-25T23:06:29.541845Z","end":"2026-06-25T23:06:29.876177Z","steps":["trace[1920615300] 'process raft request' (duration: 170.877512ms)","trace[1920615300] 'compare' (duration: 162.784791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:06:29.87628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:06:29.541819Z","time spent":"334.419905ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:06:29.876365Z","caller":"traceutil/trace.go:171","msg":"trace[140196804] linearizableReadLoop","detail":"{readStateIndex:23410; appliedIndex:23409; }","duration":"332.579423ms","start":"2026-06-25T23:06:29.543776Z","end":"2026-06-25T23:06:29.876356Z","steps":["trace[140196804] 'read index received' (duration: 168.971168ms)","trace[140196804] 'applied index is now lower than readState.Index' (duration: 163.607175ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:06:29.876554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.773588ms","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-06-25T23:06:29.876595Z","caller":"traceutil/trace.go:171","msg":"trace[1514856760] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:22656; }","duration":"332.83899ms","start":"2026-06-25T23:06:29.543741Z","end":"2026-06-25T23:06:29.87658Z","steps":["trace[1514856760] 'agreement among raft nodes before linearized reading' (duration: 332.690566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:06:29.876627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:06:29.543722Z","time spent":"332.895261ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-06-25T23:06:29.876748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.997631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:625"} {"level":"info","ts":"2026-06-25T23:06:29.876818Z","caller":"traceutil/trace.go:171","msg":"trace[1765987547] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22656; }","duration":"102.126265ms","start":"2026-06-25T23:06:29.774674Z","end":"2026-06-25T23:06:29.876801Z","steps":["trace[1765987547] 'agreement among raft nodes before linearized reading' (duration: 101.955041ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:07:16.436137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20081} {"level":"info","ts":"2026-06-25T23:07:16.550516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20081,"took":"108.57462ms","hash":2974268295,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":42602496,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-06-25T23:07:16.550595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2974268295,"revision":20081,"compact-revision":17737} {"level":"info","ts":"2026-06-25T23:09:50.779018Z","caller":"traceutil/trace.go:171","msg":"trace[1256564798] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"116.646877ms","start":"2026-06-25T23:09:50.66234Z","end":"2026-06-25T23:09:50.778986Z","steps":["trace[1256564798] 'process raft request' (duration: 116.233904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.143777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.583519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:09:56.143965Z","caller":"traceutil/trace.go:171","msg":"trace[191969841] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24732; }","duration":"120.862187ms","start":"2026-06-25T23:09:56.023074Z","end":"2026-06-25T23:09:56.143936Z","steps":["trace[191969841] 'count revisions from in-memory index tree' (duration: 120.476435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.590712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.210615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:09:56.590972Z","caller":"traceutil/trace.go:171","msg":"trace[1925178483] linearizableReadLoop","detail":"{readStateIndex:25549; appliedIndex:25545; }","duration":"301.64618ms","start":"2026-06-25T23:09:56.28931Z","end":"2026-06-25T23:09:56.590956Z","steps":["trace[1925178483] 'read index received' (duration: 44.208449ms)","trace[1925178483] 'applied index is now lower than readState.Index' (duration: 257.436511ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:09:56.591185Z","caller":"traceutil/trace.go:171","msg":"trace[2043156483] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"441.947781ms","start":"2026-06-25T23:09:56.149224Z","end":"2026-06-25T23:09:56.591172Z","steps":["trace[2043156483] 'process raft request' (duration: 184.201252ms)","trace[2043156483] 'compare' (duration: 256.507644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:09:56.591245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.149192Z","time spent":"442.019513ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:09:56.591421Z","caller":"traceutil/trace.go:171","msg":"trace[1250261085] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"395.535828ms","start":"2026-06-25T23:09:56.195875Z","end":"2026-06-25T23:09:56.591411Z","steps":["trace[1250261085] 'process raft request' (duration: 394.942841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.591471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.195855Z","time spent":"395.58791ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:09:56.591597Z","caller":"traceutil/trace.go:171","msg":"trace[1066807441] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"365.931404ms","start":"2026-06-25T23:09:56.225656Z","end":"2026-06-25T23:09:56.591588Z","steps":["trace[1066807441] 'process raft request' (duration: 365.218874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.591642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.225617Z","time spent":"365.999985ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:09:56.591767Z","caller":"traceutil/trace.go:171","msg":"trace[1822898758] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"340.295903ms","start":"2026-06-25T23:09:56.251463Z","end":"2026-06-25T23:09:56.591758Z","steps":["trace[1822898758] 'process raft request' (duration: 339.447529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.591813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.251435Z","time spent":"340.353085ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:09:56.591948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.637149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:09:56.591977Z","caller":"traceutil/trace.go:171","msg":"trace[99310086] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24737; }","duration":"302.706931ms","start":"2026-06-25T23:09:56.28926Z","end":"2026-06-25T23:09:56.591967Z","steps":["trace[99310086] 'agreement among raft nodes before linearized reading' (duration: 302.65784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.592008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.289233Z","time spent":"302.767223ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-06-25T23:09:56.777724Z","caller":"traceutil/trace.go:171","msg":"trace[1093015750] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"254.265891ms","start":"2026-06-25T23:09:56.523429Z","end":"2026-06-25T23:09:56.777695Z","steps":["trace[1093015750] 'process raft request' (duration: 254.203949ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:09:56.777721Z","caller":"traceutil/trace.go:171","msg":"trace[1068023513] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"272.84501ms","start":"2026-06-25T23:09:56.504843Z","end":"2026-06-25T23:09:56.777688Z","steps":["trace[1068023513] 'process raft request' (duration: 272.629224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.778107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.202336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-25T23:09:56.778182Z","caller":"traceutil/trace.go:171","msg":"trace[789070757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24739; }","duration":"383.3311ms","start":"2026-06-25T23:09:56.394835Z","end":"2026-06-25T23:09:56.778166Z","steps":["trace[789070757] 'agreement among raft nodes before linearized reading' (duration: 383.037622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.778237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.394815Z","time spent":"383.410372ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T23:09:56.778391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.693345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-25T23:09:56.778453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.395735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-25T23:09:56.777816Z","caller":"traceutil/trace.go:171","msg":"trace[1020310698] linearizableReadLoop","detail":"{readStateIndex:25551; appliedIndex:25551; }","duration":"186.768176ms","start":"2026-06-25T23:09:56.591Z","end":"2026-06-25T23:09:56.777768Z","steps":["trace[1020310698] 'read index received' (duration: 186.744495ms)","trace[1020310698] 'applied index is now lower than readState.Index' (duration: 20.02µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:09:56.778497Z","caller":"traceutil/trace.go:171","msg":"trace[2074662024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24739; }","duration":"381.452297ms","start":"2026-06-25T23:09:56.397031Z","end":"2026-06-25T23:09:56.778484Z","steps":["trace[2074662024] 'agreement among raft nodes before linearized reading' (duration: 381.347344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.778504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.740693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-25T23:09:56.778526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.397022Z","time spent":"381.498158ms","remote":"127.0.0.1:36054","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-06-25T23:09:56.778539Z","caller":"traceutil/trace.go:171","msg":"trace[970163892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24739; }","duration":"274.801015ms","start":"2026-06-25T23:09:56.503728Z","end":"2026-06-25T23:09:56.778529Z","steps":["trace[970163892] 'agreement among raft nodes before linearized reading' (duration: 274.753604ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:09:56.778457Z","caller":"traceutil/trace.go:171","msg":"trace[776770595] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:24739; }","duration":"346.8355ms","start":"2026-06-25T23:09:56.431607Z","end":"2026-06-25T23:09:56.778442Z","steps":["trace[776770595] 'agreement among raft nodes before linearized reading' (duration: 346.719286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:09:56.778827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:09:56.431584Z","time spent":"347.18306ms","remote":"127.0.0.1:38616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"info","ts":"2026-06-25T23:10:01.090808Z","caller":"traceutil/trace.go:171","msg":"trace[1589920814] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"116.605876ms","start":"2026-06-25T23:10:00.974175Z","end":"2026-06-25T23:10:01.090781Z","steps":["trace[1589920814] 'process raft request' (duration: 115.965077ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:01.332298Z","caller":"traceutil/trace.go:171","msg":"trace[1047831694] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"235.164305ms","start":"2026-06-25T23:10:01.097115Z","end":"2026-06-25T23:10:01.33228Z","steps":["trace[1047831694] 'process raft request' (duration: 235.006581ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:01.337952Z","caller":"traceutil/trace.go:171","msg":"trace[1320797744] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"240.610311ms","start":"2026-06-25T23:10:01.097317Z","end":"2026-06-25T23:10:01.337928Z","steps":["trace[1320797744] 'process raft request' (duration: 240.015454ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:02.402856Z","caller":"traceutil/trace.go:171","msg":"trace[1387771582] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"218.449769ms","start":"2026-06-25T23:10:02.184361Z","end":"2026-06-25T23:10:02.402811Z","steps":["trace[1387771582] 'process raft request' (duration: 152.162828ms)","trace[1387771582] 'compare' (duration: 65.833898ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:02.601101Z","caller":"traceutil/trace.go:171","msg":"trace[750535959] linearizableReadLoop","detail":"{readStateIndex:25602; appliedIndex:25601; }","duration":"116.027219ms","start":"2026-06-25T23:10:02.485044Z","end":"2026-06-25T23:10:02.601071Z","steps":["trace[750535959] 'read index received' (duration: 112.128917ms)","trace[750535959] 'applied index is now lower than readState.Index' (duration: 3.897182ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:02.601412Z","caller":"traceutil/trace.go:171","msg":"trace[1405623385] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"130.244904ms","start":"2026-06-25T23:10:02.471123Z","end":"2026-06-25T23:10:02.601368Z","steps":["trace[1405623385] 'process raft request' (duration: 126.116496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:02.601446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.079244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:02.601596Z","caller":"traceutil/trace.go:171","msg":"trace[785369685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24789; }","duration":"100.268129ms","start":"2026-06-25T23:10:02.501302Z","end":"2026-06-25T23:10:02.60157Z","steps":["trace[785369685] 'agreement among raft nodes before linearized reading' (duration: 99.987801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:02.601719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.599755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29707150-57zrz\" ","response":"range_response_count:1 size:6988"} {"level":"info","ts":"2026-06-25T23:10:02.601838Z","caller":"traceutil/trace.go:171","msg":"trace[893757678] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29707150-57zrz; range_end:; response_count:1; response_revision:24789; }","duration":"116.823341ms","start":"2026-06-25T23:10:02.484986Z","end":"2026-06-25T23:10:02.60181Z","steps":["trace[893757678] 'agreement among raft nodes before linearized reading' (duration: 116.353458ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:02.956422Z","caller":"traceutil/trace.go:171","msg":"trace[548228043] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"344.485532ms","start":"2026-06-25T23:10:02.611891Z","end":"2026-06-25T23:10:02.956376Z","steps":["trace[548228043] 'process raft request' (duration: 314.478428ms)","trace[548228043] 'compare' (duration: 29.722246ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:02.956603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.611873Z","time spent":"344.640957ms","remote":"127.0.0.1:36000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7208,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:03.248432Z","caller":"traceutil/trace.go:171","msg":"trace[1581534846] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"635.568622ms","start":"2026-06-25T23:10:02.612835Z","end":"2026-06-25T23:10:03.248403Z","steps":["trace[1581534846] 'process raft request' (duration: 635.474859ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.248848Z","caller":"traceutil/trace.go:171","msg":"trace[1833070417] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"447.53378ms","start":"2026-06-25T23:10:02.801289Z","end":"2026-06-25T23:10:03.248823Z","steps":["trace[1833070417] 'process raft request' (duration: 447.458728ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.248904Z","caller":"traceutil/trace.go:171","msg":"trace[1746742694] linearizableReadLoop","detail":"{readStateIndex:25606; appliedIndex:25602; }","duration":"630.005583ms","start":"2026-06-25T23:10:02.618875Z","end":"2026-06-25T23:10:03.24888Z","steps":["trace[1746742694] 'read index received' (duration: 307.514418ms)","trace[1746742694] 'applied index is now lower than readState.Index' (duration: 322.490085ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:03.248943Z","caller":"traceutil/trace.go:171","msg":"trace[1783089979] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"447.589862ms","start":"2026-06-25T23:10:02.801315Z","end":"2026-06-25T23:10:03.248905Z","steps":["trace[1783089979] 'process raft request' (duration: 447.473518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.248998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.612822Z","time spent":"636.086806ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:03.249001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.801264Z","time spent":"447.658454ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:03.248737Z","caller":"traceutil/trace.go:171","msg":"trace[1837712104] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"636.514679ms","start":"2026-06-25T23:10:02.612169Z","end":"2026-06-25T23:10:03.248684Z","steps":["trace[1837712104] 'process raft request' (duration: 635.987244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.249086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.199718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-25T23:10:03.249085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.801281Z","time spent":"447.726286ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:03.249113Z","caller":"traceutil/trace.go:171","msg":"trace[882052032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24796; }","duration":"630.26096ms","start":"2026-06-25T23:10:02.618844Z","end":"2026-06-25T23:10:03.249105Z","steps":["trace[882052032] 'agreement among raft nodes before linearized reading' (duration: 630.138857ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.248803Z","caller":"traceutil/trace.go:171","msg":"trace[29386035] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"634.581733ms","start":"2026-06-25T23:10:02.6142Z","end":"2026-06-25T23:10:03.248782Z","steps":["trace[29386035] 'process raft request' (duration: 634.157921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.249145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.618832Z","time spent":"630.305851ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T23:10:03.249161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.612146Z","time spent":"636.924639ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:03.249197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.614188Z","time spent":"634.969975ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:03.248828Z","caller":"traceutil/trace.go:171","msg":"trace[228580042] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"513.008617ms","start":"2026-06-25T23:10:02.735809Z","end":"2026-06-25T23:10:03.248818Z","steps":["trace[228580042] 'process raft request' (duration: 512.843673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.24932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.735779Z","time spent":"513.458779ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:03.264583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.312037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-25T23:10:03.26464Z","caller":"traceutil/trace.go:171","msg":"trace[1393657330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24797; }","duration":"458.416641ms","start":"2026-06-25T23:10:02.80621Z","end":"2026-06-25T23:10:03.264627Z","steps":["trace[1393657330] 'agreement among raft nodes before linearized reading' (duration: 458.254705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.264666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.806187Z","time spent":"458.474082ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T23:10:03.264645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.096076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:10:03.264717Z","caller":"traceutil/trace.go:171","msg":"trace[1741952576] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24797; }","duration":"340.232032ms","start":"2026-06-25T23:10:02.924467Z","end":"2026-06-25T23:10:03.264699Z","steps":["trace[1741952576] 'agreement among raft nodes before linearized reading' (duration: 340.018735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.264769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.924439Z","time spent":"340.316933ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":81,"response size":32,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-06-25T23:10:03.26484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.04138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-06-25T23:10:03.264788Z","caller":"traceutil/trace.go:171","msg":"trace[552469216] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"337.182994ms","start":"2026-06-25T23:10:02.927582Z","end":"2026-06-25T23:10:03.264765Z","steps":["trace[552469216] 'process raft request' (duration: 336.770932ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.264883Z","caller":"traceutil/trace.go:171","msg":"trace[642847563] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24797; }","duration":"371.103662ms","start":"2026-06-25T23:10:02.893757Z","end":"2026-06-25T23:10:03.26486Z","steps":["trace[642847563] 'agreement among raft nodes before linearized reading' (duration: 371.03118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.264901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.893736Z","time spent":"371.161843ms","remote":"127.0.0.1:36000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-25T23:10:03.265073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.311702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-25T23:10:03.265066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.927557Z","time spent":"337.339749ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:03.265099Z","caller":"traceutil/trace.go:171","msg":"trace[812966143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24797; }","duration":"449.358283ms","start":"2026-06-25T23:10:02.815733Z","end":"2026-06-25T23:10:03.265091Z","steps":["trace[812966143] 'agreement among raft nodes before linearized reading' (duration: 449.295391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.265116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:02.815723Z","time spent":"449.388774ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T23:10:03.42548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.583664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-06-25T23:10:03.425614Z","caller":"traceutil/trace.go:171","msg":"trace[1174690686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24798; }","duration":"143.79518ms","start":"2026-06-25T23:10:03.281793Z","end":"2026-06-25T23:10:03.425588Z","steps":["trace[1174690686] 'range keys from in-memory index tree' (duration: 143.407099ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.425906Z","caller":"traceutil/trace.go:171","msg":"trace[1063945888] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"143.958544ms","start":"2026-06-25T23:10:03.281923Z","end":"2026-06-25T23:10:03.425882Z","steps":["trace[1063945888] 'process raft request' (duration: 140.620599ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.74171Z","caller":"traceutil/trace.go:171","msg":"trace[2045945414] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25611; }","duration":"404.955387ms","start":"2026-06-25T23:10:03.336733Z","end":"2026-06-25T23:10:03.741688Z","steps":["trace[2045945414] 'read index received' (duration: 85.800337ms)","trace[2045945414] 'applied index is now lower than readState.Index' (duration: 319.154149ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:03.74179Z","caller":"traceutil/trace.go:171","msg":"trace[2144678409] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"457.958538ms","start":"2026-06-25T23:10:03.283807Z","end":"2026-06-25T23:10:03.741766Z","steps":["trace[2144678409] 'process raft request' (duration: 449.870857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.741901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.170052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-25T23:10:03.74191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:03.283788Z","time spent":"458.047371ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:03.741935Z","caller":"traceutil/trace.go:171","msg":"trace[1479190883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24800; }","duration":"405.247265ms","start":"2026-06-25T23:10:03.336676Z","end":"2026-06-25T23:10:03.741923Z","steps":["trace[1479190883] 'agreement among raft nodes before linearized reading' (duration: 405.117901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.741968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:03.336652Z","time spent":"405.301816ms","remote":"127.0.0.1:36054","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-06-25T23:10:03.742019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.327182ms","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-06-25T23:10:03.74209Z","caller":"traceutil/trace.go:171","msg":"trace[340818946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24800; }","duration":"395.443435ms","start":"2026-06-25T23:10:03.346627Z","end":"2026-06-25T23:10:03.742071Z","steps":["trace[340818946] 'agreement among raft nodes before linearized reading' (duration: 395.24815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.742127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:03.346601Z","time spent":"395.517997ms","remote":"127.0.0.1:36054","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":"warn","ts":"2026-06-25T23:10:03.742249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.514968ms","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-06-25T23:10:03.742277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.925059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-06-25T23:10:03.74229Z","caller":"traceutil/trace.go:171","msg":"trace[385749038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24800; }","duration":"287.6014ms","start":"2026-06-25T23:10:03.454677Z","end":"2026-06-25T23:10:03.742278Z","steps":["trace[385749038] 'agreement among raft nodes before linearized reading' (duration: 287.525878ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:03.742303Z","caller":"traceutil/trace.go:171","msg":"trace[506669515] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24800; }","duration":"314.992651ms","start":"2026-06-25T23:10:03.427302Z","end":"2026-06-25T23:10:03.742295Z","steps":["trace[506669515] 'agreement among raft nodes before linearized reading' (duration: 314.911198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:03.742319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:03.427282Z","time spent":"315.033902ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18253,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-25T23:10:03.742316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.568647ms","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-06-25T23:10:03.742357Z","caller":"traceutil/trace.go:171","msg":"trace[1462326776] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24800; }","duration":"141.645269ms","start":"2026-06-25T23:10:03.600698Z","end":"2026-06-25T23:10:03.742343Z","steps":["trace[1462326776] 'agreement among raft nodes before linearized reading' (duration: 141.538756ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:04.379715Z","caller":"traceutil/trace.go:171","msg":"trace[2096113882] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"140.151066ms","start":"2026-06-25T23:10:04.239538Z","end":"2026-06-25T23:10:04.379689Z","steps":["trace[2096113882] 'process raft request' (duration: 139.98812ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:05.603376Z","caller":"traceutil/trace.go:171","msg":"trace[885385394] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"345.052638ms","start":"2026-06-25T23:10:05.258293Z","end":"2026-06-25T23:10:05.603345Z","steps":["trace[885385394] 'process raft request' (duration: 344.702248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.603565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.258261Z","time spent":"345.210853ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:05.603596Z","caller":"traceutil/trace.go:171","msg":"trace[358210697] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"345.250024ms","start":"2026-06-25T23:10:05.258307Z","end":"2026-06-25T23:10:05.603557Z","steps":["trace[358210697] 'process raft request' (duration: 344.976836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.603811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.258272Z","time spent":"345.408949ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:05.623896Z","caller":"traceutil/trace.go:171","msg":"trace[1035873723] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"365.44475ms","start":"2026-06-25T23:10:05.258424Z","end":"2026-06-25T23:10:05.623869Z","steps":["trace[1035873723] 'process raft request' (duration: 365.223144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.624089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.258417Z","time spent":"365.555984ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:05.624298Z","caller":"traceutil/trace.go:171","msg":"trace[1002639638] linearizableReadLoop","detail":"{readStateIndex:25624; appliedIndex:25619; }","duration":"342.811785ms","start":"2026-06-25T23:10:05.281457Z","end":"2026-06-25T23:10:05.624269Z","steps":["trace[1002639638] 'read index received' (duration: 321.796476ms)","trace[1002639638] 'applied index is now lower than readState.Index' (duration: 21.014039ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:05.624467Z","caller":"traceutil/trace.go:171","msg":"trace[1161753777] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"365.80854ms","start":"2026-06-25T23:10:05.258643Z","end":"2026-06-25T23:10:05.624452Z","steps":["trace[1161753777] 'process raft request' (duration: 365.463871ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:05.624517Z","caller":"traceutil/trace.go:171","msg":"trace[870306288] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"365.982926ms","start":"2026-06-25T23:10:05.258515Z","end":"2026-06-25T23:10:05.624498Z","steps":["trace[870306288] 'process raft request' (duration: 365.292126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.624599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.258636Z","time spent":"365.872013ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:05.624634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.258505Z","time spent":"366.077268ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:05.624883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.821952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-25T23:10:05.624929Z","caller":"traceutil/trace.go:171","msg":"trace[1828368569] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24810; }","duration":"203.911694ms","start":"2026-06-25T23:10:05.421002Z","end":"2026-06-25T23:10:05.624914Z","steps":["trace[1828368569] 'agreement among raft nodes before linearized reading' (duration: 203.75212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.625127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.666629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-25T23:10:05.625175Z","caller":"traceutil/trace.go:171","msg":"trace[1500253545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24810; }","duration":"343.748001ms","start":"2026-06-25T23:10:05.281415Z","end":"2026-06-25T23:10:05.625163Z","steps":["trace[1500253545] 'agreement among raft nodes before linearized reading' (duration: 343.602147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.625204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.281398Z","time spent":"343.799333ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T23:10:05.62527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.220357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-25T23:10:05.625414Z","caller":"traceutil/trace.go:171","msg":"trace[1003680210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24810; }","duration":"195.415232ms","start":"2026-06-25T23:10:05.429984Z","end":"2026-06-25T23:10:05.625399Z","steps":["trace[1003680210] 'agreement among raft nodes before linearized reading' (duration: 195.068592ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:05.966065Z","caller":"traceutil/trace.go:171","msg":"trace[1795312843] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"335.165307ms","start":"2026-06-25T23:10:05.630864Z","end":"2026-06-25T23:10:05.966029Z","steps":["trace[1795312843] 'process raft request' (duration: 334.9294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:05.966309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.630838Z","time spent":"335.336092ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:06.050223Z","caller":"traceutil/trace.go:171","msg":"trace[1515177776] linearizableReadLoop","detail":"{readStateIndex:25627; appliedIndex:25624; }","duration":"303.828453ms","start":"2026-06-25T23:10:05.746361Z","end":"2026-06-25T23:10:06.05019Z","steps":["trace[1515177776] 'read index received' (duration: 219.374875ms)","trace[1515177776] 'applied index is now lower than readState.Index' (duration: 84.452278ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:06.050319Z","caller":"traceutil/trace.go:171","msg":"trace[1119586502] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"419.186303ms","start":"2026-06-25T23:10:05.631098Z","end":"2026-06-25T23:10:06.050284Z","steps":["trace[1119586502] 'process raft request' (duration: 418.000208ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:06.050376Z","caller":"traceutil/trace.go:171","msg":"trace[1098491724] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"419.08353ms","start":"2026-06-25T23:10:05.631234Z","end":"2026-06-25T23:10:06.050318Z","steps":["trace[1098491724] 'process raft request' (duration: 418.905855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:06.050477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.63106Z","time spent":"419.326497ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:06.050578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.189304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-25T23:10:06.050666Z","caller":"traceutil/trace.go:171","msg":"trace[601399280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24813; }","duration":"304.337838ms","start":"2026-06-25T23:10:05.746314Z","end":"2026-06-25T23:10:06.050652Z","steps":["trace[601399280] 'agreement among raft nodes before linearized reading' (duration: 304.04373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:06.050724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.74629Z","time spent":"304.42354ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T23:10:06.050608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.631207Z","time spent":"419.253925ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:06.050917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.839904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-25T23:10:06.050964Z","caller":"traceutil/trace.go:171","msg":"trace[2145564913] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24813; }","duration":"302.919547ms","start":"2026-06-25T23:10:05.748033Z","end":"2026-06-25T23:10:06.050953Z","steps":["trace[2145564913] 'agreement among raft nodes before linearized reading' (duration: 302.751202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:06.051003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:05.748012Z","time spent":"302.981499ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-06-25T23:10:06.051215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.594199ms","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-06-25T23:10:06.051238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.909575ms","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-06-25T23:10:06.05126Z","caller":"traceutil/trace.go:171","msg":"trace[1471837403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24813; }","duration":"288.671171ms","start":"2026-06-25T23:10:05.762579Z","end":"2026-06-25T23:10:06.05125Z","steps":["trace[1471837403] 'agreement among raft nodes before linearized reading' (duration: 288.528787ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:06.051271Z","caller":"traceutil/trace.go:171","msg":"trace[1245984597] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24813; }","duration":"289.963097ms","start":"2026-06-25T23:10:05.761297Z","end":"2026-06-25T23:10:06.05126Z","steps":["trace[1245984597] 'agreement among raft nodes before linearized reading' (duration: 289.867585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:06.051457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.452618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:06.051493Z","caller":"traceutil/trace.go:171","msg":"trace[360325102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24813; }","duration":"171.489969ms","start":"2026-06-25T23:10:05.879991Z","end":"2026-06-25T23:10:06.051481Z","steps":["trace[360325102] 'agreement among raft nodes before linearized reading' (duration: 171.432008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:06.051585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.230668ms","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-06-25T23:10:06.051623Z","caller":"traceutil/trace.go:171","msg":"trace[1154563687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24813; }","duration":"288.2897ms","start":"2026-06-25T23:10:05.763325Z","end":"2026-06-25T23:10:06.051615Z","steps":["trace[1154563687] 'agreement among raft nodes before linearized reading' (duration: 288.200208ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:06.450316Z","caller":"traceutil/trace.go:171","msg":"trace[1931576983] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"136.912254ms","start":"2026-06-25T23:10:06.313374Z","end":"2026-06-25T23:10:06.450286Z","steps":["trace[1931576983] 'process raft request' (duration: 136.685918ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:06.60912Z","caller":"traceutil/trace.go:171","msg":"trace[159269036] linearizableReadLoop","detail":"{readStateIndex:25634; appliedIndex:25633; }","duration":"108.641367ms","start":"2026-06-25T23:10:06.50044Z","end":"2026-06-25T23:10:06.609081Z","steps":["trace[159269036] 'read index received' (duration: 88.079021ms)","trace[159269036] 'applied index is now lower than readState.Index' (duration: 20.560606ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:06.609314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.835302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:06.609353Z","caller":"traceutil/trace.go:171","msg":"trace[188928131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24820; }","duration":"108.943435ms","start":"2026-06-25T23:10:06.500397Z","end":"2026-06-25T23:10:06.609341Z","steps":["trace[188928131] 'agreement among raft nodes before linearized reading' (duration: 108.820902ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:06.609282Z","caller":"traceutil/trace.go:171","msg":"trace[1307870283] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"222.654748ms","start":"2026-06-25T23:10:06.386521Z","end":"2026-06-25T23:10:06.609176Z","steps":["trace[1307870283] 'process raft request' (duration: 201.986659ms)","trace[1307870283] 'compare' (duration: 20.391921ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:08.127792Z","caller":"traceutil/trace.go:171","msg":"trace[110988026] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"517.626118ms","start":"2026-06-25T23:10:07.610147Z","end":"2026-06-25T23:10:08.127773Z","steps":["trace[110988026] 'process raft request' (duration: 517.460424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.127906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.610113Z","time spent":"517.734382ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:08.319571Z","caller":"traceutil/trace.go:171","msg":"trace[1790165059] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"709.342145ms","start":"2026-06-25T23:10:07.610195Z","end":"2026-06-25T23:10:08.319537Z","steps":["trace[1790165059] 'process raft request' (duration: 708.898653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.31983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.610153Z","time spent":"709.535101ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:08.319997Z","caller":"traceutil/trace.go:171","msg":"trace[1687183399] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"689.958782ms","start":"2026-06-25T23:10:07.630012Z","end":"2026-06-25T23:10:08.319971Z","steps":["trace[1687183399] 'process raft request' (duration: 689.456758ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:08.320112Z","caller":"traceutil/trace.go:171","msg":"trace[2135634051] linearizableReadLoop","detail":"{readStateIndex:25640; appliedIndex:25635; }","duration":"473.892772ms","start":"2026-06-25T23:10:07.84619Z","end":"2026-06-25T23:10:08.320083Z","steps":["trace[2135634051] 'read index received' (duration: 281.426234ms)","trace[2135634051] 'applied index is now lower than readState.Index' (duration: 192.465518ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:08.320132Z","caller":"traceutil/trace.go:171","msg":"trace[1255463735] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"688.122181ms","start":"2026-06-25T23:10:07.631959Z","end":"2026-06-25T23:10:08.320081Z","steps":["trace[1255463735] 'process raft request' (duration: 687.823392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.320791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.631945Z","time spent":"688.791099ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:08.320238Z","caller":"traceutil/trace.go:171","msg":"trace[145760018] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"279.51037ms","start":"2026-06-25T23:10:08.040713Z","end":"2026-06-25T23:10:08.320223Z","steps":["trace[145760018] 'process raft request' (duration: 279.313364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.320269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.629979Z","time spent":"690.077696ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:08.320937Z","caller":"traceutil/trace.go:171","msg":"trace[850754388] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"176.169252ms","start":"2026-06-25T23:10:08.14473Z","end":"2026-06-25T23:10:08.3209Z","steps":["trace[850754388] 'process raft request' (duration: 175.899834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.321173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.307533ms","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-06-25T23:10:08.320272Z","caller":"traceutil/trace.go:171","msg":"trace[1163442611] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"688.194511ms","start":"2026-06-25T23:10:07.632055Z","end":"2026-06-25T23:10:08.320249Z","steps":["trace[1163442611] 'process raft request' (duration: 687.911913ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:08.321221Z","caller":"traceutil/trace.go:171","msg":"trace[999112434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24829; }","duration":"199.402336ms","start":"2026-06-25T23:10:08.121803Z","end":"2026-06-25T23:10:08.321205Z","steps":["trace[999112434] 'agreement among raft nodes before linearized reading' (duration: 199.23428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.321206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.915597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-25T23:10:08.321291Z","caller":"traceutil/trace.go:171","msg":"trace[821281616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24829; }","duration":"350.04239ms","start":"2026-06-25T23:10:07.97123Z","end":"2026-06-25T23:10:08.321272Z","steps":["trace[821281616] 'agreement among raft nodes before linearized reading' (duration: 349.789172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.321286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.632028Z","time spent":"689.197961ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:08.321337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.971211Z","time spent":"350.117653ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T23:10:08.320344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.145038ms","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":"warn","ts":"2026-06-25T23:10:08.321396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.058252ms","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-06-25T23:10:08.321409Z","caller":"traceutil/trace.go:171","msg":"trace[1740464478] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24827; }","duration":"475.23604ms","start":"2026-06-25T23:10:07.846151Z","end":"2026-06-25T23:10:08.321387Z","steps":["trace[1740464478] 'agreement among raft nodes before linearized reading' (duration: 474.111348ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:08.321491Z","caller":"traceutil/trace.go:171","msg":"trace[1158858539] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24829; }","duration":"235.201916ms","start":"2026-06-25T23:10:08.086272Z","end":"2026-06-25T23:10:08.321474Z","steps":["trace[1158858539] 'agreement among raft nodes before linearized reading' (duration: 234.959719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.321562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:07.846133Z","time spent":"475.404986ms","remote":"127.0.0.1:57340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-06-25T23:10:08.321569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.471699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-25T23:10:08.321613Z","caller":"traceutil/trace.go:171","msg":"trace[2094970158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24829; }","duration":"265.551301ms","start":"2026-06-25T23:10:08.05605Z","end":"2026-06-25T23:10:08.321601Z","steps":["trace[2094970158] 'agreement among raft nodes before linearized reading' (duration: 265.349176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:08.321642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.939271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-25T23:10:08.32167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.774847ms","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-06-25T23:10:08.321759Z","caller":"traceutil/trace.go:171","msg":"trace[1103418852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24829; }","duration":"198.924771ms","start":"2026-06-25T23:10:08.122814Z","end":"2026-06-25T23:10:08.321739Z","steps":["trace[1103418852] 'agreement among raft nodes before linearized reading' (duration: 198.548931ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:08.321693Z","caller":"traceutil/trace.go:171","msg":"trace[2016368938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24829; }","duration":"200.023413ms","start":"2026-06-25T23:10:08.121652Z","end":"2026-06-25T23:10:08.321675Z","steps":["trace[2016368938] 'agreement among raft nodes before linearized reading' (duration: 199.814588ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:12.477623Z","caller":"traceutil/trace.go:171","msg":"trace[1595755007] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"402.94682ms","start":"2026-06-25T23:10:12.074654Z","end":"2026-06-25T23:10:12.477601Z","steps":["trace[1595755007] 'process raft request' (duration: 402.784155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.477746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.074639Z","time spent":"403.043082ms","remote":"127.0.0.1:36096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:12.666751Z","caller":"traceutil/trace.go:171","msg":"trace[906588445] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"590.741024ms","start":"2026-06-25T23:10:12.075976Z","end":"2026-06-25T23:10:12.666717Z","steps":["trace[906588445] 'process raft request' (duration: 590.469097ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:12.666899Z","caller":"traceutil/trace.go:171","msg":"trace[1455047166] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"520.866031ms","start":"2026-06-25T23:10:12.14596Z","end":"2026-06-25T23:10:12.666826Z","steps":["trace[1455047166] 'process raft request' (duration: 520.682836ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:12.666976Z","caller":"traceutil/trace.go:171","msg":"trace[1089207811] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"330.800812ms","start":"2026-06-25T23:10:12.336163Z","end":"2026-06-25T23:10:12.666964Z","steps":["trace[1089207811] 'process raft request' (duration: 330.533525ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:12.667329Z","caller":"traceutil/trace.go:171","msg":"trace[2143129513] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"326.379137ms","start":"2026-06-25T23:10:12.340926Z","end":"2026-06-25T23:10:12.667305Z","steps":["trace[2143129513] 'process raft request' (duration: 325.897823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.667075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.075959Z","time spent":"591.017362ms","remote":"127.0.0.1:36096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:12.666903Z","caller":"traceutil/trace.go:171","msg":"trace[879538528] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"326.185451ms","start":"2026-06-25T23:10:12.340687Z","end":"2026-06-25T23:10:12.666872Z","steps":["trace[879538528] 'process raft request' (duration: 326.089608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.667478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.340913Z","time spent":"326.468469ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:12.667525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.340678Z","time spent":"326.805969ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:12.667082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.145926Z","time spent":"521.055037ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:12.66716Z","caller":"traceutil/trace.go:171","msg":"trace[744206389] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"326.527641ms","start":"2026-06-25T23:10:12.340599Z","end":"2026-06-25T23:10:12.667126Z","steps":["trace[744206389] 'process raft request' (duration: 326.13916ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:12.667755Z","caller":"traceutil/trace.go:171","msg":"trace[1719026056] linearizableReadLoop","detail":"{readStateIndex:25679; appliedIndex:25671; }","duration":"322.891877ms","start":"2026-06-25T23:10:12.344836Z","end":"2026-06-25T23:10:12.667727Z","steps":["trace[1719026056] 'read index received' (duration: 132.586871ms)","trace[1719026056] 'applied index is now lower than readState.Index' (duration: 190.303676ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:12.667798Z","caller":"traceutil/trace.go:171","msg":"trace[1001795549] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"326.759847ms","start":"2026-06-25T23:10:12.341013Z","end":"2026-06-25T23:10:12.667772Z","steps":["trace[1001795549] 'process raft request' (duration: 326.408637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.667909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.341002Z","time spent":"326.851629ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:12.6679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.340578Z","time spent":"327.203119ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:12.667966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.131963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-06-25T23:10:12.667968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.336134Z","time spent":"331.125171ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:12.668018Z","caller":"traceutil/trace.go:171","msg":"trace[1771118757] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24864; }","duration":"323.202475ms","start":"2026-06-25T23:10:12.344799Z","end":"2026-06-25T23:10:12.668001Z","steps":["trace[1771118757] 'agreement among raft nodes before linearized reading' (duration: 323.038321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.668058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.344786Z","time spent":"323.264567ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-25T23:10:12.690181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.159133ms","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-06-25T23:10:12.690248Z","caller":"traceutil/trace.go:171","msg":"trace[1653298432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24865; }","duration":"344.274396ms","start":"2026-06-25T23:10:12.345958Z","end":"2026-06-25T23:10:12.690232Z","steps":["trace[1653298432] 'agreement among raft nodes before linearized reading' (duration: 344.097061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.24174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-25T23:10:12.690297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.345932Z","time spent":"344.353069ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-25T23:10:12.690333Z","caller":"traceutil/trace.go:171","msg":"trace[357146521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24865; }","duration":"340.351084ms","start":"2026-06-25T23:10:12.349965Z","end":"2026-06-25T23:10:12.690316Z","steps":["trace[357146521] 'agreement among raft nodes before linearized reading' (duration: 340.140338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.675811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:12.690451Z","caller":"traceutil/trace.go:171","msg":"trace[2116149437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24865; }","duration":"187.878397ms","start":"2026-06-25T23:10:12.50255Z","end":"2026-06-25T23:10:12.690428Z","steps":["trace[2116149437] 'agreement among raft nodes before linearized reading' (duration: 187.63934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.335133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18bc75b7977a36e5\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-06-25T23:10:12.690576Z","caller":"traceutil/trace.go:171","msg":"trace[2048728464] range","detail":"{range_begin:/registry/events/openstack/container-infra.18bc75b7977a36e5; range_end:; response_count:1; response_revision:24865; }","duration":"206.415845ms","start":"2026-06-25T23:10:12.484141Z","end":"2026-06-25T23:10:12.690557Z","steps":["trace[2048728464] 'agreement among raft nodes before linearized reading' (duration: 206.278381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.736774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T23:10:12.690611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.798342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-25T23:10:12.690678Z","caller":"traceutil/trace.go:171","msg":"trace[467123950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24865; }","duration":"340.782185ms","start":"2026-06-25T23:10:12.349875Z","end":"2026-06-25T23:10:12.690657Z","steps":["trace[467123950] 'agreement among raft nodes before linearized reading' (duration: 340.671652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.349827Z","time spent":"340.891339ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-25T23:10:12.690715Z","caller":"traceutil/trace.go:171","msg":"trace[1818772554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24865; }","duration":"343.954956ms","start":"2026-06-25T23:10:12.346736Z","end":"2026-06-25T23:10:12.690691Z","steps":["trace[1818772554] 'agreement among raft nodes before linearized reading' (duration: 343.705439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.843187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-25T23:10:12.690836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.346722Z","time spent":"344.0942ms","remote":"127.0.0.1:36054","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-06-25T23:10:12.690838Z","caller":"traceutil/trace.go:171","msg":"trace[1454394383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24865; }","duration":"341.241319ms","start":"2026-06-25T23:10:12.349579Z","end":"2026-06-25T23:10:12.69082Z","steps":["trace[1454394383] 'agreement among raft nodes before linearized reading' (duration: 340.675723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:12.690896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.349568Z","time spent":"341.313121ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T23:10:12.690393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.349951Z","time spent":"340.429036ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T23:10:13.028671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.762135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:10:13.028814Z","caller":"traceutil/trace.go:171","msg":"trace[100104319] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"355.428465ms","start":"2026-06-25T23:10:12.673357Z","end":"2026-06-25T23:10:13.028786Z","steps":["trace[100104319] 'process raft request' (duration: 155.398962ms)","trace[100104319] 'compare' (duration: 199.57422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:13.028912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.673341Z","time spent":"355.523567ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:13.211221Z","caller":"traceutil/trace.go:171","msg":"trace[1148552489] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"514.078518ms","start":"2026-06-25T23:10:12.697115Z","end":"2026-06-25T23:10:13.211194Z","steps":["trace[1148552489] 'process raft request' (duration: 513.965244ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:13.211251Z","caller":"traceutil/trace.go:171","msg":"trace[1289885311] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"514.455859ms","start":"2026-06-25T23:10:12.696748Z","end":"2026-06-25T23:10:13.211204Z","steps":["trace[1289885311] 'process raft request' (duration: 441.121988ms)","trace[1289885311] 'compare' (duration: 73.047173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:13.211383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.697108Z","time spent":"514.193111ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:13.211464Z","caller":"traceutil/trace.go:171","msg":"trace[1632600959] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"514.015956ms","start":"2026-06-25T23:10:12.697429Z","end":"2026-06-25T23:10:13.211445Z","steps":["trace[1632600959] 'process raft request' (duration: 513.711547ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:13.211494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.696732Z","time spent":"514.614813ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:13.211564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.697421Z","time spent":"514.097808ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:13.211769Z","caller":"traceutil/trace.go:171","msg":"trace[1287731196] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"514.054176ms","start":"2026-06-25T23:10:12.697703Z","end":"2026-06-25T23:10:13.211757Z","steps":["trace[1287731196] 'process raft request' (duration: 513.670706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:13.211833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.697694Z","time spent":"514.108978ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:13.211972Z","caller":"traceutil/trace.go:171","msg":"trace[863349109] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"513.785019ms","start":"2026-06-25T23:10:12.698174Z","end":"2026-06-25T23:10:13.211959Z","steps":["trace[863349109] 'process raft request' (duration: 513.675466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:13.212049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.698162Z","time spent":"513.852051ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:13.212089Z","caller":"traceutil/trace.go:171","msg":"trace[737731320] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"514.17403ms","start":"2026-06-25T23:10:12.697884Z","end":"2026-06-25T23:10:13.212058Z","steps":["trace[737731320] 'process raft request' (duration: 513.82649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:13.212171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:12.697853Z","time spent":"514.274523ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:23.659082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.833187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e6aa971-7aeb-482d-871e-8b565314eec6\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-25T23:10:23.659178Z","caller":"traceutil/trace.go:171","msg":"trace[1810739873] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e6aa971-7aeb-482d-871e-8b565314eec6; range_end:; response_count:1; response_revision:25017; }","duration":"150.986541ms","start":"2026-06-25T23:10:23.508169Z","end":"2026-06-25T23:10:23.659156Z","steps":["trace[1810739873] 'range keys from in-memory index tree' (duration: 150.621381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:23.659192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.238642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:23.659314Z","caller":"traceutil/trace.go:171","msg":"trace[721960728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25017; }","duration":"156.656104ms","start":"2026-06-25T23:10:23.502631Z","end":"2026-06-25T23:10:23.659287Z","steps":["trace[721960728] 'range keys from in-memory index tree' (duration: 156.126839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:23.949704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.648124ms","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-06-25T23:10:23.949769Z","caller":"traceutil/trace.go:171","msg":"trace[1428391868] 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:25018; }","duration":"118.761846ms","start":"2026-06-25T23:10:23.830989Z","end":"2026-06-25T23:10:23.949751Z","steps":["trace[1428391868] 'range keys from in-memory index tree' (duration: 118.568161ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:24.128941Z","caller":"traceutil/trace.go:171","msg":"trace[2104959744] linearizableReadLoop","detail":"{readStateIndex:25836; appliedIndex:25835; }","duration":"163.622793ms","start":"2026-06-25T23:10:23.965299Z","end":"2026-06-25T23:10:24.128922Z","steps":["trace[2104959744] 'read index received' (duration: 163.395726ms)","trace[2104959744] 'applied index is now lower than readState.Index' (duration: 226.457µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:24.129032Z","caller":"traceutil/trace.go:171","msg":"trace[582741516] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"165.702863ms","start":"2026-06-25T23:10:23.963309Z","end":"2026-06-25T23:10:24.129011Z","steps":["trace[582741516] 'process raft request' (duration: 165.462546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:24.129088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.770768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:24.129115Z","caller":"traceutil/trace.go:171","msg":"trace[1872598831] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25019; }","duration":"163.817249ms","start":"2026-06-25T23:10:23.96529Z","end":"2026-06-25T23:10:24.129107Z","steps":["trace[1872598831] 'agreement among raft nodes before linearized reading' (duration: 163.725586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:24.384881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.075385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:10:24.385026Z","caller":"traceutil/trace.go:171","msg":"trace[2053366130] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"252.990337ms","start":"2026-06-25T23:10:24.131994Z","end":"2026-06-25T23:10:24.384985Z","steps":["trace[2053366130] 'process raft request' (duration: 132.732938ms)","trace[2053366130] 'compare' (duration: 119.956081ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:24.973956Z","caller":"traceutil/trace.go:171","msg":"trace[845368135] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"263.54086ms","start":"2026-06-25T23:10:24.710368Z","end":"2026-06-25T23:10:24.973909Z","steps":["trace[845368135] 'process raft request' (duration: 263.280823ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:25.244229Z","caller":"traceutil/trace.go:171","msg":"trace[521270456] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"531.070364ms","start":"2026-06-25T23:10:24.71313Z","end":"2026-06-25T23:10:25.2442Z","steps":["trace[521270456] 'process raft request' (duration: 530.828557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:25.244401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:24.713111Z","time spent":"531.199927ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:25.244505Z","caller":"traceutil/trace.go:171","msg":"trace[1050426656] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"531.350773ms","start":"2026-06-25T23:10:24.71313Z","end":"2026-06-25T23:10:25.244481Z","steps":["trace[1050426656] 'process raft request' (duration: 531.014562ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:25.244595Z","caller":"traceutil/trace.go:171","msg":"trace[65607559] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"531.352942ms","start":"2026-06-25T23:10:24.713214Z","end":"2026-06-25T23:10:25.244567Z","steps":["trace[65607559] 'process raft request' (duration: 531.184777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:25.244612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:24.713111Z","time spent":"531.447655ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:25.244821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:24.713187Z","time spent":"531.533107ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:25.244859Z","caller":"traceutil/trace.go:171","msg":"trace[1273155210] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"530.487818ms","start":"2026-06-25T23:10:24.714323Z","end":"2026-06-25T23:10:25.244811Z","steps":["trace[1273155210] 'process raft request' (duration: 530.19862ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:25.244918Z","caller":"traceutil/trace.go:171","msg":"trace[1337426680] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"503.04672ms","start":"2026-06-25T23:10:24.741844Z","end":"2026-06-25T23:10:25.244891Z","steps":["trace[1337426680] 'process raft request' (duration: 502.958068ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:25.245081Z","caller":"traceutil/trace.go:171","msg":"trace[319462999] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"530.175308ms","start":"2026-06-25T23:10:24.714892Z","end":"2026-06-25T23:10:25.245067Z","steps":["trace[319462999] 'process raft request' (duration: 529.810648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:25.245095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:24.714313Z","time spent":"530.658192ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:25.245186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:24.741821Z","time spent":"503.281007ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:10:25.245222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:10:24.714886Z","time spent":"530.225449ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:10:25.25544Z","caller":"traceutil/trace.go:171","msg":"trace[808441043] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25838; }","duration":"248.712904ms","start":"2026-06-25T23:10:25.006709Z","end":"2026-06-25T23:10:25.255422Z","steps":["trace[808441043] 'read index received' (duration: 237.142392ms)","trace[808441043] 'applied index is now lower than readState.Index' (duration: 11.569122ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:25.255598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.882829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:10:25.255637Z","caller":"traceutil/trace.go:171","msg":"trace[1481049588] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25027; }","duration":"248.975612ms","start":"2026-06-25T23:10:25.006652Z","end":"2026-06-25T23:10:25.255627Z","steps":["trace[1481049588] 'agreement among raft nodes before linearized reading' (duration: 248.8826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:27.442055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.578433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:10:27.442442Z","caller":"traceutil/trace.go:171","msg":"trace[1238642541] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"192.120961ms","start":"2026-06-25T23:10:27.250302Z","end":"2026-06-25T23:10:27.442422Z","steps":["trace[1238642541] 'process raft request' (duration: 191.999767ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.442565Z","caller":"traceutil/trace.go:171","msg":"trace[1735720654] linearizableReadLoop","detail":"{readStateIndex:25861; appliedIndex:25855; }","duration":"175.268657ms","start":"2026-06-25T23:10:27.267275Z","end":"2026-06-25T23:10:27.442544Z","steps":["trace[1735720654] 'read index received' (duration: 43.426355ms)","trace[1735720654] 'applied index is now lower than readState.Index' (duration: 131.841512ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:27.44263Z","caller":"traceutil/trace.go:171","msg":"trace[1754651436] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"192.375798ms","start":"2026-06-25T23:10:27.250241Z","end":"2026-06-25T23:10:27.442617Z","steps":["trace[1754651436] 'process raft request' (duration: 191.952556ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.442672Z","caller":"traceutil/trace.go:171","msg":"trace[720156637] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"192.333407ms","start":"2026-06-25T23:10:27.250329Z","end":"2026-06-25T23:10:27.442662Z","steps":["trace[720156637] 'process raft request' (duration: 192.018868ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.44265Z","caller":"traceutil/trace.go:171","msg":"trace[838701488] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"192.75009ms","start":"2026-06-25T23:10:27.249847Z","end":"2026-06-25T23:10:27.442597Z","steps":["trace[838701488] 'process raft request' (duration: 82.529528ms)","trace[838701488] 'compare' (duration: 109.347577ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:27.442786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.522845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-25T23:10:27.44281Z","caller":"traceutil/trace.go:171","msg":"trace[1360082269] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25043; }","duration":"175.548705ms","start":"2026-06-25T23:10:27.267252Z","end":"2026-06-25T23:10:27.442801Z","steps":["trace[1360082269] 'agreement among raft nodes before linearized reading' (duration: 175.461493ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.442865Z","caller":"traceutil/trace.go:171","msg":"trace[476691592] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"192.430699ms","start":"2026-06-25T23:10:27.250428Z","end":"2026-06-25T23:10:27.442859Z","steps":["trace[476691592] 'process raft request' (duration: 192.068289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:27.442884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.702419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-25T23:10:27.442919Z","caller":"traceutil/trace.go:171","msg":"trace[1150319106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25043; }","duration":"153.808232ms","start":"2026-06-25T23:10:27.289102Z","end":"2026-06-25T23:10:27.442911Z","steps":["trace[1150319106] 'agreement among raft nodes before linearized reading' (duration: 153.672788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:27.443032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.337351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-25T23:10:27.443035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.347674ms","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-06-25T23:10:27.443053Z","caller":"traceutil/trace.go:171","msg":"trace[1695360338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25043; }","duration":"151.357081ms","start":"2026-06-25T23:10:27.291689Z","end":"2026-06-25T23:10:27.443046Z","steps":["trace[1695360338] 'agreement among raft nodes before linearized reading' (duration: 151.29418ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.443083Z","caller":"traceutil/trace.go:171","msg":"trace[1456137690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25043; }","duration":"149.415716ms","start":"2026-06-25T23:10:27.293656Z","end":"2026-06-25T23:10:27.443071Z","steps":["trace[1456137690] 'agreement among raft nodes before linearized reading' (duration: 149.278612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:27.443081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.961227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-25T23:10:27.44317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.825892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-06-25T23:10:27.443184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.592735ms","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-06-25T23:10:27.443179Z","caller":"traceutil/trace.go:171","msg":"trace[788409602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25043; }","duration":"146.097321ms","start":"2026-06-25T23:10:27.297072Z","end":"2026-06-25T23:10:27.443169Z","steps":["trace[788409602] 'agreement among raft nodes before linearized reading' (duration: 145.850464ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.443206Z","caller":"traceutil/trace.go:171","msg":"trace[1026462300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25043; }","duration":"146.613986ms","start":"2026-06-25T23:10:27.296586Z","end":"2026-06-25T23:10:27.4432Z","steps":["trace[1026462300] 'agreement among raft nodes before linearized reading' (duration: 146.528383ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:27.443208Z","caller":"traceutil/trace.go:171","msg":"trace[1948046302] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25043; }","duration":"130.870894ms","start":"2026-06-25T23:10:27.312325Z","end":"2026-06-25T23:10:27.443196Z","steps":["trace[1948046302] 'agreement among raft nodes before linearized reading' (duration: 130.781521ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:30.944577Z","caller":"traceutil/trace.go:171","msg":"trace[2051626106] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"101.243884ms","start":"2026-06-25T23:10:30.843301Z","end":"2026-06-25T23:10:30.944545Z","steps":["trace[2051626106] 'process raft request' (duration: 101.030908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:31.250673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.639591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:10:31.250828Z","caller":"traceutil/trace.go:171","msg":"trace[534185475] linearizableReadLoop","detail":"{readStateIndex:25902; appliedIndex:25901; }","duration":"243.911237ms","start":"2026-06-25T23:10:31.006889Z","end":"2026-06-25T23:10:31.2508Z","steps":["trace[534185475] 'read index received' (duration: 70.015209ms)","trace[534185475] 'applied index is now lower than readState.Index' (duration: 173.893668ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:31.250982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.088192ms","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-06-25T23:10:31.250966Z","caller":"traceutil/trace.go:171","msg":"trace[1035429268] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"252.468462ms","start":"2026-06-25T23:10:30.998458Z","end":"2026-06-25T23:10:31.250927Z","steps":["trace[1035429268] 'process raft request' (duration: 78.43719ms)","trace[1035429268] 'compare' (duration: 173.067664ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:31.251029Z","caller":"traceutil/trace.go:171","msg":"trace[1932507155] 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:25083; }","duration":"244.186025ms","start":"2026-06-25T23:10:31.006826Z","end":"2026-06-25T23:10:31.251012Z","steps":["trace[1932507155] 'agreement among raft nodes before linearized reading' (duration: 244.095982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:31.251611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.037859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-06-25T23:10:31.251694Z","caller":"traceutil/trace.go:171","msg":"trace[2089990751] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25083; }","duration":"223.181143ms","start":"2026-06-25T23:10:31.028493Z","end":"2026-06-25T23:10:31.251674Z","steps":["trace[2089990751] 'agreement among raft nodes before linearized reading' (duration: 222.893785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:31.251652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.642588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-25T23:10:31.251779Z","caller":"traceutil/trace.go:171","msg":"trace[1235183363] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25083; }","duration":"160.830863ms","start":"2026-06-25T23:10:31.090922Z","end":"2026-06-25T23:10:31.251753Z","steps":["trace[1235183363] 'agreement among raft nodes before linearized reading' (duration: 160.508114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:35.163496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.344184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-25T23:10:55.553868Z","caller":"traceutil/trace.go:171","msg":"trace[1621656874] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"133.983804ms","start":"2026-06-25T23:10:55.419858Z","end":"2026-06-25T23:10:55.553842Z","steps":["trace[1621656874] 'process raft request' (duration: 133.775568ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.604824Z","caller":"traceutil/trace.go:171","msg":"trace[820596085] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"176.644117ms","start":"2026-06-25T23:10:55.428147Z","end":"2026-06-25T23:10:55.604791Z","steps":["trace[820596085] 'process raft request' (duration: 176.347339ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.604828Z","caller":"traceutil/trace.go:171","msg":"trace[1145732427] linearizableReadLoop","detail":"{readStateIndex:26114; appliedIndex:26112; }","duration":"103.466459ms","start":"2026-06-25T23:10:55.501301Z","end":"2026-06-25T23:10:55.604768Z","steps":["trace[1145732427] 'read index received' (duration: 52.333282ms)","trace[1145732427] 'applied index is now lower than readState.Index' (duration: 51.131827ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:55.605021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.689735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:55.605114Z","caller":"traceutil/trace.go:171","msg":"trace[329980015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25290; }","duration":"103.834609ms","start":"2026-06-25T23:10:55.501255Z","end":"2026-06-25T23:10:55.60509Z","steps":["trace[329980015] 'agreement among raft nodes before linearized reading' (duration: 103.670494ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.747284Z","caller":"traceutil/trace.go:171","msg":"trace[1895494274] linearizableReadLoop","detail":"{readStateIndex:26119; appliedIndex:26115; }","duration":"142.345073ms","start":"2026-06-25T23:10:55.604922Z","end":"2026-06-25T23:10:55.747267Z","steps":["trace[1895494274] 'read index received' (duration: 119.373535ms)","trace[1895494274] 'applied index is now lower than readState.Index' (duration: 22.970658ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:55.747288Z","caller":"traceutil/trace.go:171","msg":"trace[1691292848] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"192.853902ms","start":"2026-06-25T23:10:55.554404Z","end":"2026-06-25T23:10:55.747258Z","steps":["trace[1691292848] 'process raft request' (duration: 169.922564ms)","trace[1691292848] 'compare' (duration: 22.413703ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:10:55.747374Z","caller":"traceutil/trace.go:171","msg":"trace[1410705115] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"192.77678ms","start":"2026-06-25T23:10:55.55459Z","end":"2026-06-25T23:10:55.747367Z","steps":["trace[1410705115] 'process raft request' (duration: 192.535243ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.747513Z","caller":"traceutil/trace.go:171","msg":"trace[429713961] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"192.906244ms","start":"2026-06-25T23:10:55.554601Z","end":"2026-06-25T23:10:55.747507Z","steps":["trace[429713961] 'process raft request' (duration: 192.602686ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.747564Z","caller":"traceutil/trace.go:171","msg":"trace[549283099] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"192.501483ms","start":"2026-06-25T23:10:55.555057Z","end":"2026-06-25T23:10:55.747559Z","steps":["trace[549283099] 'process raft request' (duration: 192.176353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.747699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.104854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-25T23:10:55.747721Z","caller":"traceutil/trace.go:171","msg":"trace[1301691809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25294; }","duration":"151.160116ms","start":"2026-06-25T23:10:55.596555Z","end":"2026-06-25T23:10:55.747715Z","steps":["trace[1301691809] 'agreement among raft nodes before linearized reading' (duration: 151.058363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.747799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.226001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-25T23:10:55.747815Z","caller":"traceutil/trace.go:171","msg":"trace[695037467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25294; }","duration":"171.268143ms","start":"2026-06-25T23:10:55.576542Z","end":"2026-06-25T23:10:55.747811Z","steps":["trace[695037467] 'agreement among raft nodes before linearized reading' (duration: 171.223901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.747918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.937263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-25T23:10:55.74794Z","caller":"traceutil/trace.go:171","msg":"trace[1781058484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25294; }","duration":"140.979104ms","start":"2026-06-25T23:10:55.606953Z","end":"2026-06-25T23:10:55.747932Z","steps":["trace[1781058484] 'agreement among raft nodes before linearized reading' (duration: 140.931923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.74802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.330869ms","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-06-25T23:10:55.748035Z","caller":"traceutil/trace.go:171","msg":"trace[1262018424] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25294; }","duration":"100.39119ms","start":"2026-06-25T23:10:55.64764Z","end":"2026-06-25T23:10:55.748031Z","steps":["trace[1262018424] 'agreement among raft nodes before linearized reading' (duration: 100.352819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.748122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.505481ms","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-06-25T23:10:55.748137Z","caller":"traceutil/trace.go:171","msg":"trace[1994297239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25294; }","duration":"139.542882ms","start":"2026-06-25T23:10:55.60859Z","end":"2026-06-25T23:10:55.748133Z","steps":["trace[1994297239] 'agreement among raft nodes before linearized reading' (duration: 139.501211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.748196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.824862ms","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-06-25T23:10:55.74821Z","caller":"traceutil/trace.go:171","msg":"trace[1906018745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25294; }","duration":"139.849012ms","start":"2026-06-25T23:10:55.608357Z","end":"2026-06-25T23:10:55.748206Z","steps":["trace[1906018745] 'agreement among raft nodes before linearized reading' (duration: 139.812981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:10:55.748256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.21075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:55.74827Z","caller":"traceutil/trace.go:171","msg":"trace[203851761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25294; }","duration":"141.258402ms","start":"2026-06-25T23:10:55.607008Z","end":"2026-06-25T23:10:55.748267Z","steps":["trace[203851761] 'agreement among raft nodes before linearized reading' (duration: 141.233191ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.965707Z","caller":"traceutil/trace.go:171","msg":"trace[612589154] linearizableReadLoop","detail":"{readStateIndex:26125; appliedIndex:26120; }","duration":"146.367438ms","start":"2026-06-25T23:10:55.819316Z","end":"2026-06-25T23:10:55.965684Z","steps":["trace[612589154] 'read index received' (duration: 145.978797ms)","trace[612589154] 'applied index is now lower than readState.Index' (duration: 387.551µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:10:55.965948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.588684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:10:55.966029Z","caller":"traceutil/trace.go:171","msg":"trace[1504530816] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25300; }","duration":"146.77479ms","start":"2026-06-25T23:10:55.819235Z","end":"2026-06-25T23:10:55.96601Z","steps":["trace[1504530816] 'agreement among raft nodes before linearized reading' (duration: 146.588815ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.966451Z","caller":"traceutil/trace.go:171","msg":"trace[338107041] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"212.201787ms","start":"2026-06-25T23:10:55.754236Z","end":"2026-06-25T23:10:55.966438Z","steps":["trace[338107041] 'process raft request' (duration: 211.406655ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.966558Z","caller":"traceutil/trace.go:171","msg":"trace[1381233917] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"213.514224ms","start":"2026-06-25T23:10:55.75302Z","end":"2026-06-25T23:10:55.966534Z","steps":["trace[1381233917] 'process raft request' (duration: 212.440314ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.966604Z","caller":"traceutil/trace.go:171","msg":"trace[901828075] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"213.662699ms","start":"2026-06-25T23:10:55.752904Z","end":"2026-06-25T23:10:55.966566Z","steps":["trace[901828075] 'process raft request' (duration: 212.400083ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.966771Z","caller":"traceutil/trace.go:171","msg":"trace[489673262] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"213.438732ms","start":"2026-06-25T23:10:55.753321Z","end":"2026-06-25T23:10:55.96676Z","steps":["trace[489673262] 'process raft request' (duration: 212.184537ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:55.966483Z","caller":"traceutil/trace.go:171","msg":"trace[1924754356] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"212.394472ms","start":"2026-06-25T23:10:55.754051Z","end":"2026-06-25T23:10:55.966446Z","steps":["trace[1924754356] 'process raft request' (duration: 211.545978ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:58.598818Z","caller":"traceutil/trace.go:171","msg":"trace[1755407105] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"181.541348ms","start":"2026-06-25T23:10:58.417245Z","end":"2026-06-25T23:10:58.598787Z","steps":["trace[1755407105] 'process raft request' (duration: 181.339052ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:10:59.998786Z","caller":"traceutil/trace.go:171","msg":"trace[1907822399] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"161.956496ms","start":"2026-06-25T23:10:59.836799Z","end":"2026-06-25T23:10:59.998755Z","steps":["trace[1907822399] 'process raft request' (duration: 78.646496ms)","trace[1907822399] 'compare' (duration: 82.429674ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:00.108983Z","caller":"traceutil/trace.go:171","msg":"trace[1856678308] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"105.6184ms","start":"2026-06-25T23:11:00.003335Z","end":"2026-06-25T23:11:00.108954Z","steps":["trace[1856678308] 'process raft request' (duration: 105.525517ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:00.109Z","caller":"traceutil/trace.go:171","msg":"trace[1867614108] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"105.611339ms","start":"2026-06-25T23:11:00.003354Z","end":"2026-06-25T23:11:00.108965Z","steps":["trace[1867614108] 'process raft request' (duration: 105.311821ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:00.109317Z","caller":"traceutil/trace.go:171","msg":"trace[366226113] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"105.593519ms","start":"2026-06-25T23:11:00.003707Z","end":"2026-06-25T23:11:00.1093Z","steps":["trace[366226113] 'process raft request' (duration: 105.470005ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:00.109361Z","caller":"traceutil/trace.go:171","msg":"trace[1229511665] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"105.885898ms","start":"2026-06-25T23:11:00.003419Z","end":"2026-06-25T23:11:00.109305Z","steps":["trace[1229511665] 'process raft request' (duration: 105.490297ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:02.310618Z","caller":"traceutil/trace.go:171","msg":"trace[823067807] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"192.342628ms","start":"2026-06-25T23:11:02.118241Z","end":"2026-06-25T23:11:02.310584Z","steps":["trace[823067807] 'process raft request' (duration: 169.800341ms)","trace[823067807] 'compare' (duration: 21.676042ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:02.619747Z","caller":"traceutil/trace.go:171","msg":"trace[1613345431] linearizableReadLoop","detail":"{readStateIndex:26169; appliedIndex:26166; }","duration":"239.012036ms","start":"2026-06-25T23:11:02.380703Z","end":"2026-06-25T23:11:02.619715Z","steps":["trace[1613345431] 'read index received' (duration: 182.813794ms)","trace[1613345431] 'applied index is now lower than readState.Index' (duration: 56.196452ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:02.620014Z","caller":"traceutil/trace.go:171","msg":"trace[215878897] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"500.557108ms","start":"2026-06-25T23:11:02.119417Z","end":"2026-06-25T23:11:02.619974Z","steps":["trace[215878897] 'process raft request' (duration: 500.226629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:02.620103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.377197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:02.620197Z","caller":"traceutil/trace.go:171","msg":"trace[1865561881] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25343; }","duration":"239.540131ms","start":"2026-06-25T23:11:02.380633Z","end":"2026-06-25T23:11:02.620173Z","steps":["trace[1865561881] 'agreement among raft nodes before linearized reading' (duration: 239.366166ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:02.620027Z","caller":"traceutil/trace.go:171","msg":"trace[1859520771] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"501.720172ms","start":"2026-06-25T23:11:02.118257Z","end":"2026-06-25T23:11:02.619977Z","steps":["trace[1859520771] 'process raft request' (duration: 445.242432ms)","trace[1859520771] 'compare' (duration: 55.925745ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:02.620235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:02.119392Z","time spent":"500.726273ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:11:02.620397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:02.118239Z","time spent":"502.038431ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:02.620673Z","caller":"traceutil/trace.go:171","msg":"trace[1632341009] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"502.242128ms","start":"2026-06-25T23:11:02.118396Z","end":"2026-06-25T23:11:02.620639Z","steps":["trace[1632341009] 'process raft request' (duration: 501.181837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:02.621061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:02.118361Z","time spent":"502.375932ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:11:02.621025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.541481ms","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":"info","ts":"2026-06-25T23:11:02.621159Z","caller":"traceutil/trace.go:171","msg":"trace[480344542] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25343; }","duration":"141.495309ms","start":"2026-06-25T23:11:02.479639Z","end":"2026-06-25T23:11:02.621135Z","steps":["trace[480344542] 'agreement among raft nodes before linearized reading' (duration: 140.486789ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:18.400342Z","caller":"traceutil/trace.go:171","msg":"trace[1024392301] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"216.84116ms","start":"2026-06-25T23:11:18.183468Z","end":"2026-06-25T23:11:18.400309Z","steps":["trace[1024392301] 'process raft request' (duration: 122.449653ms)","trace[1024392301] 'compare' (duration: 93.952454ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:18.744071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.252002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:18.744266Z","caller":"traceutil/trace.go:171","msg":"trace[1338959982] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"326.186836ms","start":"2026-06-25T23:11:18.418037Z","end":"2026-06-25T23:11:18.744224Z","steps":["trace[1338959982] 'process raft request' (duration: 161.651536ms)","trace[1338959982] 'compare' (duration: 164.046647ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:18.744432Z","caller":"traceutil/trace.go:171","msg":"trace[1413373919] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"119.156917ms","start":"2026-06-25T23:11:18.625205Z","end":"2026-06-25T23:11:18.744362Z","steps":["trace[1413373919] 'process raft request' (duration: 119.040944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:18.744467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:18.418002Z","time spent":"326.339641ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:11:18.744591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.554699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:18.74465Z","caller":"traceutil/trace.go:171","msg":"trace[2018776238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25468; }","duration":"241.650232ms","start":"2026-06-25T23:11:18.502983Z","end":"2026-06-25T23:11:18.744633Z","steps":["trace[2018776238] 'agreement among raft nodes before linearized reading' (duration: 241.517758ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:18.744442Z","caller":"traceutil/trace.go:171","msg":"trace[1676604734] linearizableReadLoop","detail":"{readStateIndex:26296; appliedIndex:26295; }","duration":"241.308272ms","start":"2026-06-25T23:11:18.503035Z","end":"2026-06-25T23:11:18.744344Z","steps":["trace[1676604734] 'read index received' (duration: 76.639738ms)","trace[1676604734] 'applied index is now lower than readState.Index' (duration: 164.665694ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:18.948822Z","caller":"traceutil/trace.go:171","msg":"trace[884695245] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"198.911416ms","start":"2026-06-25T23:11:18.749877Z","end":"2026-06-25T23:11:18.948789Z","steps":["trace[884695245] 'process raft request' (duration: 121.522587ms)","trace[884695245] 'compare' (duration: 77.138472ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:19.005062Z","caller":"traceutil/trace.go:171","msg":"trace[1460793900] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"254.595543ms","start":"2026-06-25T23:11:18.750436Z","end":"2026-06-25T23:11:19.005032Z","steps":["trace[1460793900] 'process raft request' (duration: 254.497571ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:19.005616Z","caller":"traceutil/trace.go:171","msg":"trace[1157712930] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"255.267883ms","start":"2026-06-25T23:11:18.750332Z","end":"2026-06-25T23:11:19.0056Z","steps":["trace[1157712930] 'process raft request' (duration: 254.432979ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:29.178672Z","caller":"traceutil/trace.go:171","msg":"trace[1302677151] linearizableReadLoop","detail":"{readStateIndex:26374; appliedIndex:26373; }","duration":"111.855421ms","start":"2026-06-25T23:11:29.066787Z","end":"2026-06-25T23:11:29.178643Z","steps":["trace[1302677151] 'read index received' (duration: 111.531532ms)","trace[1302677151] 'applied index is now lower than readState.Index' (duration: 322.259µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:29.178821Z","caller":"traceutil/trace.go:171","msg":"trace[779887722] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"175.635576ms","start":"2026-06-25T23:11:29.003148Z","end":"2026-06-25T23:11:29.178784Z","steps":["trace[779887722] 'process raft request' (duration: 175.147503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:29.178933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.126779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-06-25T23:11:29.178962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.049817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-25T23:11:29.179026Z","caller":"traceutil/trace.go:171","msg":"trace[1371520468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25543; }","duration":"112.141799ms","start":"2026-06-25T23:11:29.066869Z","end":"2026-06-25T23:11:29.179011Z","steps":["trace[1371520468] 'agreement among raft nodes before linearized reading' (duration: 111.982534ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:29.178983Z","caller":"traceutil/trace.go:171","msg":"trace[354282790] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25543; }","duration":"112.239592ms","start":"2026-06-25T23:11:29.066728Z","end":"2026-06-25T23:11:29.178967Z","steps":["trace[354282790] 'agreement among raft nodes before linearized reading' (duration: 112.069137ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:46.256447Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000678e.wal"} {"level":"info","ts":"2026-06-25T23:12:16.442238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23121} {"level":"info","ts":"2026-06-25T23:12:16.58405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23121,"took":"136.199899ms","hash":1245647210,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":35848192,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-25T23:12:16.584107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245647210,"revision":23121,"compact-revision":20081} {"level":"info","ts":"2026-06-25T23:17:16.447894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26067} {"level":"info","ts":"2026-06-25T23:17:16.590603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26067,"took":"138.41771ms","hash":4016557781,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":34697216,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-25T23:17:16.590666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4016557781,"revision":26067,"compact-revision":23121} {"level":"info","ts":"2026-06-25T23:17:59.324209Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T23:17:59.330012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-25T23:17:59.330127Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-25T23:22:16.454647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28762} {"level":"info","ts":"2026-06-25T23:22:16.60109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28762,"took":"141.016238ms","hash":461735547,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":30466048,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-25T23:22:16.60117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":461735547,"revision":28762,"compact-revision":26067} {"level":"info","ts":"2026-06-25T23:27:16.463822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30983} {"level":"info","ts":"2026-06-25T23:27:16.57552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30983,"took":"107.440999ms","hash":1502566161,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-25T23:27:16.575597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1502566161,"revision":30983,"compact-revision":28762} {"level":"info","ts":"2026-06-25T23:32:16.480527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33246} {"level":"info","ts":"2026-06-25T23:32:16.627918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33246,"took":"143.550296ms","hash":4113477642,"current-db-size-bytes":59600896,"current-db-size":"60 MB","current-db-size-in-use-bytes":31924224,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T23:32:16.627969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4113477642,"revision":33246,"compact-revision":30983} {"level":"info","ts":"2026-06-25T23:37:16.488316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35538} {"level":"info","ts":"2026-06-25T23:37:16.650141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35538,"took":"157.385575ms","hash":2830279225,"current-db-size-bytes":62619648,"current-db-size":"63 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T23:37:16.650392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2830279225,"revision":35538,"compact-revision":33246} {"level":"info","ts":"2026-06-25T23:39:29.915898Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T23:39:29.920703Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-25T23:39:29.920809Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-25T23:42:16.498471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37767} {"level":"info","ts":"2026-06-25T23:42:16.619505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37767,"took":"116.967467ms","hash":2488866449,"current-db-size-bytes":62619648,"current-db-size":"63 MB","current-db-size-in-use-bytes":31612928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T23:42:16.619565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2488866449,"revision":37767,"compact-revision":35538}