{"level":"warn","ts":"2026-06-25T22:23:29.378815Z","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:23:29.379003Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228: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.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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:23:29.379129Z","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:23:29.379147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-06-25T22:23:29.379172Z","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:23:29.380287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-06-25T22:23:29.380409Z","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.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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:23:29.383539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.764264ms"} {"level":"info","ts":"2026-06-25T22:23:29.38898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-06-25T22:23:29.389518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-06-25T22:23:29.389607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-06-25T22:23:29.389675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-25T22:23:29.389693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-06-25T22:23:29.389775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-06-25T22:23:29.394833Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-25T22:23:29.396179Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-25T22:23:29.397272Z","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:23:29.400524Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-25T22:23:29.400739Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-25T22:23:29.400786Z","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:23:29.400949Z","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:23:29.400965Z","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:23:29.402333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-06-25T22:23:29.403471Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-06-25T22:23:29.405368Z","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:23:29.405559Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-25T22:23:29.405594Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-25T22:23:29.405668Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-06-25T22:23:29.405682Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-06-25T22:23:29.79041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-06-25T22:23:29.790471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-06-25T22:23:29.790495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-06-25T22:23:29.790516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-06-25T22:23:29.790532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-06-25T22:23:29.790549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-06-25T22:23:29.790564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-06-25T22:23:29.791476Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:23:29.792347Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-06-25T22:23:29.792471Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:23:29.792539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:23:29.792807Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-25T22:23:29.792899Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-25T22:23:29.793094Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:23:29.79322Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:23:29.793289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:23:29.794821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-06-25T22:23:29.795486Z","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:23:51.653988Z","caller":"traceutil/trace.go:171","msg":"trace[1531722104] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"106.064622ms","start":"2026-06-25T22:23:51.547892Z","end":"2026-06-25T22:23:51.653957Z","steps":["trace[1531722104] 'read index received' (duration: 103.593324ms)","trace[1531722104] 'applied index is now lower than readState.Index' (duration: 2.469748ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:51.654041Z","caller":"traceutil/trace.go:171","msg":"trace[1630464675] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"168.919833ms","start":"2026-06-25T22:23:51.485085Z","end":"2026-06-25T22:23:51.654005Z","steps":["trace[1630464675] 'process raft request' (duration: 166.386074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:51.65418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.266407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:23:51.654273Z","caller":"traceutil/trace.go:171","msg":"trace[1088783371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"106.418199ms","start":"2026-06-25T22:23:51.547838Z","end":"2026-06-25T22:23:51.654256Z","steps":["trace[1088783371] 'agreement among raft nodes before linearized reading' (duration: 106.264586ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:12.71148Z","caller":"traceutil/trace.go:171","msg":"trace[625325966] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"180.42745ms","start":"2026-06-25T22:24:12.531025Z","end":"2026-06-25T22:24:12.711452Z","steps":["trace[625325966] 'process raft request' (duration: 180.273857ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:13.059056Z","caller":"traceutil/trace.go:171","msg":"trace[237163854] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"157.471167ms","start":"2026-06-25T22:24:12.901559Z","end":"2026-06-25T22:24:13.05903Z","steps":["trace[237163854] 'read index received' (duration: 73.137553ms)","trace[237163854] 'applied index is now lower than readState.Index' (duration: 84.332374ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:24:13.05912Z","caller":"traceutil/trace.go:171","msg":"trace[287239092] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"167.508914ms","start":"2026-06-25T22:24:12.891563Z","end":"2026-06-25T22:24:13.059071Z","steps":["trace[287239092] 'process raft request' (duration: 83.190061ms)","trace[287239092] 'compare' (duration: 84.087309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:24:13.059224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.632631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-25T22:24:13.05933Z","caller":"traceutil/trace.go:171","msg":"trace[852363390] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:636; }","duration":"157.758474ms","start":"2026-06-25T22:24:12.901527Z","end":"2026-06-25T22:24:13.059285Z","steps":["trace[852363390] 'agreement among raft nodes before linearized reading' (duration: 157.584779ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:13.605918Z","caller":"traceutil/trace.go:171","msg":"trace[451255199] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"172.022485ms","start":"2026-06-25T22:24:13.433871Z","end":"2026-06-25T22:24:13.605894Z","steps":["trace[451255199] 'read index received' (duration: 171.932313ms)","trace[451255199] 'applied index is now lower than readState.Index' (duration: 88.922µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:24:13.606069Z","caller":"traceutil/trace.go:171","msg":"trace[572415376] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"444.528262ms","start":"2026-06-25T22:24:13.161521Z","end":"2026-06-25T22:24:13.606049Z","steps":["trace[572415376] 'process raft request' (duration: 444.236296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:13.606268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.373562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-25T22:24:13.606419Z","caller":"traceutil/trace.go:171","msg":"trace[1737284901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"172.507694ms","start":"2026-06-25T22:24:13.433813Z","end":"2026-06-25T22:24:13.606321Z","steps":["trace[1737284901] 'agreement among raft nodes before linearized reading' (duration: 172.200018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:13.606742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:24:13.161478Z","time spent":"444.634654ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:24:13.623847Z","caller":"traceutil/trace.go:171","msg":"trace[762081176] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"161.720121ms","start":"2026-06-25T22:24:13.462092Z","end":"2026-06-25T22:24:13.623812Z","steps":["trace[762081176] 'process raft request' (duration: 156.703022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:28.111452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.838927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-25T22:24:28.151566Z","caller":"traceutil/trace.go:171","msg":"trace[288626155] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"172.718309ms","start":"2026-06-25T22:24:27.97882Z","end":"2026-06-25T22:24:28.151539Z","steps":["trace[288626155] 'process raft request' (duration: 172.393553ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:55.998628Z","caller":"traceutil/trace.go:171","msg":"trace[1249021159] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"123.627337ms","start":"2026-06-25T22:24:55.874972Z","end":"2026-06-25T22:24:55.9986Z","steps":["trace[1249021159] 'read index received' (duration: 123.369851ms)","trace[1249021159] 'applied index is now lower than readState.Index' (duration: 256.436µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:24:55.998669Z","caller":"traceutil/trace.go:171","msg":"trace[1053457764] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"124.30134ms","start":"2026-06-25T22:24:55.874345Z","end":"2026-06-25T22:24:55.998647Z","steps":["trace[1053457764] 'process raft request' (duration: 124.087374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:55.998952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.922862ms","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:24:55.999017Z","caller":"traceutil/trace.go:171","msg":"trace[207634836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"124.047015ms","start":"2026-06-25T22:24:55.874952Z","end":"2026-06-25T22:24:55.998999Z","steps":["trace[207634836] 'agreement among raft nodes before linearized reading' (duration: 123.81377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:55.998954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.528384ms","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:24:55.99912Z","caller":"traceutil/trace.go:171","msg":"trace[505203267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1188; }","duration":"120.726848ms","start":"2026-06-25T22:24:55.878379Z","end":"2026-06-25T22:24:55.999105Z","steps":["trace[505203267] 'agreement among raft nodes before linearized reading' (duration: 120.448882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.380141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.469771ms","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:25:41.380248Z","caller":"traceutil/trace.go:171","msg":"trace[748717118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1848; }","duration":"144.636295ms","start":"2026-06-25T22:25:41.235591Z","end":"2026-06-25T22:25:41.380228Z","steps":["trace[748717118] 'range keys from in-memory index tree' (duration: 144.217696ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:20.548243Z","caller":"traceutil/trace.go:171","msg":"trace[92933959] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"118.853763ms","start":"2026-06-25T22:26:20.429354Z","end":"2026-06-25T22:26:20.548207Z","steps":["trace[92933959] 'process raft request' (duration: 110.121581ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:20.571916Z","caller":"traceutil/trace.go:171","msg":"trace[325475747] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"110.664702ms","start":"2026-06-25T22:26:20.461218Z","end":"2026-06-25T22:26:20.571883Z","steps":["trace[325475747] 'read index received' (duration: 78.27629ms)","trace[325475747] 'applied index is now lower than readState.Index' (duration: 32.387152ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:26:20.572006Z","caller":"traceutil/trace.go:171","msg":"trace[1818842440] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"125.777582ms","start":"2026-06-25T22:26:20.446182Z","end":"2026-06-25T22:26:20.571959Z","steps":["trace[1818842440] 'process raft request' (duration: 125.557426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:20.572152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.903157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:26:20.572214Z","caller":"traceutil/trace.go:171","msg":"trace[1922658055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2120; }","duration":"111.021369ms","start":"2026-06-25T22:26:20.461175Z","end":"2026-06-25T22:26:20.572196Z","steps":["trace[1922658055] 'agreement among raft nodes before linearized reading' (duration: 110.816475ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:27:55.619209Z","caller":"traceutil/trace.go:171","msg":"trace[308018083] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"137.496672ms","start":"2026-06-25T22:27:55.481677Z","end":"2026-06-25T22:27:55.619174Z","steps":["trace[308018083] 'process raft request' (duration: 121.501079ms)","trace[308018083] 'compare' (duration: 15.576445ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:27:56.068376Z","caller":"traceutil/trace.go:171","msg":"trace[1605558029] linearizableReadLoop","detail":"{readStateIndex:2938; appliedIndex:2937; }","duration":"143.957303ms","start":"2026-06-25T22:27:55.924392Z","end":"2026-06-25T22:27:56.068349Z","steps":["trace[1605558029] 'read index received' (duration: 143.823081ms)","trace[1605558029] 'applied index is now lower than readState.Index' (duration: 133.322µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:27:56.068715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.30637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-25T22:27:56.068787Z","caller":"traceutil/trace.go:171","msg":"trace[1478529772] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2827; }","duration":"144.435603ms","start":"2026-06-25T22:27:55.924336Z","end":"2026-06-25T22:27:56.068771Z","steps":["trace[1478529772] 'agreement among raft nodes before linearized reading' (duration: 144.227749ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:27:56.068766Z","caller":"traceutil/trace.go:171","msg":"trace[997579012] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"154.17858ms","start":"2026-06-25T22:27:55.914551Z","end":"2026-06-25T22:27:56.06873Z","steps":["trace[997579012] 'process raft request' (duration: 153.604658ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:31:54.541849Z","caller":"traceutil/trace.go:171","msg":"trace[79182913] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5075; }","duration":"135.443976ms","start":"2026-06-25T22:31:54.406379Z","end":"2026-06-25T22:31:54.541823Z","steps":["trace[79182913] 'read index received' (duration: 31.816715ms)","trace[79182913] 'applied index is now lower than readState.Index' (duration: 103.626031ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:31:54.541978Z","caller":"traceutil/trace.go:171","msg":"trace[612068144] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"148.878338ms","start":"2026-06-25T22:31:54.393093Z","end":"2026-06-25T22:31:54.541972Z","steps":["trace[612068144] 'process raft request' (duration: 148.529341ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:31:54.541956Z","caller":"traceutil/trace.go:171","msg":"trace[79300855] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"115.794097ms","start":"2026-06-25T22:31:54.42613Z","end":"2026-06-25T22:31:54.541924Z","steps":["trace[79300855] 'process raft request' (duration: 115.648804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:31:54.542114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.675561ms","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:7"} {"level":"info","ts":"2026-06-25T22:31:54.542243Z","caller":"traceutil/trace.go:171","msg":"trace[793664432] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4889; }","duration":"135.868605ms","start":"2026-06-25T22:31:54.406349Z","end":"2026-06-25T22:31:54.542217Z","steps":["trace[793664432] 'agreement among raft nodes before linearized reading' (duration: 135.623519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:31:54.542607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.169161ms","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:31:54.542711Z","caller":"traceutil/trace.go:171","msg":"trace[1568431389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4890; }","duration":"132.298213ms","start":"2026-06-25T22:31:54.410393Z","end":"2026-06-25T22:31:54.542691Z","steps":["trace[1568431389] 'agreement among raft nodes before linearized reading' (duration: 132.039878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:31:54.542824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.969585ms","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:31:54.542963Z","caller":"traceutil/trace.go:171","msg":"trace[250238473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4890; }","duration":"131.17467ms","start":"2026-06-25T22:31:54.411758Z","end":"2026-06-25T22:31:54.542932Z","steps":["trace[250238473] 'agreement among raft nodes before linearized reading' (duration: 130.768712ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:31:56.813301Z","caller":"traceutil/trace.go:171","msg":"trace[1046793022] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5205; }","duration":"247.273854ms","start":"2026-06-25T22:31:56.565976Z","end":"2026-06-25T22:31:56.81325Z","steps":["trace[1046793022] 'read index received' (duration: 147.92603ms)","trace[1046793022] 'applied index is now lower than readState.Index' (duration: 99.345874ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:31:56.813525Z","caller":"traceutil/trace.go:171","msg":"trace[90608041] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"247.914297ms","start":"2026-06-25T22:31:56.565568Z","end":"2026-06-25T22:31:56.813483Z","steps":["trace[90608041] 'process raft request' (duration: 148.358779ms)","trace[90608041] 'compare' (duration: 98.778302ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:31:56.813746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.715683ms","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-25T22:31:56.813817Z","caller":"traceutil/trace.go:171","msg":"trace[695655074] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5015; }","duration":"247.874826ms","start":"2026-06-25T22:31:56.56592Z","end":"2026-06-25T22:31:56.813795Z","steps":["trace[695655074] 'agreement among raft nodes before linearized reading' (duration: 247.57394ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:31:56.818823Z","caller":"traceutil/trace.go:171","msg":"trace[2053314397] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"252.172234ms","start":"2026-06-25T22:31:56.566623Z","end":"2026-06-25T22:31:56.818795Z","steps":["trace[2053314397] 'process raft request' (duration: 252.00117ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:31:58.676809Z","caller":"traceutil/trace.go:171","msg":"trace[2098932339] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"106.717654ms","start":"2026-06-25T22:31:58.570053Z","end":"2026-06-25T22:31:58.676771Z","steps":["trace[2098932339] 'process raft request' (duration: 74.142984ms)","trace[2098932339] 'compare' (duration: 32.294495ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:32:11.041906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.064688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:32:11.042174Z","caller":"traceutil/trace.go:171","msg":"trace[1496521883] linearizableReadLoop","detail":"{readStateIndex:5485; appliedIndex:5484; }","duration":"156.262058ms","start":"2026-06-25T22:32:10.885874Z","end":"2026-06-25T22:32:11.042136Z","steps":["trace[1496521883] 'read index received' (duration: 57.141µs)","trace[1496521883] 'applied index is now lower than readState.Index' (duration: 156.202137ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:32:11.042243Z","caller":"traceutil/trace.go:171","msg":"trace[1064325587] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"292.125834ms","start":"2026-06-25T22:32:10.750049Z","end":"2026-06-25T22:32:11.042175Z","steps":["trace[1064325587] 'process raft request' (duration: 121.644808ms)","trace[1064325587] 'compare' (duration: 169.927905ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:32:11.042518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.643984ms","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:32:11.042613Z","caller":"traceutil/trace.go:171","msg":"trace[1207611021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5285; }","duration":"148.799897ms","start":"2026-06-25T22:32:10.89379Z","end":"2026-06-25T22:32:11.04259Z","steps":["trace[1207611021] 'agreement among raft nodes before linearized reading' (duration: 148.567983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:32:11.042505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.609826ms","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:32:11.042892Z","caller":"traceutil/trace.go:171","msg":"trace[1748103020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5285; }","duration":"157.015124ms","start":"2026-06-25T22:32:10.8858Z","end":"2026-06-25T22:32:11.042815Z","steps":["trace[1748103020] 'agreement among raft nodes before linearized reading' (duration: 156.468383ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:32:19.246771Z","caller":"traceutil/trace.go:171","msg":"trace[579863104] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"233.694389ms","start":"2026-06-25T22:32:19.013038Z","end":"2026-06-25T22:32:19.246733Z","steps":["trace[579863104] 'process raft request' (duration: 233.365322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:32:19.24695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.150167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:32:19.24705Z","caller":"traceutil/trace.go:171","msg":"trace[943173768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5468; }","duration":"195.340721ms","start":"2026-06-25T22:32:19.051689Z","end":"2026-06-25T22:32:19.247029Z","steps":["trace[943173768] 'agreement among raft nodes before linearized reading' (duration: 195.143817ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:32:19.246682Z","caller":"traceutil/trace.go:171","msg":"trace[418974617] linearizableReadLoop","detail":"{readStateIndex:5674; appliedIndex:5673; }","duration":"194.895532ms","start":"2026-06-25T22:32:19.051729Z","end":"2026-06-25T22:32:19.246624Z","steps":["trace[418974617] 'read index received' (duration: 194.704958ms)","trace[418974617] 'applied index is now lower than readState.Index' (duration: 189.064µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:32:19.326991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.52263ms","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:32:19.327064Z","caller":"traceutil/trace.go:171","msg":"trace[547760820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5469; }","duration":"172.618621ms","start":"2026-06-25T22:32:19.154429Z","end":"2026-06-25T22:32:19.327048Z","steps":["trace[547760820] 'agreement among raft nodes before linearized reading' (duration: 172.401187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:32:19.327138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.890068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-25T22:32:19.327196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.749554ms","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:32:19.327236Z","caller":"traceutil/trace.go:171","msg":"trace[1599637055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5469; }","duration":"179.051022ms","start":"2026-06-25T22:32:19.148161Z","end":"2026-06-25T22:32:19.327212Z","steps":["trace[1599637055] 'agreement among raft nodes before linearized reading' (duration: 178.784586ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:32:19.327305Z","caller":"traceutil/trace.go:171","msg":"trace[164621745] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5469; }","duration":"177.96032ms","start":"2026-06-25T22:32:19.149318Z","end":"2026-06-25T22:32:19.327279Z","steps":["trace[164621745] 'agreement among raft nodes before linearized reading' (duration: 177.575672ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:32:19.326859Z","caller":"traceutil/trace.go:171","msg":"trace[100366602] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"255.544623ms","start":"2026-06-25T22:32:19.071266Z","end":"2026-06-25T22:32:19.32681Z","steps":["trace[100366602] 'process raft request' (duration: 217.565322ms)","trace[100366602] 'compare' (duration: 37.750766ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:33:29.898129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-06-25T22:33:29.994352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"94.15552ms","hash":2321401829,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":31842304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T22:33:29.99459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2321401829,"revision":3014,"compact-revision":-1} {"level":"warn","ts":"2026-06-25T22:38:24.100825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.0356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:38:24.100996Z","caller":"traceutil/trace.go:171","msg":"trace[1358937029] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"217.848905ms","start":"2026-06-25T22:38:23.883099Z","end":"2026-06-25T22:38:24.100948Z","steps":["trace[1358937029] 'process raft request' (duration: 59.58246ms)","trace[1358937029] 'compare' (duration: 157.895247ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:38:29.905202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6102} {"level":"info","ts":"2026-06-25T22:38:30.004389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6102,"took":"96.297475ms","hash":1847552192,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":23814144,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-25T22:38:30.00447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1847552192,"revision":6102,"compact-revision":3014} {"level":"info","ts":"2026-06-25T22:38:36.337756Z","caller":"traceutil/trace.go:171","msg":"trace[1873371212] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"189.476118ms","start":"2026-06-25T22:38:36.148243Z","end":"2026-06-25T22:38:36.337719Z","steps":["trace[1873371212] 'process raft request' (duration: 189.219413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:38:37.188544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.301439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:38:37.188666Z","caller":"traceutil/trace.go:171","msg":"trace[976268016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8336; }","duration":"136.493523ms","start":"2026-06-25T22:38:37.052142Z","end":"2026-06-25T22:38:37.188636Z","steps":["trace[976268016] 'range keys from in-memory index tree' (duration: 136.178006ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:04.67009Z","caller":"traceutil/trace.go:171","msg":"trace[1864603710] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"226.152513ms","start":"2026-06-25T22:39:04.443909Z","end":"2026-06-25T22:39:04.670062Z","steps":["trace[1864603710] 'process raft request' (duration: 225.97599ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:04.866484Z","caller":"traceutil/trace.go:171","msg":"trace[250629884] linearizableReadLoop","detail":"{readStateIndex:8842; appliedIndex:8840; }","duration":"369.79644ms","start":"2026-06-25T22:39:04.496647Z","end":"2026-06-25T22:39:04.866443Z","steps":["trace[250629884] 'read index received' (duration: 173.185907ms)","trace[250629884] 'applied index is now lower than readState.Index' (duration: 196.609083ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:39:04.866701Z","caller":"traceutil/trace.go:171","msg":"trace[275844476] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"422.43856ms","start":"2026-06-25T22:39:04.444242Z","end":"2026-06-25T22:39:04.86668Z","steps":["trace[275844476] 'process raft request' (duration: 416.54442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:39:04.866838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:39:04.444209Z","time spent":"422.525972ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:39:04.866873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.350365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-06-25T22:39:04.866874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.231819ms","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:39:04.866955Z","caller":"traceutil/trace.go:171","msg":"trace[376193511] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8537; }","duration":"316.489128ms","start":"2026-06-25T22:39:04.550449Z","end":"2026-06-25T22:39:04.866938Z","steps":["trace[376193511] 'agreement among raft nodes before linearized reading' (duration: 316.303224ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:04.866976Z","caller":"traceutil/trace.go:171","msg":"trace[184549929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8537; }","duration":"370.374322ms","start":"2026-06-25T22:39:04.496584Z","end":"2026-06-25T22:39:04.866959Z","steps":["trace[184549929] 'agreement among raft nodes before linearized reading' (duration: 370.195099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:39:04.866985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:39:04.550426Z","time spent":"316.553019ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-25T22:39:04.867012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:39:04.496539Z","time spent":"370.464034ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T22:39:04.867137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.439354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-97478d945-rqs7q.18bc73ffb145ce40\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-06-25T22:39:04.867183Z","caller":"traceutil/trace.go:171","msg":"trace[1702646480] range","detail":"{range_begin:/registry/events/openstack/barbican-api-97478d945-rqs7q.18bc73ffb145ce40; range_end:; response_count:1; response_revision:8537; }","duration":"305.507755ms","start":"2026-06-25T22:39:04.561664Z","end":"2026-06-25T22:39:04.867172Z","steps":["trace[1702646480] 'agreement among raft nodes before linearized reading' (duration: 305.355831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:39:04.867215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:39:04.561654Z","time spent":"305.553065ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1155,"request content":"key:\"/registry/events/openstack/barbican-api-97478d945-rqs7q.18bc73ffb145ce40\" "} {"level":"info","ts":"2026-06-25T22:39:17.3826Z","caller":"traceutil/trace.go:171","msg":"trace[500738173] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"153.428896ms","start":"2026-06-25T22:39:17.229137Z","end":"2026-06-25T22:39:17.382566Z","steps":["trace[500738173] 'process raft request' (duration: 153.193832ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:17.463748Z","caller":"traceutil/trace.go:171","msg":"trace[1412749497] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8645; }","duration":"234.069575ms","start":"2026-06-25T22:39:17.229655Z","end":"2026-06-25T22:39:17.463724Z","steps":["trace[1412749497] 'process raft request' (duration: 233.85209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:39:18.044637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.993508ms","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:39:18.04473Z","caller":"traceutil/trace.go:171","msg":"trace[645968019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8648; }","duration":"121.123ms","start":"2026-06-25T22:39:17.923584Z","end":"2026-06-25T22:39:18.044707Z","steps":["trace[645968019] 'range keys from in-memory index tree' (duration: 120.771153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:39:18.04499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.474479ms","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-25T22:39:18.045444Z","caller":"traceutil/trace.go:171","msg":"trace[572749307] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8648; }","duration":"127.576861ms","start":"2026-06-25T22:39:17.917829Z","end":"2026-06-25T22:39:18.045406Z","steps":["trace[572749307] 'range keys from in-memory index tree' (duration: 126.169113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:39:18.203002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.794429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-25T22:39:18.291933Z","caller":"traceutil/trace.go:171","msg":"trace[91317932] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"239.981493ms","start":"2026-06-25T22:39:18.051922Z","end":"2026-06-25T22:39:18.291903Z","steps":["trace[91317932] 'process raft request' (duration: 239.901172ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:18.292355Z","caller":"traceutil/trace.go:171","msg":"trace[709197294] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"240.745609ms","start":"2026-06-25T22:39:18.051572Z","end":"2026-06-25T22:39:18.292317Z","steps":["trace[709197294] 'process raft request' (duration: 240.028665ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:18.292569Z","caller":"traceutil/trace.go:171","msg":"trace[1730392287] linearizableReadLoop","detail":"{readStateIndex:8961; appliedIndex:8961; }","duration":"213.334503ms","start":"2026-06-25T22:39:18.079187Z","end":"2026-06-25T22:39:18.292521Z","steps":["trace[1730392287] 'read index received' (duration: 213.299282ms)","trace[1730392287] 'applied index is now lower than readState.Index' (duration: 30.74µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:39:18.292844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.639139ms","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:39:18.2929Z","caller":"traceutil/trace.go:171","msg":"trace[1454115270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8650; }","duration":"213.751562ms","start":"2026-06-25T22:39:18.079134Z","end":"2026-06-25T22:39:18.292885Z","steps":["trace[1454115270] 'agreement among raft nodes before linearized reading' (duration: 213.519367ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:18.326521Z","caller":"traceutil/trace.go:171","msg":"trace[1581593145] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"114.356202ms","start":"2026-06-25T22:39:18.212125Z","end":"2026-06-25T22:39:18.326482Z","steps":["trace[1581593145] 'process raft request' (duration: 96.5381ms)","trace[1581593145] 'compare' (duration: 17.631029ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:39:18.32678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.009028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g2qqn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-25T22:39:18.326867Z","caller":"traceutil/trace.go:171","msg":"trace[255036312] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g2qqn; range_end:; response_count:1; response_revision:8651; }","duration":"219.123141ms","start":"2026-06-25T22:39:18.107725Z","end":"2026-06-25T22:39:18.326849Z","steps":["trace[255036312] 'agreement among raft nodes before linearized reading' (duration: 218.781964ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:39:33.330979Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002397.wal"} {"level":"info","ts":"2026-06-25T22:40:34.32475Z","caller":"traceutil/trace.go:171","msg":"trace[1844203414] linearizableReadLoop","detail":"{readStateIndex:9728; appliedIndex:9727; }","duration":"108.857887ms","start":"2026-06-25T22:40:34.215866Z","end":"2026-06-25T22:40:34.324724Z","steps":["trace[1844203414] 'read index received' (duration: 108.634772ms)","trace[1844203414] 'applied index is now lower than readState.Index' (duration: 221.965µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:40:34.32485Z","caller":"traceutil/trace.go:171","msg":"trace[6782309] transaction","detail":"{read_only:false; response_revision:9398; number_of_response:1; }","duration":"115.40745ms","start":"2026-06-25T22:40:34.209403Z","end":"2026-06-25T22:40:34.32481Z","steps":["trace[6782309] 'process raft request' (duration: 115.157835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:40:34.324969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.073221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:40:34.325055Z","caller":"traceutil/trace.go:171","msg":"trace[528617470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9398; }","duration":"109.244354ms","start":"2026-06-25T22:40:34.215788Z","end":"2026-06-25T22:40:34.325033Z","steps":["trace[528617470] 'agreement among raft nodes before linearized reading' (duration: 109.04468ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:41:09.514036Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T22:41:09.522787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-25T22:41:09.522948Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-25T22:43:29.912964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8299} {"level":"info","ts":"2026-06-25T22:43:29.996339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8299,"took":"80.144433ms","hash":2835506616,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":26398720,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-25T22:43:29.996413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2835506616,"revision":8299,"compact-revision":6102} {"level":"info","ts":"2026-06-25T22:48:29.920126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10788} {"level":"info","ts":"2026-06-25T22:48:29.995762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10788,"took":"72.821022ms","hash":1166206809,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":27303936,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-25T22:48:29.995837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1166206809,"revision":10788,"compact-revision":8299} {"level":"info","ts":"2026-06-25T22:50:13.272449Z","caller":"traceutil/trace.go:171","msg":"trace[586066094] transaction","detail":"{read_only:false; response_revision:13666; number_of_response:1; }","duration":"106.991777ms","start":"2026-06-25T22:50:13.165432Z","end":"2026-06-25T22:50:13.272424Z","steps":["trace[586066094] 'process raft request' (duration: 106.815633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:50:51.735996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.147329ms","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:50:51.736083Z","caller":"traceutil/trace.go:171","msg":"trace[606866792] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13885; }","duration":"101.278141ms","start":"2026-06-25T22:50:51.63479Z","end":"2026-06-25T22:50:51.736068Z","steps":["trace[606866792] 'range keys from in-memory index tree' (duration: 101.089777ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:51:01.226643Z","caller":"traceutil/trace.go:171","msg":"trace[2116682222] linearizableReadLoop","detail":"{readStateIndex:14538; appliedIndex:14537; }","duration":"174.936639ms","start":"2026-06-25T22:51:01.051681Z","end":"2026-06-25T22:51:01.226618Z","steps":["trace[2116682222] 'read index received' (duration: 174.790496ms)","trace[2116682222] 'applied index is now lower than readState.Index' (duration: 144.563µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:51:01.226679Z","caller":"traceutil/trace.go:171","msg":"trace[514853884] transaction","detail":"{read_only:false; response_revision:14057; number_of_response:1; }","duration":"213.376902ms","start":"2026-06-25T22:51:01.013264Z","end":"2026-06-25T22:51:01.226641Z","steps":["trace[514853884] 'process raft request' (duration: 213.188988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:51:01.226788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.087953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:51:01.226838Z","caller":"traceutil/trace.go:171","msg":"trace[1097841028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14057; }","duration":"175.157304ms","start":"2026-06-25T22:51:01.051667Z","end":"2026-06-25T22:51:01.226824Z","steps":["trace[1097841028] 'agreement among raft nodes before linearized reading' (duration: 175.046582ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:51:01.41674Z","caller":"traceutil/trace.go:171","msg":"trace[1583333066] transaction","detail":"{read_only:false; response_revision:14058; number_of_response:1; }","duration":"173.630973ms","start":"2026-06-25T22:51:01.243088Z","end":"2026-06-25T22:51:01.416719Z","steps":["trace[1583333066] 'process raft request' (duration: 173.188624ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:52:40.305577Z","caller":"traceutil/trace.go:171","msg":"trace[1072745260] transaction","detail":"{read_only:false; response_revision:15141; number_of_response:1; }","duration":"149.877478ms","start":"2026-06-25T22:52:40.155658Z","end":"2026-06-25T22:52:40.305535Z","steps":["trace[1072745260] 'process raft request' (duration: 149.560082ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:52:40.464541Z","caller":"traceutil/trace.go:171","msg":"trace[350018023] linearizableReadLoop","detail":"{readStateIndex:15659; appliedIndex:15658; }","duration":"101.913994ms","start":"2026-06-25T22:52:40.362598Z","end":"2026-06-25T22:52:40.464512Z","steps":["trace[350018023] 'read index received' (duration: 41.364352ms)","trace[350018023] 'applied index is now lower than readState.Index' (duration: 60.548172ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:52:40.4647Z","caller":"traceutil/trace.go:171","msg":"trace[1563904175] transaction","detail":"{read_only:false; response_revision:15142; number_of_response:1; }","duration":"154.255968ms","start":"2026-06-25T22:52:40.310427Z","end":"2026-06-25T22:52:40.464683Z","steps":["trace[1563904175] 'process raft request' (duration: 93.491511ms)","trace[1563904175] 'compare' (duration: 60.397639ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:52:40.465032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.421124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-25T22:52:40.465111Z","caller":"traceutil/trace.go:171","msg":"trace[156495130] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15142; }","duration":"102.553407ms","start":"2026-06-25T22:52:40.362539Z","end":"2026-06-25T22:52:40.465092Z","steps":["trace[156495130] 'agreement among raft nodes before linearized reading' (duration: 102.383234ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:52:40.951436Z","caller":"traceutil/trace.go:171","msg":"trace[1191356746] linearizableReadLoop","detail":"{readStateIndex:15661; appliedIndex:15660; }","duration":"150.273907ms","start":"2026-06-25T22:52:40.801135Z","end":"2026-06-25T22:52:40.951408Z","steps":["trace[1191356746] 'read index received' (duration: 142.490279ms)","trace[1191356746] 'applied index is now lower than readState.Index' (duration: 7.782468ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:52:40.951476Z","caller":"traceutil/trace.go:171","msg":"trace[1417580136] transaction","detail":"{read_only:false; response_revision:15144; number_of_response:1; }","duration":"227.945498ms","start":"2026-06-25T22:52:40.723506Z","end":"2026-06-25T22:52:40.951451Z","steps":["trace[1417580136] 'process raft request' (duration: 220.080668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:52:40.951623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.748895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:52:40.951669Z","caller":"traceutil/trace.go:171","msg":"trace[838920556] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15144; }","duration":"143.857657ms","start":"2026-06-25T22:52:40.807799Z","end":"2026-06-25T22:52:40.951657Z","steps":["trace[838920556] 'agreement among raft nodes before linearized reading' (duration: 143.760525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:52:40.951914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.725056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-25T22:52:40.95197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.313805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-25T22:52:40.952149Z","caller":"traceutil/trace.go:171","msg":"trace[1687967262] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15144; }","duration":"140.529019ms","start":"2026-06-25T22:52:40.811592Z","end":"2026-06-25T22:52:40.952121Z","steps":["trace[1687967262] 'agreement among raft nodes before linearized reading' (duration: 140.09575ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:52:40.952053Z","caller":"traceutil/trace.go:171","msg":"trace[1966314694] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15144; }","duration":"150.932461ms","start":"2026-06-25T22:52:40.80109Z","end":"2026-06-25T22:52:40.952023Z","steps":["trace[1966314694] 'agreement among raft nodes before linearized reading' (duration: 150.455901ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:53:29.931348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12877} {"level":"info","ts":"2026-06-25T22:53:30.012745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12877,"took":"78.473956ms","hash":997866653,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":30842880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-25T22:53:30.01281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":997866653,"revision":12877,"compact-revision":10788} {"level":"info","ts":"2026-06-25T22:58:18.781683Z","caller":"traceutil/trace.go:171","msg":"trace[760499597] transaction","detail":"{read_only:false; response_revision:17690; number_of_response:1; }","duration":"159.356399ms","start":"2026-06-25T22:58:18.62231Z","end":"2026-06-25T22:58:18.781666Z","steps":["trace[760499597] 'process raft request' (duration: 159.178685ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:58:23.465696Z","caller":"traceutil/trace.go:171","msg":"trace[574460995] transaction","detail":"{read_only:false; response_revision:17740; number_of_response:1; }","duration":"101.552844ms","start":"2026-06-25T22:58:23.364097Z","end":"2026-06-25T22:58:23.465649Z","steps":["trace[574460995] 'process raft request' (duration: 101.33397ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:58:23.656062Z","caller":"traceutil/trace.go:171","msg":"trace[1533336157] transaction","detail":"{read_only:false; response_revision:17741; number_of_response:1; }","duration":"139.072187ms","start":"2026-06-25T22:58:23.516947Z","end":"2026-06-25T22:58:23.656019Z","steps":["trace[1533336157] 'process raft request' (duration: 128.124135ms)","trace[1533336157] 'compare' (duration: 10.719878ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:58:23.679259Z","caller":"traceutil/trace.go:171","msg":"trace[1824504679] transaction","detail":"{read_only:false; response_revision:17742; number_of_response:1; }","duration":"139.874524ms","start":"2026-06-25T22:58:23.539335Z","end":"2026-06-25T22:58:23.679209Z","steps":["trace[1824504679] 'process raft request' (duration: 139.68063ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:58:25.242322Z","caller":"traceutil/trace.go:171","msg":"trace[38578950] linearizableReadLoop","detail":"{readStateIndex:18357; appliedIndex:18356; }","duration":"190.567744ms","start":"2026-06-25T22:58:25.051718Z","end":"2026-06-25T22:58:25.242286Z","steps":["trace[38578950] 'read index received' (duration: 190.278348ms)","trace[38578950] 'applied index is now lower than readState.Index' (duration: 287.546µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:58:25.242425Z","caller":"traceutil/trace.go:171","msg":"trace[1564841037] transaction","detail":"{read_only:false; response_revision:17755; number_of_response:1; }","duration":"212.741315ms","start":"2026-06-25T22:58:25.029645Z","end":"2026-06-25T22:58:25.242386Z","steps":["trace[1564841037] 'process raft request' (duration: 212.397588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:58:25.24267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.921002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T22:58:25.243363Z","caller":"traceutil/trace.go:171","msg":"trace[50738201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17755; }","duration":"191.076625ms","start":"2026-06-25T22:58:25.051652Z","end":"2026-06-25T22:58:25.242729Z","steps":["trace[50738201] 'agreement among raft nodes before linearized reading' (duration: 190.82576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:58:25.250239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.380743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-06-25T22:58:25.250586Z","caller":"traceutil/trace.go:171","msg":"trace[1770239280] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17755; }","duration":"198.521496ms","start":"2026-06-25T22:58:25.05178Z","end":"2026-06-25T22:58:25.250301Z","steps":["trace[1770239280] 'agreement among raft nodes before linearized reading' (duration: 190.712247ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:58:25.558656Z","caller":"traceutil/trace.go:171","msg":"trace[726424250] transaction","detail":"{read_only:false; response_revision:17759; number_of_response:1; }","duration":"254.661349ms","start":"2026-06-25T22:58:25.303967Z","end":"2026-06-25T22:58:25.558629Z","steps":["trace[726424250] 'process raft request' (duration: 254.450363ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:58:25.574314Z","caller":"traceutil/trace.go:171","msg":"trace[578680264] transaction","detail":"{read_only:false; response_revision:17760; number_of_response:1; }","duration":"101.364551ms","start":"2026-06-25T22:58:25.472913Z","end":"2026-06-25T22:58:25.574277Z","steps":["trace[578680264] 'process raft request' (duration: 100.902182ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:58:29.937718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15459} {"level":"info","ts":"2026-06-25T22:58:30.088327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15459,"took":"147.49986ms","hash":3817534468,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":32681984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-25T22:58:30.088387Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3817534468,"revision":15459,"compact-revision":12877} {"level":"info","ts":"2026-06-25T23:01:35.960287Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T23:01:35.965393Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-25T23:01:35.965515Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-25T23:03:29.945774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17793} {"level":"info","ts":"2026-06-25T23:03:30.047311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17793,"took":"97.458106ms","hash":3883720258,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":34373632,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-25T23:03:30.047413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3883720258,"revision":17793,"compact-revision":15459} {"level":"info","ts":"2026-06-25T23:03:48.997173Z","caller":"traceutil/trace.go:171","msg":"trace[947229357] transaction","detail":"{read_only:false; response_revision:20292; number_of_response:1; }","duration":"134.966638ms","start":"2026-06-25T23:03:48.861948Z","end":"2026-06-25T23:03:48.996914Z","steps":["trace[947229357] 'process raft request' (duration: 134.742533ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:03:49.196655Z","caller":"traceutil/trace.go:171","msg":"trace[1650716016] transaction","detail":"{read_only:false; response_revision:20293; number_of_response:1; }","duration":"190.603066ms","start":"2026-06-25T23:03:49.006014Z","end":"2026-06-25T23:03:49.196617Z","steps":["trace[1650716016] 'process raft request' (duration: 190.388292ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:03:49.446132Z","caller":"traceutil/trace.go:171","msg":"trace[831333965] transaction","detail":"{read_only:false; response_revision:20295; number_of_response:1; }","duration":"437.74909ms","start":"2026-06-25T23:03:49.008347Z","end":"2026-06-25T23:03:49.446096Z","steps":["trace[831333965] 'process raft request' (duration: 437.564766ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:03:49.446156Z","caller":"traceutil/trace.go:171","msg":"trace[61052980] transaction","detail":"{read_only:false; response_revision:20294; number_of_response:1; }","duration":"437.751779ms","start":"2026-06-25T23:03:49.008351Z","end":"2026-06-25T23:03:49.446103Z","steps":["trace[61052980] 'process raft request' (duration: 342.532237ms)","trace[61052980] 'compare' (duration: 94.685541ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:03:49.446345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:03:49.008308Z","time spent":"437.928172ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:03:49.446467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:03:49.008332Z","time spent":"437.953823ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:03:49.446501Z","caller":"traceutil/trace.go:171","msg":"trace[2013986930] transaction","detail":"{read_only:false; response_revision:20296; number_of_response:1; }","duration":"407.206379ms","start":"2026-06-25T23:03:49.039269Z","end":"2026-06-25T23:03:49.446475Z","steps":["trace[2013986930] 'process raft request' (duration: 406.77181ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:03:49.446642Z","caller":"traceutil/trace.go:171","msg":"trace[974872175] linearizableReadLoop","detail":"{readStateIndex:20983; appliedIndex:20978; }","duration":"280.625632ms","start":"2026-06-25T23:03:49.165908Z","end":"2026-06-25T23:03:49.446533Z","steps":["trace[974872175] 'read index received' (duration: 30.343546ms)","trace[974872175] 'applied index is now lower than readState.Index' (duration: 250.280556ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:03:49.446731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:03:49.039248Z","time spent":"407.384952ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:03:49.44693Z","caller":"traceutil/trace.go:171","msg":"trace[1694312521] transaction","detail":"{read_only:false; response_revision:20297; number_of_response:1; }","duration":"294.76434ms","start":"2026-06-25T23:03:49.152102Z","end":"2026-06-25T23:03:49.446866Z","steps":["trace[1694312521] 'process raft request' (duration: 294.221388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:03:49.447154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.139153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:03:49.447216Z","caller":"traceutil/trace.go:171","msg":"trace[1501199223] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:20297; }","duration":"281.362157ms","start":"2026-06-25T23:03:49.165836Z","end":"2026-06-25T23:03:49.447198Z","steps":["trace[1501199223] 'agreement among raft nodes before linearized reading' (duration: 281.052321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:03:49.736592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.713996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:03:49.7367Z","caller":"traceutil/trace.go:171","msg":"trace[1517422667] transaction","detail":"{read_only:false; response_revision:20298; number_of_response:1; }","duration":"284.200824ms","start":"2026-06-25T23:03:49.452479Z","end":"2026-06-25T23:03:49.73668Z","steps":["trace[1517422667] 'process raft request' (duration: 139.304265ms)","trace[1517422667] 'compare' (duration: 144.590483ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:03:49.743876Z","caller":"traceutil/trace.go:171","msg":"trace[1612669159] transaction","detail":"{read_only:false; response_revision:20299; number_of_response:1; }","duration":"259.687248ms","start":"2026-06-25T23:03:49.484161Z","end":"2026-06-25T23:03:49.743849Z","steps":["trace[1612669159] 'process raft request' (duration: 259.516103ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:03:50.210977Z","caller":"traceutil/trace.go:171","msg":"trace[1656592192] transaction","detail":"{read_only:false; response_revision:20300; number_of_response:1; }","duration":"134.04105ms","start":"2026-06-25T23:03:50.076905Z","end":"2026-06-25T23:03:50.210946Z","steps":["trace[1656592192] 'process raft request' (duration: 133.798895ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:07:42.342065Z","caller":"traceutil/trace.go:171","msg":"trace[733966530] linearizableReadLoop","detail":"{readStateIndex:23517; appliedIndex:23516; }","duration":"116.267959ms","start":"2026-06-25T23:07:42.225761Z","end":"2026-06-25T23:07:42.342028Z","steps":["trace[733966530] 'read index received' (duration: 95.060698ms)","trace[733966530] 'applied index is now lower than readState.Index' (duration: 21.205701ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:07:42.342543Z","caller":"traceutil/trace.go:171","msg":"trace[463552075] transaction","detail":"{read_only:false; response_revision:22759; number_of_response:1; }","duration":"255.625505ms","start":"2026-06-25T23:07:42.086864Z","end":"2026-06-25T23:07:42.34249Z","steps":["trace[463552075] 'process raft request' (duration: 233.992496ms)","trace[463552075] 'compare' (duration: 21.004746ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:07:42.342572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.774629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:07:42.343134Z","caller":"traceutil/trace.go:171","msg":"trace[203073967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:22759; }","duration":"117.369591ms","start":"2026-06-25T23:07:42.225702Z","end":"2026-06-25T23:07:42.343072Z","steps":["trace[203073967] 'agreement among raft nodes before linearized reading' (duration: 116.766628ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:08:29.952256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20196} {"level":"info","ts":"2026-06-25T23:08:30.071542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20196,"took":"113.775209ms","hash":1590764511,"current-db-size-bytes":57868288,"current-db-size":"58 MB","current-db-size-in-use-bytes":42737664,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-06-25T23:08:30.071615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1590764511,"revision":20196,"compact-revision":17793} {"level":"info","ts":"2026-06-25T23:11:13.906209Z","caller":"traceutil/trace.go:171","msg":"trace[1780995961] linearizableReadLoop","detail":"{readStateIndex:25720; appliedIndex:25719; }","duration":"144.212546ms","start":"2026-06-25T23:11:13.761965Z","end":"2026-06-25T23:11:13.906178Z","steps":["trace[1780995961] 'read index received' (duration: 144.012912ms)","trace[1780995961] 'applied index is now lower than readState.Index' (duration: 198.414µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:13.906369Z","caller":"traceutil/trace.go:171","msg":"trace[953244463] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"219.257212ms","start":"2026-06-25T23:11:13.68705Z","end":"2026-06-25T23:11:13.906307Z","steps":["trace[953244463] 'process raft request' (duration: 218.918006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:13.906511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.859507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-25T23:11:13.906479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.4552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:13.906554Z","caller":"traceutil/trace.go:171","msg":"trace[545899284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24906; }","duration":"111.953998ms","start":"2026-06-25T23:11:13.794589Z","end":"2026-06-25T23:11:13.906543Z","steps":["trace[545899284] 'agreement among raft nodes before linearized reading' (duration: 111.766394ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:13.9066Z","caller":"traceutil/trace.go:171","msg":"trace[1059161561] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:24906; }","duration":"144.673325ms","start":"2026-06-25T23:11:13.761893Z","end":"2026-06-25T23:11:13.906567Z","steps":["trace[1059161561] 'agreement among raft nodes before linearized reading' (duration: 144.44227ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:14.314638Z","caller":"traceutil/trace.go:171","msg":"trace[102771242] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25721; }","duration":"124.545845ms","start":"2026-06-25T23:11:14.190061Z","end":"2026-06-25T23:11:14.314607Z","steps":["trace[102771242] 'read index received' (duration: 124.347181ms)","trace[102771242] 'applied index is now lower than readState.Index' (duration: 197.444µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:14.314678Z","caller":"traceutil/trace.go:171","msg":"trace[1370489601] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"141.52465ms","start":"2026-06-25T23:11:14.173123Z","end":"2026-06-25T23:11:14.314648Z","steps":["trace[1370489601] 'process raft request' (duration: 141.244774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:14.314883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.81007ms","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:11:14.314967Z","caller":"traceutil/trace.go:171","msg":"trace[2010165658] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24908; }","duration":"124.946794ms","start":"2026-06-25T23:11:14.190004Z","end":"2026-06-25T23:11:14.314951Z","steps":["trace[2010165658] 'agreement among raft nodes before linearized reading' (duration: 124.724628ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:14.438439Z","caller":"traceutil/trace.go:171","msg":"trace[185345653] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"158.926575ms","start":"2026-06-25T23:11:14.279462Z","end":"2026-06-25T23:11:14.438389Z","steps":["trace[185345653] 'process raft request' (duration: 150.82964ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:14.506069Z","caller":"traceutil/trace.go:171","msg":"trace[1204865679] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"185.404783ms","start":"2026-06-25T23:11:14.320629Z","end":"2026-06-25T23:11:14.506034Z","steps":["trace[1204865679] 'process raft request' (duration: 185.25384ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:14.860319Z","caller":"traceutil/trace.go:171","msg":"trace[1266348967] linearizableReadLoop","detail":"{readStateIndex:25725; appliedIndex:25724; }","duration":"182.869272ms","start":"2026-06-25T23:11:14.677423Z","end":"2026-06-25T23:11:14.860292Z","steps":["trace[1266348967] 'read index received' (duration: 162.581349ms)","trace[1266348967] 'applied index is now lower than readState.Index' (duration: 20.287073ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:14.860368Z","caller":"traceutil/trace.go:171","msg":"trace[1057343359] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"362.327594ms","start":"2026-06-25T23:11:14.497981Z","end":"2026-06-25T23:11:14.860309Z","steps":["trace[1057343359] 'process raft request' (duration: 341.917869ms)","trace[1057343359] 'compare' (duration: 20.256272ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:14.860425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.992844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-25T23:11:14.860553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:14.497945Z","time spent":"362.529418ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:14.860646Z","caller":"traceutil/trace.go:171","msg":"trace[914961580] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24911; }","duration":"183.216118ms","start":"2026-06-25T23:11:14.67736Z","end":"2026-06-25T23:11:14.860576Z","steps":["trace[914961580] 'agreement among raft nodes before linearized reading' (duration: 182.997854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:14.924004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.824009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:14.924104Z","caller":"traceutil/trace.go:171","msg":"trace[28440609] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"180.435722ms","start":"2026-06-25T23:11:14.74365Z","end":"2026-06-25T23:11:14.924086Z","steps":["trace[28440609] 'process raft request' (duration: 179.956793ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:14.924148Z","caller":"traceutil/trace.go:171","msg":"trace[494476289] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24912; }","duration":"208.021714ms","start":"2026-06-25T23:11:14.716088Z","end":"2026-06-25T23:11:14.92411Z","steps":["trace[494476289] 'agreement among raft nodes before linearized reading' (duration: 207.745948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:14.924037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.717584ms","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:11:14.924326Z","caller":"traceutil/trace.go:171","msg":"trace[1056092383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24912; }","duration":"196.04898ms","start":"2026-06-25T23:11:14.728268Z","end":"2026-06-25T23:11:14.924317Z","steps":["trace[1056092383] 'agreement among raft nodes before linearized reading' (duration: 195.589641ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:15.106801Z","caller":"traceutil/trace.go:171","msg":"trace[1847880032] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"176.116275ms","start":"2026-06-25T23:11:14.930643Z","end":"2026-06-25T23:11:15.106759Z","steps":["trace[1847880032] 'process raft request' (duration: 157.123508ms)","trace[1847880032] 'compare' (duration: 18.580549ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:15.405459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.821024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:15.405704Z","caller":"traceutil/trace.go:171","msg":"trace[384317669] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"158.844213ms","start":"2026-06-25T23:11:15.246838Z","end":"2026-06-25T23:11:15.405682Z","steps":["trace[384317669] 'process raft request' (duration: 158.771132ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:15.405854Z","caller":"traceutil/trace.go:171","msg":"trace[1491729741] linearizableReadLoop","detail":"{readStateIndex:25728; appliedIndex:25727; }","duration":"163.915366ms","start":"2026-06-25T23:11:15.2419Z","end":"2026-06-25T23:11:15.405815Z","steps":["trace[1491729741] 'read index received' (duration: 41.44µs)","trace[1491729741] 'applied index is now lower than readState.Index' (duration: 163.871406ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:15.40591Z","caller":"traceutil/trace.go:171","msg":"trace[1832343682] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"282.293315ms","start":"2026-06-25T23:11:15.123515Z","end":"2026-06-25T23:11:15.405808Z","steps":["trace[1832343682] 'process raft request' (duration: 117.008181ms)","trace[1832343682] 'compare' (duration: 164.60638ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:15.406143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.223953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-25T23:11:15.406213Z","caller":"traceutil/trace.go:171","msg":"trace[1458275109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24915; }","duration":"164.342015ms","start":"2026-06-25T23:11:15.241851Z","end":"2026-06-25T23:11:15.406193Z","steps":["trace[1458275109] 'agreement among raft nodes before linearized reading' (duration: 164.10228ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:15.718477Z","caller":"traceutil/trace.go:171","msg":"trace[757944875] linearizableReadLoop","detail":"{readStateIndex:25730; appliedIndex:25729; }","duration":"312.515982ms","start":"2026-06-25T23:11:15.405934Z","end":"2026-06-25T23:11:15.71845Z","steps":["trace[757944875] 'read index received' (duration: 253.284045ms)","trace[757944875] 'applied index is now lower than readState.Index' (duration: 59.230636ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:15.718492Z","caller":"traceutil/trace.go:171","msg":"trace[354805192] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"416.474947ms","start":"2026-06-25T23:11:15.301976Z","end":"2026-06-25T23:11:15.718451Z","steps":["trace[354805192] 'process raft request' (duration: 357.22423ms)","trace[354805192] 'compare' (duration: 59.004131ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:15.718746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:15.301949Z","time spent":"416.696941ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:15.718757Z","caller":"traceutil/trace.go:171","msg":"trace[793048196] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"307.896237ms","start":"2026-06-25T23:11:15.410824Z","end":"2026-06-25T23:11:15.71872Z","steps":["trace[793048196] 'process raft request' (duration: 307.59374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:15.718794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.59626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:15.718882Z","caller":"traceutil/trace.go:171","msg":"trace[937225237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24917; }","duration":"475.701132ms","start":"2026-06-25T23:11:15.243159Z","end":"2026-06-25T23:11:15.71886Z","steps":["trace[937225237] 'agreement among raft nodes before linearized reading' (duration: 475.380125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:15.718958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:15.243091Z","time spent":"475.852765ms","remote":"127.0.0.1:48560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-25T23:11:15.718941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.081549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-25T23:11:15.719013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:15.4108Z","time spent":"308.05556ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:15.719026Z","caller":"traceutil/trace.go:171","msg":"trace[264242190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24917; }","duration":"105.243283ms","start":"2026-06-25T23:11:15.613757Z","end":"2026-06-25T23:11:15.719Z","steps":["trace[264242190] 'agreement among raft nodes before linearized reading' (duration: 104.961087ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:16.095297Z","caller":"traceutil/trace.go:171","msg":"trace[2056184913] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"139.275266ms","start":"2026-06-25T23:11:15.955982Z","end":"2026-06-25T23:11:16.095257Z","steps":["trace[2056184913] 'process raft request' (duration: 120.198537ms)","trace[2056184913] 'compare' (duration: 18.62599ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:21.874776Z","caller":"traceutil/trace.go:171","msg":"trace[908307368] linearizableReadLoop","detail":"{readStateIndex:25779; appliedIndex:25778; }","duration":"135.483697ms","start":"2026-06-25T23:11:21.739267Z","end":"2026-06-25T23:11:21.874751Z","steps":["trace[908307368] 'read index received' (duration: 135.210142ms)","trace[908307368] 'applied index is now lower than readState.Index' (duration: 272.675µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:21.874952Z","caller":"traceutil/trace.go:171","msg":"trace[892507720] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"136.448568ms","start":"2026-06-25T23:11:21.738418Z","end":"2026-06-25T23:11:21.874867Z","steps":["trace[892507720] 'process raft request' (duration: 136.139542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:21.875074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.784183ms","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:11:21.875117Z","caller":"traceutil/trace.go:171","msg":"trace[1956557530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24964; }","duration":"135.898865ms","start":"2026-06-25T23:11:21.739203Z","end":"2026-06-25T23:11:21.875102Z","steps":["trace[1956557530] 'agreement among raft nodes before linearized reading' (duration: 135.734232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:21.875208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.988073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-25T23:11:21.87528Z","caller":"traceutil/trace.go:171","msg":"trace[860429126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24964; }","duration":"125.088576ms","start":"2026-06-25T23:11:21.75017Z","end":"2026-06-25T23:11:21.875259Z","steps":["trace[860429126] 'agreement among raft nodes before linearized reading' (duration: 124.884682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:21.875447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.495462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-25T23:11:21.875488Z","caller":"traceutil/trace.go:171","msg":"trace[443721927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24964; }","duration":"121.551544ms","start":"2026-06-25T23:11:21.753924Z","end":"2026-06-25T23:11:21.875476Z","steps":["trace[443721927] 'agreement among raft nodes before linearized reading' (duration: 121.463132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:22.17383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.121286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:22.173921Z","caller":"traceutil/trace.go:171","msg":"trace[1523116032] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"290.578014ms","start":"2026-06-25T23:11:21.883327Z","end":"2026-06-25T23:11:22.173905Z","steps":["trace[1523116032] 'process raft request' (duration: 111.305865ms)","trace[1523116032] 'compare' (duration: 178.929602ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:22.174575Z","caller":"traceutil/trace.go:171","msg":"trace[1194733826] linearizableReadLoop","detail":"{readStateIndex:25782; appliedIndex:25779; }","duration":"227.253615ms","start":"2026-06-25T23:11:21.947304Z","end":"2026-06-25T23:11:22.174558Z","steps":["trace[1194733826] 'read index received' (duration: 47.340164ms)","trace[1194733826] 'applied index is now lower than readState.Index' (duration: 179.912411ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:22.174893Z","caller":"traceutil/trace.go:171","msg":"trace[1876593393] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"291.518364ms","start":"2026-06-25T23:11:21.883363Z","end":"2026-06-25T23:11:22.174882Z","steps":["trace[1876593393] 'process raft request' (duration: 291.016724ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:22.175126Z","caller":"traceutil/trace.go:171","msg":"trace[249589722] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"291.753158ms","start":"2026-06-25T23:11:21.883363Z","end":"2026-06-25T23:11:22.175117Z","steps":["trace[249589722] 'process raft request' (duration: 291.106995ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:22.175476Z","caller":"traceutil/trace.go:171","msg":"trace[844621411] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"205.970261ms","start":"2026-06-25T23:11:21.969496Z","end":"2026-06-25T23:11:22.175466Z","steps":["trace[844621411] 'process raft request' (duration: 205.025672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:22.175678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.352798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:22.175732Z","caller":"traceutil/trace.go:171","msg":"trace[1776851446] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24968; }","duration":"228.46052ms","start":"2026-06-25T23:11:21.947261Z","end":"2026-06-25T23:11:22.175722Z","steps":["trace[1776851446] 'agreement among raft nodes before linearized reading' (duration: 228.363908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:22.176197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.173248ms","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:22.176226Z","caller":"traceutil/trace.go:171","msg":"trace[395781810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24968; }","duration":"125.442093ms","start":"2026-06-25T23:11:22.050777Z","end":"2026-06-25T23:11:22.176219Z","steps":["trace[395781810] 'agreement among raft nodes before linearized reading' (duration: 125.184318ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:22.372527Z","caller":"traceutil/trace.go:171","msg":"trace[625204369] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25783; }","duration":"188.436835ms","start":"2026-06-25T23:11:22.184052Z","end":"2026-06-25T23:11:22.372488Z","steps":["trace[625204369] 'read index received' (duration: 91.855639ms)","trace[625204369] 'applied index is now lower than readState.Index' (duration: 96.579776ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:22.37276Z","caller":"traceutil/trace.go:171","msg":"trace[988968886] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"188.987426ms","start":"2026-06-25T23:11:22.183735Z","end":"2026-06-25T23:11:22.372722Z","steps":["trace[988968886] 'process raft request' (duration: 92.336489ms)","trace[988968886] 'compare' (duration: 96.234999ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:22.372934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.845083ms","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:22.373Z","caller":"traceutil/trace.go:171","msg":"trace[394315402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24969; }","duration":"188.954725ms","start":"2026-06-25T23:11:22.184029Z","end":"2026-06-25T23:11:22.372984Z","steps":["trace[394315402] 'agreement among raft nodes before linearized reading' (duration: 188.820062ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:22.534875Z","caller":"traceutil/trace.go:171","msg":"trace[945994929] linearizableReadLoop","detail":"{readStateIndex:25785; appliedIndex:25784; }","duration":"162.227772ms","start":"2026-06-25T23:11:22.372614Z","end":"2026-06-25T23:11:22.534842Z","steps":["trace[945994929] 'read index received' (duration: 129.18748ms)","trace[945994929] 'applied index is now lower than readState.Index' (duration: 33.038902ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:22.535075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.739766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:22.535115Z","caller":"traceutil/trace.go:171","msg":"trace[245162576] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24970; }","duration":"232.833038ms","start":"2026-06-25T23:11:22.302268Z","end":"2026-06-25T23:11:22.535101Z","steps":["trace[245162576] 'agreement among raft nodes before linearized reading' (duration: 232.717066ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:22.535412Z","caller":"traceutil/trace.go:171","msg":"trace[920192184] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"170.399328ms","start":"2026-06-25T23:11:22.365Z","end":"2026-06-25T23:11:22.5354Z","steps":["trace[920192184] 'process raft request' (duration: 136.806725ms)","trace[920192184] 'compare' (duration: 32.658134ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:22.648957Z","caller":"traceutil/trace.go:171","msg":"trace[482521466] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"182.684237ms","start":"2026-06-25T23:11:22.466243Z","end":"2026-06-25T23:11:22.648927Z","steps":["trace[482521466] 'process raft request' (duration: 181.093886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:22.92169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.480529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:22.921813Z","caller":"traceutil/trace.go:171","msg":"trace[2140075828] linearizableReadLoop","detail":"{readStateIndex:25787; appliedIndex:25786; }","duration":"223.209233ms","start":"2026-06-25T23:11:22.698585Z","end":"2026-06-25T23:11:22.921795Z","steps":["trace[2140075828] 'read index received' (duration: 84.574551ms)","trace[2140075828] 'applied index is now lower than readState.Index' (duration: 138.633042ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:22.921887Z","caller":"traceutil/trace.go:171","msg":"trace[237675169] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"267.529715ms","start":"2026-06-25T23:11:22.654298Z","end":"2026-06-25T23:11:22.921828Z","steps":["trace[237675169] 'process raft request' (duration: 128.809761ms)","trace[237675169] 'compare' (duration: 138.226924ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:22.922271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.779669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-25T23:11:22.922319Z","caller":"traceutil/trace.go:171","msg":"trace[686025532] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:24972; }","duration":"178.874611ms","start":"2026-06-25T23:11:22.743431Z","end":"2026-06-25T23:11:22.922305Z","steps":["trace[686025532] 'agreement among raft nodes before linearized reading' (duration: 178.475853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:22.922347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.238647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:22.922396Z","caller":"traceutil/trace.go:171","msg":"trace[1185106060] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:24972; }","duration":"149.320049ms","start":"2026-06-25T23:11:22.773057Z","end":"2026-06-25T23:11:22.922377Z","steps":["trace[1185106060] 'agreement among raft nodes before linearized reading' (duration: 149.261888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:22.92223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.619121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:22.922553Z","caller":"traceutil/trace.go:171","msg":"trace[522312574] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24972; }","duration":"223.994388ms","start":"2026-06-25T23:11:22.698546Z","end":"2026-06-25T23:11:22.92254Z","steps":["trace[522312574] 'agreement among raft nodes before linearized reading' (duration: 223.348425ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:23.611081Z","caller":"traceutil/trace.go:171","msg":"trace[972826060] linearizableReadLoop","detail":"{readStateIndex:25794; appliedIndex:25793; }","duration":"117.42117ms","start":"2026-06-25T23:11:23.49363Z","end":"2026-06-25T23:11:23.611052Z","steps":["trace[972826060] 'read index received' (duration: 113.300535ms)","trace[972826060] 'applied index is now lower than readState.Index' (duration: 4.119415ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:23.611101Z","caller":"traceutil/trace.go:171","msg":"trace[1300154511] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"176.295408ms","start":"2026-06-25T23:11:23.434766Z","end":"2026-06-25T23:11:23.611061Z","steps":["trace[1300154511] 'process raft request' (duration: 172.222034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:23.611248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.601633ms","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-25T23:11:23.611412Z","caller":"traceutil/trace.go:171","msg":"trace[429449308] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24978; }","duration":"117.780457ms","start":"2026-06-25T23:11:23.493618Z","end":"2026-06-25T23:11:23.611398Z","steps":["trace[429449308] 'agreement among raft nodes before linearized reading' (duration: 117.557552ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:24.140296Z","caller":"traceutil/trace.go:171","msg":"trace[1279548787] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25794; }","duration":"242.479015ms","start":"2026-06-25T23:11:23.897794Z","end":"2026-06-25T23:11:24.140273Z","steps":["trace[1279548787] 'read index received' (duration: 242.327042ms)","trace[1279548787] 'applied index is now lower than readState.Index' (duration: 150.463µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:24.140415Z","caller":"traceutil/trace.go:171","msg":"trace[194956594] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"258.573083ms","start":"2026-06-25T23:11:23.881812Z","end":"2026-06-25T23:11:24.140385Z","steps":["trace[194956594] 'process raft request' (duration: 258.299527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:24.140464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.659289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:24.140493Z","caller":"traceutil/trace.go:171","msg":"trace[886083499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24979; }","duration":"242.770212ms","start":"2026-06-25T23:11:23.897714Z","end":"2026-06-25T23:11:24.140484Z","steps":["trace[886083499] 'agreement among raft nodes before linearized reading' (duration: 242.68562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:24.140646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.702012ms","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:11:24.140716Z","caller":"traceutil/trace.go:171","msg":"trace[445454954] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24979; }","duration":"136.838705ms","start":"2026-06-25T23:11:24.003862Z","end":"2026-06-25T23:11:24.1407Z","steps":["trace[445454954] 'agreement among raft nodes before linearized reading' (duration: 136.60997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:24.586035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.992273ms","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:11:24.586123Z","caller":"traceutil/trace.go:171","msg":"trace[1096704472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24983; }","duration":"209.131216ms","start":"2026-06-25T23:11:24.376973Z","end":"2026-06-25T23:11:24.586104Z","steps":["trace[1096704472] 'range keys from in-memory index tree' (duration: 208.815771ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:24.586631Z","caller":"traceutil/trace.go:171","msg":"trace[244079008] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"102.164589ms","start":"2026-06-25T23:11:24.484364Z","end":"2026-06-25T23:11:24.586528Z","steps":["trace[244079008] 'process raft request' (duration: 88.255476ms)","trace[244079008] 'compare' (duration: 13.039745ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:24.885407Z","caller":"traceutil/trace.go:171","msg":"trace[1892241070] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"330.68586ms","start":"2026-06-25T23:11:24.554691Z","end":"2026-06-25T23:11:24.885377Z","steps":["trace[1892241070] 'process raft request' (duration: 330.523117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:24.885571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:24.554664Z","time spent":"330.816552ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:24.911164Z","caller":"traceutil/trace.go:171","msg":"trace[895480158] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"320.40805ms","start":"2026-06-25T23:11:24.590722Z","end":"2026-06-25T23:11:24.91113Z","steps":["trace[895480158] 'process raft request' (duration: 320.021393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:24.911308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:24.590684Z","time spent":"320.552144ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:24.911358Z","caller":"traceutil/trace.go:171","msg":"trace[1296432937] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"254.019991ms","start":"2026-06-25T23:11:24.65732Z","end":"2026-06-25T23:11:24.91134Z","steps":["trace[1296432937] 'process raft request' (duration: 253.648053ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:24.911411Z","caller":"traceutil/trace.go:171","msg":"trace[920878229] linearizableReadLoop","detail":"{readStateIndex:25803; appliedIndex:25801; }","duration":"154.364761ms","start":"2026-06-25T23:11:24.756905Z","end":"2026-06-25T23:11:24.91127Z","steps":["trace[920878229] 'read index received' (duration: 128.465704ms)","trace[920878229] 'applied index is now lower than readState.Index' (duration: 25.897687ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:24.911565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.657508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:24.91162Z","caller":"traceutil/trace.go:171","msg":"trace[464819008] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24988; }","duration":"154.77722ms","start":"2026-06-25T23:11:24.75683Z","end":"2026-06-25T23:11:24.911607Z","steps":["trace[464819008] 'agreement among raft nodes before linearized reading' (duration: 154.652487ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:25.033411Z","caller":"traceutil/trace.go:171","msg":"trace[974780187] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"101.252781ms","start":"2026-06-25T23:11:24.932124Z","end":"2026-06-25T23:11:25.033376Z","steps":["trace[974780187] 'process raft request' (duration: 99.127937ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:25.252679Z","caller":"traceutil/trace.go:171","msg":"trace[875772866] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"268.029936ms","start":"2026-06-25T23:11:24.984597Z","end":"2026-06-25T23:11:25.252627Z","steps":["trace[875772866] 'process raft request' (duration: 220.896156ms)","trace[875772866] 'compare' (duration: 46.748862ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:26.62778Z","caller":"traceutil/trace.go:171","msg":"trace[1833496014] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"314.288086ms","start":"2026-06-25T23:11:26.313461Z","end":"2026-06-25T23:11:26.627749Z","steps":["trace[1833496014] 'process raft request' (duration: 283.288355ms)","trace[1833496014] 'compare' (duration: 30.612534ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:26.627932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:26.31344Z","time spent":"314.412939ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:26.628486Z","caller":"traceutil/trace.go:171","msg":"trace[1023594616] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"314.869158ms","start":"2026-06-25T23:11:26.313604Z","end":"2026-06-25T23:11:26.628473Z","steps":["trace[1023594616] 'process raft request' (duration: 314.714055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:26.628555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:26.313563Z","time spent":"314.953579ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:26.628687Z","caller":"traceutil/trace.go:171","msg":"trace[2022394182] linearizableReadLoop","detail":"{readStateIndex:25814; appliedIndex:25811; }","duration":"169.168772ms","start":"2026-06-25T23:11:26.459487Z","end":"2026-06-25T23:11:26.628656Z","steps":["trace[2022394182] 'read index received' (duration: 137.215572ms)","trace[2022394182] 'applied index is now lower than readState.Index' (duration: 31.95224ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:26.62884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.327085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:26.628835Z","caller":"traceutil/trace.go:171","msg":"trace[440666339] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"315.047951ms","start":"2026-06-25T23:11:26.313754Z","end":"2026-06-25T23:11:26.628802Z","steps":["trace[440666339] 'process raft request' (duration: 314.682264ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:26.628899Z","caller":"traceutil/trace.go:171","msg":"trace[33372232] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24998; }","duration":"169.405467ms","start":"2026-06-25T23:11:26.45948Z","end":"2026-06-25T23:11:26.628886Z","steps":["trace[33372232] 'agreement among raft nodes before linearized reading' (duration: 169.267274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:26.629004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:26.313743Z","time spent":"315.167264ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:27.005944Z","caller":"traceutil/trace.go:171","msg":"trace[856563241] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"114.363668ms","start":"2026-06-25T23:11:26.891544Z","end":"2026-06-25T23:11:27.005908Z","steps":["trace[856563241] 'process raft request' (duration: 114.033061ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:27.173655Z","caller":"traceutil/trace.go:171","msg":"trace[1620410363] linearizableReadLoop","detail":"{readStateIndex:25817; appliedIndex:25816; }","duration":"135.218042ms","start":"2026-06-25T23:11:27.038416Z","end":"2026-06-25T23:11:27.173634Z","steps":["trace[1620410363] 'read index received' (duration: 135.001678ms)","trace[1620410363] 'applied index is now lower than readState.Index' (duration: 215.604µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:27.173761Z","caller":"traceutil/trace.go:171","msg":"trace[1883388021] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"162.244313ms","start":"2026-06-25T23:11:27.011508Z","end":"2026-06-25T23:11:27.173752Z","steps":["trace[1883388021] 'process raft request' (duration: 161.978677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:27.174254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.809625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-25T23:11:27.174351Z","caller":"traceutil/trace.go:171","msg":"trace[406006339] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25001; }","duration":"135.953417ms","start":"2026-06-25T23:11:27.038374Z","end":"2026-06-25T23:11:27.174327Z","steps":["trace[406006339] 'agreement among raft nodes before linearized reading' (duration: 135.671062ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:27.474219Z","caller":"traceutil/trace.go:171","msg":"trace[1761892402] linearizableReadLoop","detail":"{readStateIndex:25819; appliedIndex:25818; }","duration":"201.95124ms","start":"2026-06-25T23:11:27.272236Z","end":"2026-06-25T23:11:27.474188Z","steps":["trace[1761892402] 'read index received' (duration: 179.86056ms)","trace[1761892402] 'applied index is now lower than readState.Index' (duration: 22.08922ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:27.474262Z","caller":"traceutil/trace.go:171","msg":"trace[617521146] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"214.293422ms","start":"2026-06-25T23:11:27.259936Z","end":"2026-06-25T23:11:27.474229Z","steps":["trace[617521146] 'process raft request' (duration: 192.364735ms)","trace[617521146] 'compare' (duration: 21.617501ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:27.474493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.238316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-25T23:11:27.474591Z","caller":"traceutil/trace.go:171","msg":"trace[2014668684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25003; }","duration":"202.389509ms","start":"2026-06-25T23:11:27.272179Z","end":"2026-06-25T23:11:27.474569Z","steps":["trace[2014668684] 'agreement among raft nodes before linearized reading' (duration: 202.104343ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:27.799103Z","caller":"traceutil/trace.go:171","msg":"trace[419413508] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"139.100301ms","start":"2026-06-25T23:11:27.659959Z","end":"2026-06-25T23:11:27.799059Z","steps":["trace[419413508] 'process raft request' (duration: 127.370453ms)","trace[419413508] 'compare' (duration: 11.096115ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:30.886838Z","caller":"traceutil/trace.go:171","msg":"trace[494053698] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25840; }","duration":"241.309552ms","start":"2026-06-25T23:11:30.645505Z","end":"2026-06-25T23:11:30.886815Z","steps":["trace[494053698] 'read index received' (duration: 241.189189ms)","trace[494053698] 'applied index is now lower than readState.Index' (duration: 119.253µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:30.886982Z","caller":"traceutil/trace.go:171","msg":"trace[911439290] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"242.174519ms","start":"2026-06-25T23:11:30.644758Z","end":"2026-06-25T23:11:30.886932Z","steps":["trace[911439290] 'process raft request' (duration: 241.893113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:30.887264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.716589ms","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:11:30.887398Z","caller":"traceutil/trace.go:171","msg":"trace[1251578544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25024; }","duration":"241.927074ms","start":"2026-06-25T23:11:30.645446Z","end":"2026-06-25T23:11:30.887373Z","steps":["trace[1251578544] 'agreement among raft nodes before linearized reading' (duration: 241.505855ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:30.918688Z","caller":"traceutil/trace.go:171","msg":"trace[964258886] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"270.758541ms","start":"2026-06-25T23:11:30.647897Z","end":"2026-06-25T23:11:30.918656Z","steps":["trace[964258886] 'process raft request' (duration: 270.537806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.485041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.216848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:31.48513Z","caller":"traceutil/trace.go:171","msg":"trace[1038646905] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25026; }","duration":"516.346ms","start":"2026-06-25T23:11:30.968763Z","end":"2026-06-25T23:11:31.485109Z","steps":["trace[1038646905] 'count revisions from in-memory index tree' (duration: 516.157646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.485176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:30.96875Z","time spent":"516.411861ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-06-25T23:11:31.485713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.883687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:31.48582Z","caller":"traceutil/trace.go:171","msg":"trace[1747434560] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"467.244539ms","start":"2026-06-25T23:11:31.018552Z","end":"2026-06-25T23:11:31.485797Z","steps":["trace[1747434560] 'process raft request' (duration: 187.1862ms)","trace[1747434560] 'compare' (duration: 279.652861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:31.485882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:31.018517Z","time spent":"467.336081ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:31.495632Z","caller":"traceutil/trace.go:171","msg":"trace[249262388] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25843; }","duration":"437.497225ms","start":"2026-06-25T23:11:31.058112Z","end":"2026-06-25T23:11:31.49561Z","steps":["trace[249262388] 'read index received' (duration: 147.703737ms)","trace[249262388] 'applied index is now lower than readState.Index' (duration: 289.792668ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:31.495889Z","caller":"traceutil/trace.go:171","msg":"trace[906794893] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"476.11963ms","start":"2026-06-25T23:11:31.019759Z","end":"2026-06-25T23:11:31.495878Z","steps":["trace[906794893] 'process raft request' (duration: 475.730201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.495982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:31.019721Z","time spent":"476.194912ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:11:31.496144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.038086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:31.496177Z","caller":"traceutil/trace.go:171","msg":"trace[527127476] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25028; }","duration":"438.069117ms","start":"2026-06-25T23:11:31.058096Z","end":"2026-06-25T23:11:31.496165Z","steps":["trace[527127476] 'agreement among raft nodes before linearized reading' (duration: 438.017576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.4962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:31.058012Z","time spent":"438.182699ms","remote":"127.0.0.1:59662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-06-25T23:11:31.518745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.366193ms","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:11:31.518836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.739826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130955"} {"level":"info","ts":"2026-06-25T23:11:31.518847Z","caller":"traceutil/trace.go:171","msg":"trace[1306959656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25029; }","duration":"330.524297ms","start":"2026-06-25T23:11:31.188301Z","end":"2026-06-25T23:11:31.518825Z","steps":["trace[1306959656] 'agreement among raft nodes before linearized reading' (duration: 330.270672ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:31.518923Z","caller":"traceutil/trace.go:171","msg":"trace[1122664497] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25029; }","duration":"171.858158ms","start":"2026-06-25T23:11:31.347023Z","end":"2026-06-25T23:11:31.518881Z","steps":["trace[1122664497] 'agreement among raft nodes before linearized reading' (duration: 171.601783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.51897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:31.188266Z","time spent":"330.69026ms","remote":"127.0.0.1:39246","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:11:31.518968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.354391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-06-25T23:11:31.519018Z","caller":"traceutil/trace.go:171","msg":"trace[1579848741] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25029; }","duration":"209.460703ms","start":"2026-06-25T23:11:31.309545Z","end":"2026-06-25T23:11:31.519006Z","steps":["trace[1579848741] 'agreement among raft nodes before linearized reading' (duration: 209.28262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.51901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.825056ms","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:11:31.519111Z","caller":"traceutil/trace.go:171","msg":"trace[202102045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25029; }","duration":"311.949289ms","start":"2026-06-25T23:11:31.207137Z","end":"2026-06-25T23:11:31.519086Z","steps":["trace[202102045] 'agreement among raft nodes before linearized reading' (duration: 311.600842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:31.519159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:31.2071Z","time spent":"312.051051ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-25T23:11:31.518814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.710534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:31.519276Z","caller":"traceutil/trace.go:171","msg":"trace[130081158] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25029; }","duration":"285.208784ms","start":"2026-06-25T23:11:31.234052Z","end":"2026-06-25T23:11:31.519261Z","steps":["trace[130081158] 'agreement among raft nodes before linearized reading' (duration: 284.660983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:34.48809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.34315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:34.488332Z","caller":"traceutil/trace.go:171","msg":"trace[933266054] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"152.171817ms","start":"2026-06-25T23:11:34.336135Z","end":"2026-06-25T23:11:34.488307Z","steps":["trace[933266054] 'process raft request' (duration: 29.49419ms)","trace[933266054] 'compare' (duration: 122.178287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:37.227275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.533985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:37.227515Z","caller":"traceutil/trace.go:171","msg":"trace[69708119] linearizableReadLoop","detail":"{readStateIndex:25951; appliedIndex:25949; }","duration":"175.34667ms","start":"2026-06-25T23:11:37.052139Z","end":"2026-06-25T23:11:37.227485Z","steps":["trace[69708119] 'read index received' (duration: 18.684951ms)","trace[69708119] 'applied index is now lower than readState.Index' (duration: 156.660799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:37.22759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.461272ms","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:37.227626Z","caller":"traceutil/trace.go:171","msg":"trace[452342784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25131; }","duration":"175.533963ms","start":"2026-06-25T23:11:37.052081Z","end":"2026-06-25T23:11:37.227615Z","steps":["trace[452342784] 'agreement among raft nodes before linearized reading' (duration: 175.471192ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:37.227618Z","caller":"traceutil/trace.go:171","msg":"trace[433016611] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"209.984084ms","start":"2026-06-25T23:11:37.017602Z","end":"2026-06-25T23:11:37.227586Z","steps":["trace[433016611] 'process raft request' (duration: 53.033409ms)","trace[433016611] 'compare' (duration: 156.328651ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:37.227843Z","caller":"traceutil/trace.go:171","msg":"trace[172362148] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"210.158367ms","start":"2026-06-25T23:11:37.017647Z","end":"2026-06-25T23:11:37.227806Z","steps":["trace[172362148] 'process raft request' (duration: 209.737109ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:41.02605Z","caller":"traceutil/trace.go:171","msg":"trace[1609317711] linearizableReadLoop","detail":"{readStateIndex:25996; appliedIndex:25995; }","duration":"108.501768ms","start":"2026-06-25T23:11:40.917521Z","end":"2026-06-25T23:11:41.026022Z","steps":["trace[1609317711] 'read index received' (duration: 108.219482ms)","trace[1609317711] 'applied index is now lower than readState.Index' (duration: 281.286µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:41.026191Z","caller":"traceutil/trace.go:171","msg":"trace[1015864469] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"146.90042ms","start":"2026-06-25T23:11:40.879212Z","end":"2026-06-25T23:11:41.026112Z","steps":["trace[1015864469] 'process raft request' (duration: 146.620324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:41.026284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.739703ms","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:11:41.026329Z","caller":"traceutil/trace.go:171","msg":"trace[497612932] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25175; }","duration":"108.830445ms","start":"2026-06-25T23:11:40.917484Z","end":"2026-06-25T23:11:41.026315Z","steps":["trace[497612932] 'agreement among raft nodes before linearized reading' (duration: 108.655302ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:41.275215Z","caller":"traceutil/trace.go:171","msg":"trace[1044736835] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25996; }","duration":"238.070856ms","start":"2026-06-25T23:11:41.037118Z","end":"2026-06-25T23:11:41.275189Z","steps":["trace[1044736835] 'read index received' (duration: 238.002074ms)","trace[1044736835] 'applied index is now lower than readState.Index' (duration: 67.982µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:41.275353Z","caller":"traceutil/trace.go:171","msg":"trace[956272326] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"238.629568ms","start":"2026-06-25T23:11:41.036668Z","end":"2026-06-25T23:11:41.275298Z","steps":["trace[956272326] 'process raft request' (duration: 238.353761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:41.27546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.315302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-06-25T23:11:41.275506Z","caller":"traceutil/trace.go:171","msg":"trace[1206059567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:25176; }","duration":"238.386343ms","start":"2026-06-25T23:11:41.037107Z","end":"2026-06-25T23:11:41.275493Z","steps":["trace[1206059567] 'agreement among raft nodes before linearized reading' (duration: 238.206679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:41.335256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.786367ms","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:41.335262Z","caller":"traceutil/trace.go:171","msg":"trace[2079039880] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"292.489983ms","start":"2026-06-25T23:11:41.042735Z","end":"2026-06-25T23:11:41.335225Z","steps":["trace[2079039880] 'process raft request' (duration: 292.403391ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:41.335326Z","caller":"traceutil/trace.go:171","msg":"trace[1588458759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25178; }","duration":"286.884749ms","start":"2026-06-25T23:11:41.048428Z","end":"2026-06-25T23:11:41.335313Z","steps":["trace[1588458759] 'agreement among raft nodes before linearized reading' (duration: 286.752436ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:41.33547Z","caller":"traceutil/trace.go:171","msg":"trace[1871695822] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"293.204878ms","start":"2026-06-25T23:11:41.042211Z","end":"2026-06-25T23:11:41.335416Z","steps":["trace[1871695822] 'process raft request' (duration: 292.764369ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:56.509602Z","caller":"traceutil/trace.go:171","msg":"trace[851378292] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"171.247575ms","start":"2026-06-25T23:11:56.338326Z","end":"2026-06-25T23:11:56.509573Z","steps":["trace[851378292] 'process raft request' (duration: 171.031701ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:56.841331Z","caller":"traceutil/trace.go:171","msg":"trace[924775432] linearizableReadLoop","detail":"{readStateIndex:26129; appliedIndex:26128; }","duration":"254.187404ms","start":"2026-06-25T23:11:56.587116Z","end":"2026-06-25T23:11:56.841303Z","steps":["trace[924775432] 'read index received' (duration: 233.35685ms)","trace[924775432] 'applied index is now lower than readState.Index' (duration: 20.829114ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:56.841385Z","caller":"traceutil/trace.go:171","msg":"trace[1191813926] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"275.16066ms","start":"2026-06-25T23:11:56.566163Z","end":"2026-06-25T23:11:56.841324Z","steps":["trace[1191813926] 'process raft request' (duration: 254.467869ms)","trace[1191813926] 'compare' (duration: 20.256242ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:56.841502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.372598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:56.841794Z","caller":"traceutil/trace.go:171","msg":"trace[297762958] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25305; }","duration":"254.666933ms","start":"2026-06-25T23:11:56.587046Z","end":"2026-06-25T23:11:56.841713Z","steps":["trace[297762958] 'agreement among raft nodes before linearized reading' (duration: 254.384097ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:57.081921Z","caller":"traceutil/trace.go:171","msg":"trace[1916906530] linearizableReadLoop","detail":"{readStateIndex:26130; appliedIndex:26129; }","duration":"240.492075ms","start":"2026-06-25T23:11:56.841401Z","end":"2026-06-25T23:11:57.081893Z","steps":["trace[1916906530] 'read index received' (duration: 240.267471ms)","trace[1916906530] 'applied index is now lower than readState.Index' (duration: 223.044µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:11:57.082Z","caller":"traceutil/trace.go:171","msg":"trace[987703453] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"245.995426ms","start":"2026-06-25T23:11:56.835946Z","end":"2026-06-25T23:11:57.081941Z","steps":["trace[987703453] 'process raft request' (duration: 245.768252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:57.082106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.583444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:11:57.082186Z","caller":"traceutil/trace.go:171","msg":"trace[77163146] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25306; }","duration":"288.708596ms","start":"2026-06-25T23:11:56.793459Z","end":"2026-06-25T23:11:57.082167Z","steps":["trace[77163146] 'agreement among raft nodes before linearized reading' (duration: 288.543053ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:57.705633Z","caller":"traceutil/trace.go:171","msg":"trace[914722590] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"249.59993ms","start":"2026-06-25T23:11:57.456001Z","end":"2026-06-25T23:11:57.705601Z","steps":["trace[914722590] 'process raft request' (duration: 248.021147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:57.98821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.611497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-25T23:11:57.988505Z","caller":"traceutil/trace.go:171","msg":"trace[1633315048] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"531.816864ms","start":"2026-06-25T23:11:57.456657Z","end":"2026-06-25T23:11:57.988474Z","steps":["trace[1633315048] 'process raft request' (duration: 372.794407ms)","trace[1633315048] 'compare' (duration: 158.283081ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:57.988616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.456631Z","time spent":"531.922796ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:57.988763Z","caller":"traceutil/trace.go:171","msg":"trace[1866710896] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"386.996326ms","start":"2026-06-25T23:11:57.601751Z","end":"2026-06-25T23:11:57.988747Z","steps":["trace[1866710896] 'process raft request' (duration: 386.657849ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:57.988809Z","caller":"traceutil/trace.go:171","msg":"trace[1385664174] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"375.822958ms","start":"2026-06-25T23:11:57.612974Z","end":"2026-06-25T23:11:57.988797Z","steps":["trace[1385664174] 'process raft request' (duration: 375.466501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:57.988822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.601743Z","time spent":"387.045167ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T23:11:57.988865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.612967Z","time spent":"375.86962ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:57.988866Z","caller":"traceutil/trace.go:171","msg":"trace[256747327] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"396.45011ms","start":"2026-06-25T23:11:57.592365Z","end":"2026-06-25T23:11:57.988815Z","steps":["trace[256747327] 'process raft request' (duration: 395.98724ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:57.988905Z","caller":"traceutil/trace.go:171","msg":"trace[748182624] linearizableReadLoop","detail":"{readStateIndex:26136; appliedIndex:26131; }","duration":"343.120713ms","start":"2026-06-25T23:11:57.645682Z","end":"2026-06-25T23:11:57.988803Z","steps":["trace[748182624] 'read index received' (duration: 58.362638ms)","trace[748182624] 'applied index is now lower than readState.Index' (duration: 284.749575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T23:11:57.989073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.592336Z","time spent":"396.620872ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:11:57.988573Z","caller":"traceutil/trace.go:171","msg":"trace[536771876] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"248.353724ms","start":"2026-06-25T23:11:57.740209Z","end":"2026-06-25T23:11:57.988563Z","steps":["trace[536771876] 'process raft request' (duration: 248.275113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:57.989095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.39835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-25T23:11:57.989178Z","caller":"traceutil/trace.go:171","msg":"trace[441003762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25313; }","duration":"343.508321ms","start":"2026-06-25T23:11:57.645647Z","end":"2026-06-25T23:11:57.989155Z","steps":["trace[441003762] 'agreement among raft nodes before linearized reading' (duration: 343.233796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:57.989219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.64564Z","time spent":"343.570852ms","remote":"127.0.0.1:39246","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":"warn","ts":"2026-06-25T23:11:58.087244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.79388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-25T23:11:58.087303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.502323ms","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:11:58.087297Z","caller":"traceutil/trace.go:171","msg":"trace[15700078] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"185.335462ms","start":"2026-06-25T23:11:57.901911Z","end":"2026-06-25T23:11:58.087247Z","steps":["trace[15700078] 'process raft request' (duration: 184.884513ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:58.087387Z","caller":"traceutil/trace.go:171","msg":"trace[630960708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25314; }","duration":"404.643756ms","start":"2026-06-25T23:11:57.68272Z","end":"2026-06-25T23:11:58.087364Z","steps":["trace[630960708] 'agreement among raft nodes before linearized reading' (duration: 404.398121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:58.087419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.614094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:11:58.087449Z","caller":"traceutil/trace.go:171","msg":"trace[2023671354] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25314; }","duration":"144.709896ms","start":"2026-06-25T23:11:57.942732Z","end":"2026-06-25T23:11:58.087441Z","steps":["trace[2023671354] 'agreement among raft nodes before linearized reading' (duration: 144.644715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:58.08744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.682689Z","time spent":"404.742439ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T23:11:58.087404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.041534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-25T23:11:58.087552Z","caller":"traceutil/trace.go:171","msg":"trace[1686121248] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25314; }","duration":"433.226888ms","start":"2026-06-25T23:11:57.654305Z","end":"2026-06-25T23:11:58.087532Z","steps":["trace[1686121248] 'agreement among raft nodes before linearized reading' (duration: 432.969112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:58.08759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.654281Z","time spent":"433.29916ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-25T23:11:58.08734Z","caller":"traceutil/trace.go:171","msg":"trace[243114432] 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:25314; }","duration":"381.925492ms","start":"2026-06-25T23:11:57.705391Z","end":"2026-06-25T23:11:58.087317Z","steps":["trace[243114432] 'agreement among raft nodes before linearized reading' (duration: 381.746409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:58.08767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.705371Z","time spent":"382.2921ms","remote":"127.0.0.1:45642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-06-25T23:11:58.087677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.675143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-06-25T23:11:58.087789Z","caller":"traceutil/trace.go:171","msg":"trace[1139946215] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25314; }","duration":"366.857296ms","start":"2026-06-25T23:11:57.720901Z","end":"2026-06-25T23:11:58.087758Z","steps":["trace[1139946215] 'agreement among raft nodes before linearized reading' (duration: 366.183433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:58.08785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:11:57.72088Z","time spent":"366.958559ms","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-06-25T23:11:58.103468Z","caller":"traceutil/trace.go:171","msg":"trace[389041869] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"108.717963ms","start":"2026-06-25T23:11:57.994698Z","end":"2026-06-25T23:11:58.103416Z","steps":["trace[389041869] 'process raft request' (duration: 108.239723ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:11:58.88281Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006621.wal"} {"level":"info","ts":"2026-06-25T23:11:58.883583Z","caller":"traceutil/trace.go:171","msg":"trace[1068796662] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"221.137801ms","start":"2026-06-25T23:11:58.66242Z","end":"2026-06-25T23:11:58.883557Z","steps":["trace[1068796662] 'process raft request' (duration: 220.752633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:59.582429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.001615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-25T23:11:59.582511Z","caller":"traceutil/trace.go:171","msg":"trace[620739558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25321; }","duration":"127.094737ms","start":"2026-06-25T23:11:59.455398Z","end":"2026-06-25T23:11:59.582493Z","steps":["trace[620739558] 'range keys from in-memory index tree' (duration: 126.809501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:59.582559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.049479ms","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-25T23:11:59.582741Z","caller":"traceutil/trace.go:171","msg":"trace[1191284227] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25321; }","duration":"220.292024ms","start":"2026-06-25T23:11:59.362426Z","end":"2026-06-25T23:11:59.582718Z","steps":["trace[1191284227] 'range keys from in-memory index tree' (duration: 219.972537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:11:59.841469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.949476ms","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:11:59.841559Z","caller":"traceutil/trace.go:171","msg":"trace[2144135022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25322; }","duration":"131.103709ms","start":"2026-06-25T23:11:59.710433Z","end":"2026-06-25T23:11:59.841537Z","steps":["trace[2144135022] 'range keys from in-memory index tree' (duration: 130.723301ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:24.294549Z","caller":"traceutil/trace.go:171","msg":"trace[1533143835] linearizableReadLoop","detail":"{readStateIndex:26359; appliedIndex:26358; }","duration":"108.157922ms","start":"2026-06-25T23:12:24.186365Z","end":"2026-06-25T23:12:24.294523Z","steps":["trace[1533143835] 'read index received' (duration: 106.826715ms)","trace[1533143835] 'applied index is now lower than readState.Index' (duration: 1.330597ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:12:24.294728Z","caller":"traceutil/trace.go:171","msg":"trace[305851006] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"114.868798ms","start":"2026-06-25T23:12:24.179851Z","end":"2026-06-25T23:12:24.29472Z","steps":["trace[305851006] 'process raft request' (duration: 113.361697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:12:24.294973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.611531ms","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:12:24.294999Z","caller":"traceutil/trace.go:171","msg":"trace[1392389206] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25528; }","duration":"108.688312ms","start":"2026-06-25T23:12:24.186304Z","end":"2026-06-25T23:12:24.294992Z","steps":["trace[1392389206] 'agreement among raft nodes before linearized reading' (duration: 108.56758ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.232566Z","caller":"traceutil/trace.go:171","msg":"trace[1714954523] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"190.16718ms","start":"2026-06-25T23:12:30.042374Z","end":"2026-06-25T23:12:30.232541Z","steps":["trace[1714954523] 'process raft request' (duration: 189.997937ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.405603Z","caller":"traceutil/trace.go:171","msg":"trace[561500104] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"339.288845ms","start":"2026-06-25T23:12:30.066284Z","end":"2026-06-25T23:12:30.405573Z","steps":["trace[561500104] 'process raft request' (duration: 339.045761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:12:30.405807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:12:30.066264Z","time spent":"339.431499ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:12:30.406058Z","caller":"traceutil/trace.go:171","msg":"trace[1445761255] linearizableReadLoop","detail":"{readStateIndex:26402; appliedIndex:26396; }","duration":"205.782509ms","start":"2026-06-25T23:12:30.20026Z","end":"2026-06-25T23:12:30.406043Z","steps":["trace[1445761255] 'read index received' (duration: 32.194616ms)","trace[1445761255] 'applied index is now lower than readState.Index' (duration: 173.586943ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:12:30.406046Z","caller":"traceutil/trace.go:171","msg":"trace[847866180] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"305.787105ms","start":"2026-06-25T23:12:30.100199Z","end":"2026-06-25T23:12:30.405987Z","steps":["trace[847866180] 'process raft request' (duration: 305.317914ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.406155Z","caller":"traceutil/trace.go:171","msg":"trace[1260098357] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"225.884748ms","start":"2026-06-25T23:12:30.180262Z","end":"2026-06-25T23:12:30.406146Z","steps":["trace[1260098357] 'process raft request' (duration: 225.702364ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.406113Z","caller":"traceutil/trace.go:171","msg":"trace[1460326227] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"244.073088ms","start":"2026-06-25T23:12:30.162005Z","end":"2026-06-25T23:12:30.406078Z","steps":["trace[1460326227] 'process raft request' (duration: 243.921555ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.406208Z","caller":"traceutil/trace.go:171","msg":"trace[34136875] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"203.790148ms","start":"2026-06-25T23:12:30.202375Z","end":"2026-06-25T23:12:30.406165Z","steps":["trace[34136875] 'process raft request' (duration: 203.634105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:12:30.40629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T23:12:30.100179Z","time spent":"305.971938ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T23:12:30.406327Z","caller":"traceutil/trace.go:171","msg":"trace[1972685467] transaction","detail":"{read_only:false; response_revision:25568; number_of_response:1; }","duration":"246.142039ms","start":"2026-06-25T23:12:30.160173Z","end":"2026-06-25T23:12:30.406315Z","steps":["trace[1972685467] 'process raft request' (duration: 245.636809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:12:30.406336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.072625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-25T23:12:30.406394Z","caller":"traceutil/trace.go:171","msg":"trace[1030287018] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25571; }","duration":"206.158117ms","start":"2026-06-25T23:12:30.200223Z","end":"2026-06-25T23:12:30.406382Z","steps":["trace[1030287018] 'agreement among raft nodes before linearized reading' (duration: 205.987213ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.565509Z","caller":"traceutil/trace.go:171","msg":"trace[766010291] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"144.572003ms","start":"2026-06-25T23:12:30.420896Z","end":"2026-06-25T23:12:30.565468Z","steps":["trace[766010291] 'process raft request' (duration: 68.623576ms)","trace[766010291] 'compare' (duration: 75.756072ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:12:30.56676Z","caller":"traceutil/trace.go:171","msg":"trace[2131075872] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"145.750797ms","start":"2026-06-25T23:12:30.420976Z","end":"2026-06-25T23:12:30.566727Z","steps":["trace[2131075872] 'process raft request' (duration: 145.517462ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.567198Z","caller":"traceutil/trace.go:171","msg":"trace[959829200] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"145.94249ms","start":"2026-06-25T23:12:30.421225Z","end":"2026-06-25T23:12:30.567168Z","steps":["trace[959829200] 'process raft request' (duration: 145.449131ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:12:30.567296Z","caller":"traceutil/trace.go:171","msg":"trace[1482801480] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"141.791615ms","start":"2026-06-25T23:12:30.425471Z","end":"2026-06-25T23:12:30.567262Z","steps":["trace[1482801480] 'process raft request' (duration: 141.50047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:12:30.79272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.495118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-25T23:12:30.792796Z","caller":"traceutil/trace.go:171","msg":"trace[1628800001] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25576; }","duration":"104.636051ms","start":"2026-06-25T23:12:30.688142Z","end":"2026-06-25T23:12:30.792778Z","steps":["trace[1628800001] 'count revisions from in-memory index tree' (duration: 104.376005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T23:12:35.419364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.289005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-25T23:12:35.419505Z","caller":"traceutil/trace.go:171","msg":"trace[828336669] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25608; }","duration":"139.480559ms","start":"2026-06-25T23:12:35.279983Z","end":"2026-06-25T23:12:35.419464Z","steps":["trace[828336669] 'count revisions from in-memory index tree' (duration: 139.173931ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T23:13:29.95782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23214} {"level":"info","ts":"2026-06-25T23:13:30.100916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23214,"took":"138.727934ms","hash":2218632261,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":36118528,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-25T23:13:30.100983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2218632261,"revision":23214,"compact-revision":20196} {"level":"info","ts":"2026-06-25T23:18:29.96516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26179} {"level":"info","ts":"2026-06-25T23:18:30.122948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26179,"took":"152.858648ms","hash":296394826,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":34660352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-25T23:18:30.123037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":296394826,"revision":26179,"compact-revision":23214} {"level":"info","ts":"2026-06-25T23:18:40.673092Z","caller":"traceutil/trace.go:171","msg":"trace[1632505405] transaction","detail":"{read_only:false; response_revision:28960; number_of_response:1; }","duration":"114.499288ms","start":"2026-06-25T23:18:40.558554Z","end":"2026-06-25T23:18:40.673054Z","steps":["trace[1632505405] 'process raft request' (duration: 89.468529ms)","trace[1632505405] 'compare' (duration: 23.881896ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T23:18:56.754832Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T23:18:56.760446Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-25T23:18:56.760538Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-25T23:23:29.975134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28880} {"level":"info","ts":"2026-06-25T23:23:30.124158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28880,"took":"139.443145ms","hash":684044522,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":30482432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-25T23:23:30.124467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":684044522,"revision":28880,"compact-revision":26179} {"level":"info","ts":"2026-06-25T23:28:29.983074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31098} {"level":"info","ts":"2026-06-25T23:28:30.111314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31098,"took":"124.309803ms","hash":3732318292,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":30564352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-25T23:28:30.111387Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3732318292,"revision":31098,"compact-revision":28880} {"level":"info","ts":"2026-06-25T23:33:29.990343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33362} {"level":"info","ts":"2026-06-25T23:33:30.178573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33362,"took":"182.523183ms","hash":292108766,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T23:33:30.178681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292108766,"revision":33362,"compact-revision":31098} {"level":"info","ts":"2026-06-25T23:38:29.997069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35656} {"level":"info","ts":"2026-06-25T23:38:30.138328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35656,"took":"137.037901ms","hash":2344197248,"current-db-size-bytes":59809792,"current-db-size":"60 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-25T23:38:30.138395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344197248,"revision":35656,"compact-revision":33362} {"level":"info","ts":"2026-06-25T23:40:23.360681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-25T23:40:23.364864Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-25T23:40:23.364977Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-25T23:43:30.003101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37880} {"level":"info","ts":"2026-06-25T23:43:30.11878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37880,"took":"111.994955ms","hash":555125205,"current-db-size-bytes":59809792,"current-db-size":"60 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-25T23:43:30.11884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":555125205,"revision":37880,"compact-revision":35656}