{"level":"warn","ts":"2026-09-07T10:50:25.787545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:50:25.787759Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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://162.253.55.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-09-07T10:50:25.787941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:50:25.787973Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-07T10:50:25.78803Z","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-09-07T10:50:25.789014Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-09-07T10:50:25.789143Z","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://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-09-07T10:50:25.794697Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.110831ms"} {"level":"info","ts":"2026-09-07T10:50:25.803025Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-09-07T10:50:25.803146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:50:25.80317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-09-07T10:50:25.803186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:50:25.803201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-09-07T10:50:25.803283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-09-07T10:50:25.810771Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:50:25.813025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:50:25.814784Z","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-09-07T10:50:25.818427Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:50:25.818914Z","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-09-07T10:50:25.818911Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:50:25.819048Z","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-09-07T10:50:25.819109Z","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-09-07T10:50:25.821769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-09-07T10:50:25.822229Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-07T10:50:25.826365Z","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-09-07T10:50:25.826531Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-07T10:50:25.826589Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-07T10:50:25.826973Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:50:25.827076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:50:26.00409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:50:26.004202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:50:26.00424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-09-07T10:50:26.004271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:50:26.00429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-07T10:50:26.00431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-09-07T10:50:26.004333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-07T10:50:26.006767Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:26.007042Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:50:26.00704Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:50:26.007061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:50:26.007472Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:50:26.007512Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:50:26.00842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:26.008603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:26.008665Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:26.010743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T10:50:26.011119Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-09-07T10:51:04.225262Z","caller":"traceutil/trace.go:171","msg":"trace[927562131] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"217.943949ms","start":"2026-09-07T10:51:04.007243Z","end":"2026-09-07T10:51:04.225187Z","steps":["trace[927562131] 'process raft request' (duration: 212.351484ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:04.225801Z","caller":"traceutil/trace.go:171","msg":"trace[944992650] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"213.344853ms","start":"2026-09-07T10:51:04.012441Z","end":"2026-09-07T10:51:04.225786Z","steps":["trace[944992650] 'process raft request' (duration: 212.615191ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:04.226079Z","caller":"traceutil/trace.go:171","msg":"trace[144251640] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"205.76382ms","start":"2026-09-07T10:51:04.020272Z","end":"2026-09-07T10:51:04.226036Z","steps":["trace[144251640] 'read index received' (duration: 199.371562ms)","trace[144251640] 'applied index is now lower than readState.Index' (duration: 6.390358ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:04.226338Z","caller":"traceutil/trace.go:171","msg":"trace[257186496] transaction","detail":"{read_only:false; number_of_response:1; response_revision:574; }","duration":"203.106392ms","start":"2026-09-07T10:51:04.023017Z","end":"2026-09-07T10:51:04.226124Z","steps":["trace[257186496] 'process raft request' (duration: 202.613768ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:04.22659Z","caller":"traceutil/trace.go:171","msg":"trace[1006936975] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"149.54457ms","start":"2026-09-07T10:51:04.077021Z","end":"2026-09-07T10:51:04.226566Z","steps":["trace[1006936975] 'process raft request' (duration: 148.82282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:04.226915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.621796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T10:51:04.227027Z","caller":"traceutil/trace.go:171","msg":"trace[835409670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"206.77724ms","start":"2026-09-07T10:51:04.020227Z","end":"2026-09-07T10:51:04.227004Z","steps":["trace[835409670] 'agreement among raft nodes before linearized reading' (duration: 206.621406ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:10.679107Z","caller":"traceutil/trace.go:171","msg":"trace[1048370320] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"140.383441ms","start":"2026-09-07T10:51:10.538679Z","end":"2026-09-07T10:51:10.679063Z","steps":["trace[1048370320] 'process raft request' (duration: 140.114433ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:11.044488Z","caller":"traceutil/trace.go:171","msg":"trace[1556425781] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"309.174621ms","start":"2026-09-07T10:51:10.735278Z","end":"2026-09-07T10:51:11.044453Z","steps":["trace[1556425781] 'process raft request' (duration: 308.939664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:11.045189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:10.735245Z","time spent":"309.339566ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:51:11.711791Z","caller":"traceutil/trace.go:171","msg":"trace[615426691] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"557.581158ms","start":"2026-09-07T10:51:11.154185Z","end":"2026-09-07T10:51:11.711766Z","steps":["trace[615426691] 'process raft request' (duration: 556.987761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:11.712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:11.154144Z","time spent":"557.764814ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:51:28.13634Z","caller":"traceutil/trace.go:171","msg":"trace[1040355546] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"120.123904ms","start":"2026-09-07T10:51:28.016191Z","end":"2026-09-07T10:51:28.136315Z","steps":["trace[1040355546] 'read index received' (duration: 119.935559ms)","trace[1040355546] 'applied index is now lower than readState.Index' (duration: 187.035µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:28.136419Z","caller":"traceutil/trace.go:171","msg":"trace[2136418543] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"225.403759ms","start":"2026-09-07T10:51:27.91098Z","end":"2026-09-07T10:51:28.136384Z","steps":["trace[2136418543] 'process raft request' (duration: 225.156262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:28.136776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.566187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T10:51:28.13685Z","caller":"traceutil/trace.go:171","msg":"trace[936940841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"120.69026ms","start":"2026-09-07T10:51:28.016138Z","end":"2026-09-07T10:51:28.136828Z","steps":["trace[936940841] 'agreement among raft nodes before linearized reading' (duration: 120.353261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:21.532235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.107949ms","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-09-07T10:52:21.532356Z","caller":"traceutil/trace.go:171","msg":"trace[621543267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1461; }","duration":"110.316315ms","start":"2026-09-07T10:52:21.422016Z","end":"2026-09-07T10:52:21.532332Z","steps":["trace[621543267] 'range keys from in-memory index tree' (duration: 109.824441ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:52:38.957098Z","caller":"traceutil/trace.go:171","msg":"trace[1552232505] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"107.001197ms","start":"2026-09-07T10:52:38.850074Z","end":"2026-09-07T10:52:38.957076Z","steps":["trace[1552232505] 'process raft request' (duration: 105.448681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:46.348396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.145549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-07T10:52:46.348572Z","caller":"traceutil/trace.go:171","msg":"trace[10722625] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1865; }","duration":"104.371646ms","start":"2026-09-07T10:52:46.244168Z","end":"2026-09-07T10:52:46.348539Z","steps":["trace[10722625] 'range keys from in-memory index tree' (duration: 103.949872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:46.777907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.651815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:52:46.778039Z","caller":"traceutil/trace.go:171","msg":"trace[1376813012] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"228.925345ms","start":"2026-09-07T10:52:46.549091Z","end":"2026-09-07T10:52:46.778017Z","steps":["trace[1376813012] 'read index received' (duration: 109.969631ms)","trace[1376813012] 'applied index is now lower than readState.Index' (duration: 118.953864ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:52:46.778145Z","caller":"traceutil/trace.go:171","msg":"trace[719070763] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"289.449482ms","start":"2026-09-07T10:52:46.488683Z","end":"2026-09-07T10:52:46.778132Z","steps":["trace[719070763] 'process raft request' (duration: 170.263821ms)","trace[719070763] 'compare' (duration: 118.343436ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:52:46.778535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.696277ms","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-09-07T10:52:46.778621Z","caller":"traceutil/trace.go:171","msg":"trace[1755379086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"181.849471ms","start":"2026-09-07T10:52:46.596752Z","end":"2026-09-07T10:52:46.778601Z","steps":["trace[1755379086] 'agreement among raft nodes before linearized reading' (duration: 181.551423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:46.778708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.568393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:52:46.779047Z","caller":"traceutil/trace.go:171","msg":"trace[126837753] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1873; }","duration":"229.805219ms","start":"2026-09-07T10:52:46.54903Z","end":"2026-09-07T10:52:46.778835Z","steps":["trace[126837753] 'agreement among raft nodes before linearized reading' (duration: 229.370007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:59.619136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.253341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:52:59.619264Z","caller":"traceutil/trace.go:171","msg":"trace[695368702] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2055; }","duration":"112.474403ms","start":"2026-09-07T10:52:59.506771Z","end":"2026-09-07T10:52:59.619245Z","steps":["trace[695368702] 'read index received' (duration: 96.942µs)","trace[695368702] 'applied index is now lower than readState.Index' (duration: 112.375871ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:52:59.619363Z","caller":"traceutil/trace.go:171","msg":"trace[1442806491] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"156.113975ms","start":"2026-09-07T10:52:59.463237Z","end":"2026-09-07T10:52:59.619351Z","steps":["trace[1442806491] 'process raft request' (duration: 16.546205ms)","trace[1442806491] 'compare' (duration: 139.100737ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:52:59.619928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.021139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-07T10:52:59.620128Z","caller":"traceutil/trace.go:171","msg":"trace[1488978758] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1978; }","duration":"113.376431ms","start":"2026-09-07T10:52:59.506698Z","end":"2026-09-07T10:52:59.620074Z","steps":["trace[1488978758] 'agreement among raft nodes before linearized reading' (duration: 112.781553ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:52:59.87493Z","caller":"traceutil/trace.go:171","msg":"trace[1434477340] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2057; }","duration":"179.7836ms","start":"2026-09-07T10:52:59.695124Z","end":"2026-09-07T10:52:59.874908Z","steps":["trace[1434477340] 'read index received' (duration: 179.564954ms)","trace[1434477340] 'applied index is now lower than readState.Index' (duration: 217.706µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:52:59.874966Z","caller":"traceutil/trace.go:171","msg":"trace[1146900074] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"189.63918ms","start":"2026-09-07T10:52:59.685302Z","end":"2026-09-07T10:52:59.874941Z","steps":["trace[1146900074] 'process raft request' (duration: 189.442575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:59.875253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.406154ms","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-09-07T10:52:59.875306Z","caller":"traceutil/trace.go:171","msg":"trace[876661338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1979; }","duration":"123.497907ms","start":"2026-09-07T10:52:59.751792Z","end":"2026-09-07T10:52:59.87529Z","steps":["trace[876661338] 'agreement among raft nodes before linearized reading' (duration: 123.301002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:59.875591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.413789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:52:59.875713Z","caller":"traceutil/trace.go:171","msg":"trace[2119294069] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1979; }","duration":"180.624765ms","start":"2026-09-07T10:52:59.695063Z","end":"2026-09-07T10:52:59.875687Z","steps":["trace[2119294069] 'agreement among raft nodes before linearized reading' (duration: 179.957886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:59.875744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.133015ms","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-09-07T10:52:59.875812Z","caller":"traceutil/trace.go:171","msg":"trace[1041113172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1979; }","duration":"125.232798ms","start":"2026-09-07T10:52:59.75054Z","end":"2026-09-07T10:52:59.875772Z","steps":["trace[1041113172] 'agreement among raft nodes before linearized reading' (duration: 124.497007ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:53:00.034242Z","caller":"traceutil/trace.go:171","msg":"trace[1731951673] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2058; }","duration":"151.999084ms","start":"2026-09-07T10:52:59.882185Z","end":"2026-09-07T10:53:00.034184Z","steps":["trace[1731951673] 'read index received' (duration: 51.683478ms)","trace[1731951673] 'applied index is now lower than readState.Index' (duration: 100.314116ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:53:00.034523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.296843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T10:53:00.034574Z","caller":"traceutil/trace.go:171","msg":"trace[1855583740] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"152.890541ms","start":"2026-09-07T10:52:59.881631Z","end":"2026-09-07T10:53:00.034521Z","steps":["trace[1855583740] 'process raft request' (duration: 79.732262ms)","trace[1855583740] 'compare' (duration: 72.607343ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:53:00.034575Z","caller":"traceutil/trace.go:171","msg":"trace[776379302] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1981; }","duration":"152.407927ms","start":"2026-09-07T10:52:59.882153Z","end":"2026-09-07T10:53:00.034561Z","steps":["trace[776379302] 'agreement among raft nodes before linearized reading' (duration: 152.202091ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:53:02.210892Z","caller":"traceutil/trace.go:171","msg":"trace[1680041246] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"166.850481ms","start":"2026-09-07T10:53:02.044008Z","end":"2026-09-07T10:53:02.210858Z","steps":["trace[1680041246] 'process raft request' (duration: 166.625324ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:53:36.726631Z","caller":"traceutil/trace.go:171","msg":"trace[2058963803] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"315.878458ms","start":"2026-09-07T10:53:36.410715Z","end":"2026-09-07T10:53:36.726593Z","steps":["trace[2058963803] 'process raft request' (duration: 315.65399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:53:36.726845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:53:36.410689Z","time spent":"316.047113ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:53:37.030782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.837026ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:53:37.030878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.416292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-07T10:53:37.030928Z","caller":"traceutil/trace.go:171","msg":"trace[156169106] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2193; }","duration":"205.99645ms","start":"2026-09-07T10:53:36.824905Z","end":"2026-09-07T10:53:37.030901Z","steps":["trace[156169106] 'range keys from in-memory index tree' (duration: 205.818865ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:53:37.030943Z","caller":"traceutil/trace.go:171","msg":"trace[1279362107] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2193; }","duration":"128.514035ms","start":"2026-09-07T10:53:36.902405Z","end":"2026-09-07T10:53:37.030919Z","steps":["trace[1279362107] 'range keys from in-memory index tree' (duration: 128.197266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:54:25.731545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.428938ms","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-09-07T10:54:25.731642Z","caller":"traceutil/trace.go:171","msg":"trace[917198545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2628; }","duration":"223.612504ms","start":"2026-09-07T10:54:25.508013Z","end":"2026-09-07T10:54:25.731625Z","steps":["trace[917198545] 'range keys from in-memory index tree' (duration: 223.267104ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:59.289662Z","caller":"traceutil/trace.go:171","msg":"trace[745144503] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"110.738787ms","start":"2026-09-07T10:54:59.1789Z","end":"2026-09-07T10:54:59.289639Z","steps":["trace[745144503] 'process raft request' (duration: 110.616893ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:59.302629Z","caller":"traceutil/trace.go:171","msg":"trace[1311470929] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"122.879514ms","start":"2026-09-07T10:54:59.179723Z","end":"2026-09-07T10:54:59.302602Z","steps":["trace[1311470929] 'process raft request' (duration: 122.74352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:54:59.842272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.242818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:54:59.842441Z","caller":"traceutil/trace.go:171","msg":"trace[1771452346] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"197.762267ms","start":"2026-09-07T10:54:59.644664Z","end":"2026-09-07T10:54:59.842427Z","steps":["trace[1771452346] 'process raft request' (duration: 197.737017ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:59.842643Z","caller":"traceutil/trace.go:171","msg":"trace[225044696] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"201.489286ms","start":"2026-09-07T10:54:59.641145Z","end":"2026-09-07T10:54:59.842634Z","steps":["trace[225044696] 'process raft request' (duration: 82.793405ms)","trace[225044696] 'compare' (duration: 118.036821ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:54:59.842672Z","caller":"traceutil/trace.go:171","msg":"trace[1499306696] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"198.505189ms","start":"2026-09-07T10:54:59.64414Z","end":"2026-09-07T10:54:59.842645Z","steps":["trace[1499306696] 'process raft request' (duration: 198.21653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:57:41.505968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.278783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:57:41.506244Z","caller":"traceutil/trace.go:171","msg":"trace[1913521940] transaction","detail":"{read_only:false; response_revision:3817; number_of_response:1; }","duration":"110.328719ms","start":"2026-09-07T10:57:41.395862Z","end":"2026-09-07T10:57:41.506191Z","steps":["trace[1913521940] 'compare' (duration: 106.048647ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:57:41.506399Z","caller":"traceutil/trace.go:171","msg":"trace[1800170553] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"110.171405ms","start":"2026-09-07T10:57:41.39621Z","end":"2026-09-07T10:57:41.506381Z","steps":["trace[1800170553] 'process raft request' (duration: 109.909377ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:57:41.506542Z","caller":"traceutil/trace.go:171","msg":"trace[466857448] linearizableReadLoop","detail":"{readStateIndex:3969; appliedIndex:3967; }","duration":"108.982471ms","start":"2026-09-07T10:57:41.397272Z","end":"2026-09-07T10:57:41.506254Z","steps":["trace[466857448] 'read index received' (duration: 2.213594ms)","trace[466857448] 'applied index is now lower than readState.Index' (duration: 106.767787ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:57:41.50656Z","caller":"traceutil/trace.go:171","msg":"trace[438474853] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"107.798557ms","start":"2026-09-07T10:57:41.398728Z","end":"2026-09-07T10:57:41.506526Z","steps":["trace[438474853] 'process raft request' (duration: 107.477698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:57:41.506633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.366901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:57:41.506743Z","caller":"traceutil/trace.go:171","msg":"trace[1385741782] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io; range_end:; response_count:0; response_revision:3819; }","duration":"109.520445ms","start":"2026-09-07T10:57:41.397208Z","end":"2026-09-07T10:57:41.506728Z","steps":["trace[1385741782] 'agreement among raft nodes before linearized reading' (duration: 109.373771ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:58:13.906922Z","caller":"traceutil/trace.go:171","msg":"trace[1985444425] linearizableReadLoop","detail":"{readStateIndex:4214; appliedIndex:4213; }","duration":"166.931144ms","start":"2026-09-07T10:58:13.739966Z","end":"2026-09-07T10:58:13.906898Z","steps":["trace[1985444425] 'read index received' (duration: 166.762098ms)","trace[1985444425] 'applied index is now lower than readState.Index' (duration: 167.666µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:58:13.907073Z","caller":"traceutil/trace.go:171","msg":"trace[2080364396] transaction","detail":"{read_only:false; response_revision:4057; number_of_response:1; }","duration":"215.539762ms","start":"2026-09-07T10:58:13.691482Z","end":"2026-09-07T10:58:13.907022Z","steps":["trace[2080364396] 'process raft request' (duration: 215.281164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:58:13.907262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.285544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xqq2m\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T10:58:13.90737Z","caller":"traceutil/trace.go:171","msg":"trace[884234509] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xqq2m; range_end:; response_count:1; response_revision:4057; }","duration":"167.454559ms","start":"2026-09-07T10:58:13.73989Z","end":"2026-09-07T10:58:13.907345Z","steps":["trace[884234509] 'agreement among raft nodes before linearized reading' (duration: 167.14328ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:39.615553Z","caller":"traceutil/trace.go:171","msg":"trace[2141291839] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"104.987655ms","start":"2026-09-07T10:59:39.510534Z","end":"2026-09-07T10:59:39.615522Z","steps":["trace[2141291839] 'process raft request' (duration: 71.25041ms)","trace[2141291839] 'compare' (duration: 33.484727ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:59:45.82427Z","caller":"traceutil/trace.go:171","msg":"trace[810234328] linearizableReadLoop","detail":"{readStateIndex:5313; appliedIndex:5312; }","duration":"186.015461ms","start":"2026-09-07T10:59:45.638237Z","end":"2026-09-07T10:59:45.824252Z","steps":["trace[810234328] 'read index received' (duration: 185.927628ms)","trace[810234328] 'applied index is now lower than readState.Index' (duration: 86.873µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:59:45.824324Z","caller":"traceutil/trace.go:171","msg":"trace[1225292408] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"207.504707ms","start":"2026-09-07T10:59:45.616786Z","end":"2026-09-07T10:59:45.824291Z","steps":["trace[1225292408] 'process raft request' (duration: 207.349373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:45.824432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.187646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:59:45.824462Z","caller":"traceutil/trace.go:171","msg":"trace[1615003846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5112; }","duration":"186.257398ms","start":"2026-09-07T10:59:45.638197Z","end":"2026-09-07T10:59:45.824455Z","steps":["trace[1615003846] 'agreement among raft nodes before linearized reading' (duration: 186.133514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:49.244231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.959639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:59:49.244334Z","caller":"traceutil/trace.go:171","msg":"trace[1044486595] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5269; }","duration":"222.121495ms","start":"2026-09-07T10:59:49.02219Z","end":"2026-09-07T10:59:49.244312Z","steps":["trace[1044486595] 'range keys from in-memory index tree' (duration: 221.857957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:49.244391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.092309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2782bae-9652-4906-966e-e761388d305a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-07T10:59:49.244446Z","caller":"traceutil/trace.go:171","msg":"trace[1647470523] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2782bae-9652-4906-966e-e761388d305a; range_end:; response_count:1; response_revision:5269; }","duration":"140.159461ms","start":"2026-09-07T10:59:49.104274Z","end":"2026-09-07T10:59:49.244433Z","steps":["trace[1647470523] 'range keys from in-memory index tree' (duration: 139.920705ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:49.408525Z","caller":"traceutil/trace.go:171","msg":"trace[297860595] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5270; }","duration":"100.33761ms","start":"2026-09-07T10:59:49.308163Z","end":"2026-09-07T10:59:49.408501Z","steps":["trace[297860595] 'process raft request' (duration: 94.904711ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:49.690741Z","caller":"traceutil/trace.go:171","msg":"trace[625986874] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"118.715085ms","start":"2026-09-07T10:59:49.572004Z","end":"2026-09-07T10:59:49.690719Z","steps":["trace[625986874] 'process raft request' (duration: 118.600002ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:49.690779Z","caller":"traceutil/trace.go:171","msg":"trace[1388474093] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"118.408546ms","start":"2026-09-07T10:59:49.572348Z","end":"2026-09-07T10:59:49.690757Z","steps":["trace[1388474093] 'process raft request' (duration: 118.298743ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:49.690725Z","caller":"traceutil/trace.go:171","msg":"trace[1215703719] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"119.397296ms","start":"2026-09-07T10:59:49.571295Z","end":"2026-09-07T10:59:49.690692Z","steps":["trace[1215703719] 'process raft request' (duration: 119.138268ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:58.057196Z","caller":"traceutil/trace.go:171","msg":"trace[2097365515] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"118.622664ms","start":"2026-09-07T10:59:57.938537Z","end":"2026-09-07T10:59:58.057159Z","steps":["trace[2097365515] 'process raft request' (duration: 98.708333ms)","trace[2097365515] 'compare' (duration: 19.681944ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:59:58.057233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.01678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:59:58.057308Z","caller":"traceutil/trace.go:171","msg":"trace[540560307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5459; }","duration":"100.142914ms","start":"2026-09-07T10:59:57.957148Z","end":"2026-09-07T10:59:58.057291Z","steps":["trace[540560307] 'agreement among raft nodes before linearized reading' (duration: 99.913647ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:03.431537Z","caller":"traceutil/trace.go:171","msg":"trace[1373562958] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"254.734597ms","start":"2026-09-07T11:00:03.176778Z","end":"2026-09-07T11:00:03.431512Z","steps":["trace[1373562958] 'process raft request' (duration: 254.548131ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:03.485001Z","caller":"traceutil/trace.go:171","msg":"trace[1417176889] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"156.384535ms","start":"2026-09-07T11:00:03.328593Z","end":"2026-09-07T11:00:03.484977Z","steps":["trace[1417176889] 'process raft request' (duration: 156.215501ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:03.583268Z","caller":"traceutil/trace.go:171","msg":"trace[1587215047] linearizableReadLoop","detail":"{readStateIndex:5752; appliedIndex:5750; }","duration":"131.334704ms","start":"2026-09-07T11:00:03.451909Z","end":"2026-09-07T11:00:03.583244Z","steps":["trace[1587215047] 'read index received' (duration: 32.91489ms)","trace[1587215047] 'applied index is now lower than readState.Index' (duration: 98.418644ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:00:03.583391Z","caller":"traceutil/trace.go:171","msg":"trace[1098173565] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"149.851835ms","start":"2026-09-07T11:00:03.433526Z","end":"2026-09-07T11:00:03.583378Z","steps":["trace[1098173565] 'process raft request' (duration: 107.638163ms)","trace[1098173565] 'compare' (duration: 41.934433ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:00:03.583693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.747116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:00:03.583806Z","caller":"traceutil/trace.go:171","msg":"trace[1832531023] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5538; }","duration":"131.929841ms","start":"2026-09-07T11:00:03.451847Z","end":"2026-09-07T11:00:03.583777Z","steps":["trace[1832531023] 'agreement among raft nodes before linearized reading' (duration: 131.711185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.057564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.137633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:00:15.058341Z","caller":"traceutil/trace.go:171","msg":"trace[1896141697] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"156.950052ms","start":"2026-09-07T11:00:14.901307Z","end":"2026-09-07T11:00:15.058257Z","steps":["trace[1896141697] 'process raft request' (duration: 28.968316ms)","trace[1896141697] 'compare' (duration: 126.823762ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:00:15.162758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.081432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-l5lg2\" ","response":"range_response_count:1 size:8467"} {"level":"info","ts":"2026-09-07T11:00:15.162947Z","caller":"traceutil/trace.go:171","msg":"trace[110948819] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-l5lg2; range_end:; response_count:1; response_revision:5760; }","duration":"100.322769ms","start":"2026-09-07T11:00:15.062588Z","end":"2026-09-07T11:00:15.162911Z","steps":["trace[110948819] 'agreement among raft nodes before linearized reading' (duration: 99.943538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.364285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.392893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:00:15.364514Z","caller":"traceutil/trace.go:171","msg":"trace[937385108] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"185.929878ms","start":"2026-09-07T11:00:15.178564Z","end":"2026-09-07T11:00:15.364494Z","steps":["trace[937385108] 'process raft request' (duration: 185.833076ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.364514Z","caller":"traceutil/trace.go:171","msg":"trace[1903351238] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"254.542101ms","start":"2026-09-07T11:00:15.109913Z","end":"2026-09-07T11:00:15.364456Z","steps":["trace[1903351238] 'process raft request' (duration: 81.927312ms)","trace[1903351238] 'compare' (duration: 172.083853ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:00:15.364887Z","caller":"traceutil/trace.go:171","msg":"trace[1657456190] linearizableReadLoop","detail":"{readStateIndex:5981; appliedIndex:5980; }","duration":"202.371578ms","start":"2026-09-07T11:00:15.162459Z","end":"2026-09-07T11:00:15.36483Z","steps":["trace[1657456190] 'read index received' (duration: 29.281994ms)","trace[1657456190] 'applied index is now lower than readState.Index' (duration: 173.087214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:00:15.36507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.441448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:00:15.365125Z","caller":"traceutil/trace.go:171","msg":"trace[402363880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5763; }","duration":"191.557983ms","start":"2026-09-07T11:00:15.173556Z","end":"2026-09-07T11:00:15.365114Z","steps":["trace[402363880] 'agreement among raft nodes before linearized reading' (duration: 191.473051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.365061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.077621ms","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":"warn","ts":"2026-09-07T11:00:15.365156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.694048ms","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-09-07T11:00:15.365208Z","caller":"traceutil/trace.go:171","msg":"trace[1113787074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5763; }","duration":"263.259675ms","start":"2026-09-07T11:00:15.101934Z","end":"2026-09-07T11:00:15.365194Z","steps":["trace[1113787074] 'agreement among raft nodes before linearized reading' (duration: 262.908695ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.365246Z","caller":"traceutil/trace.go:171","msg":"trace[666585500] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5763; }","duration":"263.888093ms","start":"2026-09-07T11:00:15.101332Z","end":"2026-09-07T11:00:15.36522Z","steps":["trace[666585500] 'agreement among raft nodes before linearized reading' (duration: 263.647546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.365368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.154222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-07T11:00:15.365431Z","caller":"traceutil/trace.go:171","msg":"trace[1042771920] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5763; }","duration":"116.269976ms","start":"2026-09-07T11:00:15.249144Z","end":"2026-09-07T11:00:15.365414Z","steps":["trace[1042771920] 'agreement among raft nodes before linearized reading' (duration: 116.072919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.742589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.641977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:00:15.743077Z","caller":"traceutil/trace.go:171","msg":"trace[2025846642] transaction","detail":"{read_only:false; response_revision:5765; number_of_response:1; }","duration":"372.089603ms","start":"2026-09-07T11:00:15.370952Z","end":"2026-09-07T11:00:15.743041Z","steps":["trace[2025846642] 'process raft request' (duration: 371.746384ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.743229Z","caller":"traceutil/trace.go:171","msg":"trace[1783813880] transaction","detail":"{read_only:false; response_revision:5766; number_of_response:1; }","duration":"371.943308ms","start":"2026-09-07T11:00:15.37126Z","end":"2026-09-07T11:00:15.743204Z","steps":["trace[1783813880] 'process raft request' (duration: 371.570837ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.743288Z","caller":"traceutil/trace.go:171","msg":"trace[1257604029] transaction","detail":"{read_only:false; response_revision:5770; number_of_response:1; }","duration":"369.958291ms","start":"2026-09-07T11:00:15.373308Z","end":"2026-09-07T11:00:15.743267Z","steps":["trace[1257604029] 'process raft request' (duration: 369.774686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.74329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.370925Z","time spent":"372.267768ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:00:15.743317Z","caller":"traceutil/trace.go:171","msg":"trace[503088361] linearizableReadLoop","detail":"{readStateIndex:5990; appliedIndex:5982; }","duration":"255.083587ms","start":"2026-09-07T11:00:15.488221Z","end":"2026-09-07T11:00:15.743305Z","steps":["trace[503088361] 'read index received' (duration: 4.738099ms)","trace[503088361] 'applied index is now lower than readState.Index' (duration: 250.344468ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:00:15.743325Z","caller":"traceutil/trace.go:171","msg":"trace[2011451173] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"364.773399ms","start":"2026-09-07T11:00:15.378526Z","end":"2026-09-07T11:00:15.743299Z","steps":["trace[2011451173] 'process raft request' (duration: 364.604584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.743382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.373296Z","time spent":"370.038013ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1222,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:00:15.743403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.371243Z","time spent":"372.072551ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:00:15.743457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.378512Z","time spent":"364.887002ms","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6941,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:00:15.743498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.261853ms","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-09-07T11:00:15.743548Z","caller":"traceutil/trace.go:171","msg":"trace[1501274805] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5772; }","duration":"255.388896ms","start":"2026-09-07T11:00:15.488146Z","end":"2026-09-07T11:00:15.743535Z","steps":["trace[1501274805] 'agreement among raft nodes before linearized reading' (duration: 255.263802ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.743557Z","caller":"traceutil/trace.go:171","msg":"trace[1767688525] transaction","detail":"{read_only:false; response_revision:5764; number_of_response:1; }","duration":"372.875756ms","start":"2026-09-07T11:00:15.370652Z","end":"2026-09-07T11:00:15.743528Z","steps":["trace[1767688525] 'process raft request' (duration: 122.212938ms)","trace[1767688525] 'compare' (duration: 249.374821ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:00:15.743645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.370628Z","time spent":"372.969299ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:00:15.743266Z","caller":"traceutil/trace.go:171","msg":"trace[1219520881] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"216.109729ms","start":"2026-09-07T11:00:15.527132Z","end":"2026-09-07T11:00:15.743242Z","steps":["trace[1219520881] 'process raft request' (duration: 216.059018ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.743637Z","caller":"traceutil/trace.go:171","msg":"trace[1627152634] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"371.660491ms","start":"2026-09-07T11:00:15.371928Z","end":"2026-09-07T11:00:15.743588Z","steps":["trace[1627152634] 'process raft request' (duration: 371.108314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.743784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.222451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-09-07T11:00:15.743944Z","caller":"traceutil/trace.go:171","msg":"trace[1088700898] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5772; }","duration":"119.414986ms","start":"2026-09-07T11:00:15.624505Z","end":"2026-09-07T11:00:15.74392Z","steps":["trace[1088700898] 'agreement among raft nodes before linearized reading' (duration: 118.968734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.743998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.37191Z","time spent":"371.844886ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":686,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:00:15.743983Z","caller":"traceutil/trace.go:171","msg":"trace[178702686] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"372.057152ms","start":"2026-09-07T11:00:15.37188Z","end":"2026-09-07T11:00:15.743937Z","steps":["trace[178702686] 'process raft request' (duration: 371.109334ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:15.743228Z","caller":"traceutil/trace.go:171","msg":"trace[1090290545] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"371.534906ms","start":"2026-09-07T11:00:15.371662Z","end":"2026-09-07T11:00:15.743197Z","steps":["trace[1090290545] 'process raft request' (duration: 371.269738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:15.74417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.371868Z","time spent":"372.212707ms","remote":"127.0.0.1:41932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T11:00:15.744169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:15.371649Z","time spent":"372.438303ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:00:25.284717Z","caller":"traceutil/trace.go:171","msg":"trace[460413335] transaction","detail":"{read_only:false; response_revision:5875; number_of_response:1; }","duration":"262.71155ms","start":"2026-09-07T11:00:25.021981Z","end":"2026-09-07T11:00:25.284693Z","steps":["trace[460413335] 'process raft request' (duration: 262.549527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:25.468052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.76478ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-07T11:00:25.468418Z","caller":"traceutil/trace.go:171","msg":"trace[991961425] linearizableReadLoop","detail":"{readStateIndex:6096; appliedIndex:6094; }","duration":"425.702102ms","start":"2026-09-07T11:00:25.042681Z","end":"2026-09-07T11:00:25.468383Z","steps":["trace[991961425] 'read index received' (duration: 241.887008ms)","trace[991961425] 'applied index is now lower than readState.Index' (duration: 183.809734ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:00:25.46849Z","caller":"traceutil/trace.go:171","msg":"trace[308749107] transaction","detail":"{read_only:false; response_revision:5879; number_of_response:1; }","duration":"176.933205ms","start":"2026-09-07T11:00:25.291543Z","end":"2026-09-07T11:00:25.468476Z","steps":["trace[308749107] 'process raft request' (duration: 176.850264ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:25.468491Z","caller":"traceutil/trace.go:171","msg":"trace[1392365708] transaction","detail":"{read_only:false; response_revision:5878; number_of_response:1; }","duration":"177.144209ms","start":"2026-09-07T11:00:25.291317Z","end":"2026-09-07T11:00:25.468462Z","steps":["trace[1392365708] 'process raft request' (duration: 177.041547ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:25.468472Z","caller":"traceutil/trace.go:171","msg":"trace[682644420] transaction","detail":"{read_only:false; response_revision:5877; number_of_response:1; }","duration":"177.396044ms","start":"2026-09-07T11:00:25.291038Z","end":"2026-09-07T11:00:25.468434Z","steps":["trace[682644420] 'process raft request' (duration: 177.268472ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:25.468691Z","caller":"traceutil/trace.go:171","msg":"trace[254736213] transaction","detail":"{read_only:false; response_revision:5880; number_of_response:1; }","duration":"175.70529ms","start":"2026-09-07T11:00:25.292949Z","end":"2026-09-07T11:00:25.468655Z","steps":["trace[254736213] 'process raft request' (duration: 175.491266ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:25.46841Z","caller":"traceutil/trace.go:171","msg":"trace[1704942793] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"267.666499ms","start":"2026-09-07T11:00:25.200706Z","end":"2026-09-07T11:00:25.468372Z","steps":["trace[1704942793] 'process raft request' (duration: 267.455855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:25.469336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.639522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T11:00:25.469406Z","caller":"traceutil/trace.go:171","msg":"trace[102027396] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5880; }","duration":"426.743714ms","start":"2026-09-07T11:00:25.042645Z","end":"2026-09-07T11:00:25.469388Z","steps":["trace[102027396] 'agreement among raft nodes before linearized reading' (duration: 425.850716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:25.46944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:00:25.042628Z","time spent":"426.802645ms","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-07T11:00:26.133317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-09-07T11:00:26.212573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"77.16106ms","hash":3757318120,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":32161792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T11:00:26.212627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3757318120,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-09-07T11:03:56.132429Z","caller":"traceutil/trace.go:171","msg":"trace[1205449511] linearizableReadLoop","detail":"{readStateIndex:7635; appliedIndex:7634; }","duration":"298.93836ms","start":"2026-09-07T11:03:55.833462Z","end":"2026-09-07T11:03:56.1324Z","steps":["trace[1205449511] 'read index received' (duration: 298.691633ms)","trace[1205449511] 'applied index is now lower than readState.Index' (duration: 245.887µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:56.132649Z","caller":"traceutil/trace.go:171","msg":"trace[1283044094] transaction","detail":"{read_only:false; response_revision:7365; number_of_response:1; }","duration":"358.070589ms","start":"2026-09-07T11:03:55.774561Z","end":"2026-09-07T11:03:56.132631Z","steps":["trace[1283044094] 'process raft request' (duration: 357.663338ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:56.1328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:03:55.774527Z","time spent":"358.187272ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:03:56.132836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.565532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:03:56.132951Z","caller":"traceutil/trace.go:171","msg":"trace[1342716412] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7365; }","duration":"296.744937ms","start":"2026-09-07T11:03:55.836182Z","end":"2026-09-07T11:03:56.132927Z","steps":["trace[1342716412] 'agreement among raft nodes before linearized reading' (duration: 296.502821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:56.133341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.875916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:03:56.133394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.202798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:03:56.133441Z","caller":"traceutil/trace.go:171","msg":"trace[1332174994] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:7365; }","duration":"248.30783ms","start":"2026-09-07T11:03:55.88512Z","end":"2026-09-07T11:03:56.133428Z","steps":["trace[1332174994] 'agreement among raft nodes before linearized reading' (duration: 248.237138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:56.133527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.00299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xqq2m\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T11:03:56.133581Z","caller":"traceutil/trace.go:171","msg":"trace[1111717672] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xqq2m; range_end:; response_count:1; response_revision:7365; }","duration":"288.077613ms","start":"2026-09-07T11:03:55.84549Z","end":"2026-09-07T11:03:56.133567Z","steps":["trace[1111717672] 'agreement among raft nodes before linearized reading' (duration: 287.924509ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:56.133853Z","caller":"traceutil/trace.go:171","msg":"trace[933214163] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7365; }","duration":"300.0099ms","start":"2026-09-07T11:03:55.833405Z","end":"2026-09-07T11:03:56.133415Z","steps":["trace[933214163] 'agreement among raft nodes before linearized reading' (duration: 299.877426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:56.133952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:03:55.833379Z","time spent":"300.556945ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-07T11:03:57.162836Z","caller":"traceutil/trace.go:171","msg":"trace[816123598] linearizableReadLoop","detail":"{readStateIndex:7643; appliedIndex:7640; }","duration":"125.661227ms","start":"2026-09-07T11:03:57.037151Z","end":"2026-09-07T11:03:57.162812Z","steps":["trace[816123598] 'read index received' (duration: 85.72345ms)","trace[816123598] 'applied index is now lower than readState.Index' (duration: 39.936527ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:57.162902Z","caller":"traceutil/trace.go:171","msg":"trace[1783125983] transaction","detail":"{read_only:false; response_revision:7372; number_of_response:1; }","duration":"136.291097ms","start":"2026-09-07T11:03:57.026574Z","end":"2026-09-07T11:03:57.162865Z","steps":["trace[1783125983] 'process raft request' (duration: 136.06261ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:57.16295Z","caller":"traceutil/trace.go:171","msg":"trace[1831512865] transaction","detail":"{read_only:false; response_revision:7373; number_of_response:1; }","duration":"135.66991ms","start":"2026-09-07T11:03:57.027242Z","end":"2026-09-07T11:03:57.162912Z","steps":["trace[1831512865] 'process raft request' (duration: 135.521586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:57.163029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.872363ms","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-09-07T11:03:57.163054Z","caller":"traceutil/trace.go:171","msg":"trace[1037545599] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7373; }","duration":"125.948686ms","start":"2026-09-07T11:03:57.037097Z","end":"2026-09-07T11:03:57.163046Z","steps":["trace[1037545599] 'agreement among raft nodes before linearized reading' (duration: 125.802312ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:57.872016Z","caller":"traceutil/trace.go:171","msg":"trace[1853405238] transaction","detail":"{read_only:false; response_revision:7378; number_of_response:1; }","duration":"142.925975ms","start":"2026-09-07T11:03:57.72906Z","end":"2026-09-07T11:03:57.871986Z","steps":["trace[1853405238] 'process raft request' (duration: 118.332861ms)","trace[1853405238] 'compare' (duration: 24.45195ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:58.76771Z","caller":"traceutil/trace.go:171","msg":"trace[1001589599] linearizableReadLoop","detail":"{readStateIndex:7658; appliedIndex:7657; }","duration":"115.460249ms","start":"2026-09-07T11:03:58.65222Z","end":"2026-09-07T11:03:58.76768Z","steps":["trace[1001589599] 'read index received' (duration: 111.056075ms)","trace[1001589599] 'applied index is now lower than readState.Index' (duration: 4.402914ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:58.767746Z","caller":"traceutil/trace.go:171","msg":"trace[503726754] transaction","detail":"{read_only:false; response_revision:7387; number_of_response:1; }","duration":"130.887205ms","start":"2026-09-07T11:03:58.636845Z","end":"2026-09-07T11:03:58.767732Z","steps":["trace[503726754] 'process raft request' (duration: 126.418969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:58.7679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.671724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T11:03:58.76793Z","caller":"traceutil/trace.go:171","msg":"trace[1393035873] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7387; }","duration":"115.739586ms","start":"2026-09-07T11:03:58.652181Z","end":"2026-09-07T11:03:58.767921Z","steps":["trace[1393035873] 'agreement among raft nodes before linearized reading' (duration: 115.598003ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:58.81201Z","caller":"traceutil/trace.go:171","msg":"trace[416336699] transaction","detail":"{read_only:false; response_revision:7388; number_of_response:1; }","duration":"148.530273ms","start":"2026-09-07T11:03:58.663454Z","end":"2026-09-07T11:03:58.811984Z","steps":["trace[416336699] 'process raft request' (duration: 148.332117ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:58.812183Z","caller":"traceutil/trace.go:171","msg":"trace[169290421] transaction","detail":"{read_only:false; response_revision:7389; number_of_response:1; }","duration":"102.265547ms","start":"2026-09-07T11:03:58.709892Z","end":"2026-09-07T11:03:58.812157Z","steps":["trace[169290421] 'process raft request' (duration: 102.049341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:59.025117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.423874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T11:03:59.025206Z","caller":"traceutil/trace.go:171","msg":"trace[2025758459] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7389; }","duration":"106.576808ms","start":"2026-09-07T11:03:58.918609Z","end":"2026-09-07T11:03:59.025186Z","steps":["trace[2025758459] 'range keys from in-memory index tree' (duration: 106.177858ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:59.300131Z","caller":"traceutil/trace.go:171","msg":"trace[727955102] transaction","detail":"{read_only:false; response_revision:7391; number_of_response:1; }","duration":"106.768173ms","start":"2026-09-07T11:03:59.193333Z","end":"2026-09-07T11:03:59.300101Z","steps":["trace[727955102] 'process raft request' (duration: 106.611309ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:59.312729Z","caller":"traceutil/trace.go:171","msg":"trace[217170667] transaction","detail":"{read_only:false; response_revision:7392; number_of_response:1; }","duration":"119.212635ms","start":"2026-09-07T11:03:59.193476Z","end":"2026-09-07T11:03:59.312688Z","steps":["trace[217170667] 'process raft request' (duration: 118.953378ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:59.312924Z","caller":"traceutil/trace.go:171","msg":"trace[452666630] linearizableReadLoop","detail":"{readStateIndex:7664; appliedIndex:7661; }","duration":"111.867357ms","start":"2026-09-07T11:03:59.201035Z","end":"2026-09-07T11:03:59.312902Z","steps":["trace[452666630] 'read index received' (duration: 98.905151ms)","trace[452666630] 'applied index is now lower than readState.Index' (duration: 12.960666ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:59.313024Z","caller":"traceutil/trace.go:171","msg":"trace[1242053678] transaction","detail":"{read_only:false; response_revision:7393; number_of_response:1; }","duration":"119.096123ms","start":"2026-09-07T11:03:59.193848Z","end":"2026-09-07T11:03:59.312944Z","steps":["trace[1242053678] 'process raft request' (duration: 118.786934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:59.313618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.051573ms","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-09-07T11:03:59.313708Z","caller":"traceutil/trace.go:171","msg":"trace[894466304] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7393; }","duration":"112.693011ms","start":"2026-09-07T11:03:59.200994Z","end":"2026-09-07T11:03:59.313687Z","steps":["trace[894466304] 'agreement among raft nodes before linearized reading' (duration: 111.984851ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:59.696198Z","caller":"traceutil/trace.go:171","msg":"trace[1823697131] linearizableReadLoop","detail":"{readStateIndex:7667; appliedIndex:7666; }","duration":"124.226296ms","start":"2026-09-07T11:03:59.571945Z","end":"2026-09-07T11:03:59.696172Z","steps":["trace[1823697131] 'read index received' (duration: 123.952058ms)","trace[1823697131] 'applied index is now lower than readState.Index' (duration: 272.748µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:59.696249Z","caller":"traceutil/trace.go:171","msg":"trace[1206678042] transaction","detail":"{read_only:false; response_revision:7396; number_of_response:1; }","duration":"167.793047ms","start":"2026-09-07T11:03:59.528425Z","end":"2026-09-07T11:03:59.696218Z","steps":["trace[1206678042] 'process raft request' (duration: 167.569481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:59.696465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.511215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-07T11:03:59.696501Z","caller":"traceutil/trace.go:171","msg":"trace[1447385348] range","detail":"{range_begin:/registry/masterleases/162.253.55.27; range_end:; response_count:1; response_revision:7396; }","duration":"124.626278ms","start":"2026-09-07T11:03:59.571863Z","end":"2026-09-07T11:03:59.696489Z","steps":["trace[1447385348] 'agreement among raft nodes before linearized reading' (duration: 124.407031ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:01.376381Z","caller":"traceutil/trace.go:171","msg":"trace[918098684] transaction","detail":"{read_only:false; response_revision:7403; number_of_response:1; }","duration":"315.718292ms","start":"2026-09-07T11:04:01.060632Z","end":"2026-09-07T11:04:01.37635Z","steps":["trace[918098684] 'process raft request' (duration: 315.404304ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:01.376386Z","caller":"traceutil/trace.go:171","msg":"trace[1267000720] linearizableReadLoop","detail":"{readStateIndex:7675; appliedIndex:7674; }","duration":"278.77558ms","start":"2026-09-07T11:04:01.097567Z","end":"2026-09-07T11:04:01.376343Z","steps":["trace[1267000720] 'read index received' (duration: 278.429771ms)","trace[1267000720] 'applied index is now lower than readState.Index' (duration: 343.149µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:01.376582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.060596Z","time spent":"315.879227ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:04:01.740571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.840761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:04:01.740636Z","caller":"traceutil/trace.go:171","msg":"trace[1067699598] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:7403; }","duration":"278.955885ms","start":"2026-09-07T11:04:01.461663Z","end":"2026-09-07T11:04:01.740618Z","steps":["trace[1067699598] 'count revisions from in-memory index tree' (duration: 278.761789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:01.740757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.165886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-07T11:04:01.740757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.28365ms","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-09-07T11:04:01.740805Z","caller":"traceutil/trace.go:171","msg":"trace[318961070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7403; }","duration":"293.25005ms","start":"2026-09-07T11:04:01.447544Z","end":"2026-09-07T11:04:01.740794Z","steps":["trace[318961070] 'range keys from in-memory index tree' (duration: 293.078704ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:01.74083Z","caller":"traceutil/trace.go:171","msg":"trace[184742425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7403; }","duration":"422.413535ms","start":"2026-09-07T11:04:01.318395Z","end":"2026-09-07T11:04:01.740809Z","steps":["trace[184742425] 'agreement among raft nodes before linearized reading' (duration: 58.368578ms)","trace[184742425] 'range keys from in-memory index tree' (duration: 363.910733ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:01.740885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.318355Z","time spent":"422.517658ms","remote":"127.0.0.1:42116","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-09-07T11:04:01.740718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.115395ms","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:7"} {"level":"info","ts":"2026-09-07T11:04:01.741Z","caller":"traceutil/trace.go:171","msg":"trace[655375054] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:7403; }","duration":"643.473946ms","start":"2026-09-07T11:04:01.097511Z","end":"2026-09-07T11:04:01.740985Z","steps":["trace[655375054] 'agreement among raft nodes before linearized reading' (duration: 278.933244ms)","trace[655375054] 'count revisions from in-memory index tree' (duration: 364.184662ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:01.741038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.097489Z","time spent":"643.535338ms","remote":"127.0.0.1:46400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:04:01.741136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.101933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-07T11:04:01.741143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.124578ms","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":"warn","ts":"2026-09-07T11:04:01.741048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.979543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-07T11:04:01.74132Z","caller":"traceutil/trace.go:171","msg":"trace[285704705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7403; }","duration":"424.248907ms","start":"2026-09-07T11:04:01.316958Z","end":"2026-09-07T11:04:01.741207Z","steps":["trace[285704705] 'agreement among raft nodes before linearized reading' (duration: 59.798119ms)","trace[285704705] 'range keys from in-memory index tree' (duration: 364.222492ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:01.741316Z","caller":"traceutil/trace.go:171","msg":"trace[1921496900] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7403; }","duration":"574.256591ms","start":"2026-09-07T11:04:01.167021Z","end":"2026-09-07T11:04:01.741277Z","steps":["trace[1921496900] 'agreement among raft nodes before linearized reading' (duration: 209.697118ms)","trace[1921496900] 'range keys from in-memory index tree' (duration: 364.186201ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:01.741337Z","caller":"traceutil/trace.go:171","msg":"trace[1369672654] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7403; }","duration":"393.332504ms","start":"2026-09-07T11:04:01.347932Z","end":"2026-09-07T11:04:01.741264Z","steps":["trace[1369672654] 'agreement among raft nodes before linearized reading' (duration: 28.875675ms)","trace[1369672654] 'range keys from in-memory index tree' (duration: 364.184421ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:01.741366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.167297ms","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":"warn","ts":"2026-09-07T11:04:01.741394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.166999Z","time spent":"574.364844ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-07T11:04:01.741409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.347919Z","time spent":"393.476448ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-07T11:04:01.741437Z","caller":"traceutil/trace.go:171","msg":"trace[1654234917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7403; }","duration":"434.278789ms","start":"2026-09-07T11:04:01.307125Z","end":"2026-09-07T11:04:01.741404Z","steps":["trace[1654234917] 'agreement among raft nodes before linearized reading' (duration: 69.643096ms)","trace[1654234917] 'range keys from in-memory index tree' (duration: 363.865831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:01.74147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.307094Z","time spent":"434.367712ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T11:04:01.741372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.316929Z","time spent":"424.432441ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-07T11:04:02.081161Z","caller":"traceutil/trace.go:171","msg":"trace[251998474] transaction","detail":"{read_only:false; response_revision:7405; number_of_response:1; }","duration":"334.914716ms","start":"2026-09-07T11:04:01.746213Z","end":"2026-09-07T11:04:02.081127Z","steps":["trace[251998474] 'process raft request' (duration: 334.504494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.08133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.746186Z","time spent":"335.046539ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:04:02.081642Z","caller":"traceutil/trace.go:171","msg":"trace[2034442746] transaction","detail":"{read_only:false; response_revision:7406; number_of_response:1; }","duration":"335.234474ms","start":"2026-09-07T11:04:01.746328Z","end":"2026-09-07T11:04:02.081563Z","steps":["trace[2034442746] 'process raft request' (duration: 334.659337ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:02.081736Z","caller":"traceutil/trace.go:171","msg":"trace[205825689] transaction","detail":"{read_only:false; response_revision:7407; number_of_response:1; }","duration":"335.326866ms","start":"2026-09-07T11:04:01.746396Z","end":"2026-09-07T11:04:02.081722Z","steps":["trace[205825689] 'process raft request' (duration: 334.719959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.081805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.74638Z","time spent":"335.384418ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:04:02.081791Z","caller":"traceutil/trace.go:171","msg":"trace[1780379632] transaction","detail":"{read_only:false; response_revision:7408; number_of_response:1; }","duration":"335.002397ms","start":"2026-09-07T11:04:01.746746Z","end":"2026-09-07T11:04:02.081749Z","steps":["trace[1780379632] 'process raft request' (duration: 334.439712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.08189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.746315Z","time spent":"335.42822ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:04:02.082083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:01.746729Z","time spent":"335.202582ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:04:02.082105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.870333ms","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-09-07T11:04:02.082175Z","caller":"traceutil/trace.go:171","msg":"trace[2001413334] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7408; }","duration":"210.980675ms","start":"2026-09-07T11:04:01.871173Z","end":"2026-09-07T11:04:02.082154Z","steps":["trace[2001413334] 'agreement among raft nodes before linearized reading' (duration: 210.869362ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:02.08135Z","caller":"traceutil/trace.go:171","msg":"trace[979117273] linearizableReadLoop","detail":"{readStateIndex:7680; appliedIndex:7676; }","duration":"210.043479ms","start":"2026-09-07T11:04:01.871236Z","end":"2026-09-07T11:04:02.081279Z","steps":["trace[979117273] 'read index received' (duration: 209.38031ms)","trace[979117273] 'applied index is now lower than readState.Index' (duration: 661.229µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:02.082571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.500818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T11:04:02.082586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.6677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:04:02.082617Z","caller":"traceutil/trace.go:171","msg":"trace[336475215] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:7408; }","duration":"179.590811ms","start":"2026-09-07T11:04:01.903011Z","end":"2026-09-07T11:04:02.082602Z","steps":["trace[336475215] 'agreement among raft nodes before linearized reading' (duration: 179.467017ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:02.082636Z","caller":"traceutil/trace.go:171","msg":"trace[726761536] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7408; }","duration":"152.755432ms","start":"2026-09-07T11:04:01.929864Z","end":"2026-09-07T11:04:02.08262Z","steps":["trace[726761536] 'agreement among raft nodes before linearized reading' (duration: 152.67322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.72863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.148961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:04:02.72881Z","caller":"traceutil/trace.go:171","msg":"trace[1475351980] linearizableReadLoop","detail":"{readStateIndex:7682; appliedIndex:7681; }","duration":"415.305733ms","start":"2026-09-07T11:04:02.313489Z","end":"2026-09-07T11:04:02.728794Z","steps":["trace[1475351980] 'read index received' (duration: 118.949728ms)","trace[1475351980] 'applied index is now lower than readState.Index' (duration: 296.354945ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:02.728963Z","caller":"traceutil/trace.go:171","msg":"trace[872536705] transaction","detail":"{read_only:false; response_revision:7410; number_of_response:1; }","duration":"418.522105ms","start":"2026-09-07T11:04:02.310384Z","end":"2026-09-07T11:04:02.728906Z","steps":["trace[872536705] 'process raft request' (duration: 122.005105ms)","trace[872536705] 'compare' (duration: 295.944915ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:02.729087Z","caller":"traceutil/trace.go:171","msg":"trace[1411085002] transaction","detail":"{read_only:false; response_revision:7411; number_of_response:1; }","duration":"397.11913ms","start":"2026-09-07T11:04:02.331938Z","end":"2026-09-07T11:04:02.729057Z","steps":["trace[1411085002] 'process raft request' (duration: 396.801432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.729252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:02.331909Z","time spent":"397.246693ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:04:02.729263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:02.310367Z","time spent":"418.7151ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:04:02.729362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.8762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:18 size:161016"} {"level":"info","ts":"2026-09-07T11:04:02.729417Z","caller":"traceutil/trace.go:171","msg":"trace[1489647977] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:18; response_revision:7411; }","duration":"415.990294ms","start":"2026-09-07T11:04:02.313414Z","end":"2026-09-07T11:04:02.729404Z","steps":["trace[1489647977] 'agreement among raft nodes before linearized reading' (duration: 415.431868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.729459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:02.31338Z","time spent":"416.069226ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":18,"response size":161039,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-07T11:04:02.75313Z","caller":"traceutil/trace.go:171","msg":"trace[1282411039] transaction","detail":"{read_only:false; response_revision:7412; number_of_response:1; }","duration":"232.777812ms","start":"2026-09-07T11:04:02.520314Z","end":"2026-09-07T11:04:02.753092Z","steps":["trace[1282411039] 'process raft request' (duration: 232.614706ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:02.898329Z","caller":"traceutil/trace.go:171","msg":"trace[1260591664] linearizableReadLoop","detail":"{readStateIndex:7685; appliedIndex:7684; }","duration":"116.177149ms","start":"2026-09-07T11:04:02.782133Z","end":"2026-09-07T11:04:02.898311Z","steps":["trace[1260591664] 'read index received' (duration: 116.010045ms)","trace[1260591664] 'applied index is now lower than readState.Index' (duration: 166.144µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:02.898385Z","caller":"traceutil/trace.go:171","msg":"trace[1157839477] transaction","detail":"{read_only:false; response_revision:7413; number_of_response:1; }","duration":"317.346628ms","start":"2026-09-07T11:04:02.581014Z","end":"2026-09-07T11:04:02.898361Z","steps":["trace[1157839477] 'process raft request' (duration: 317.126362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.898464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.328744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-07T11:04:02.898494Z","caller":"traceutil/trace.go:171","msg":"trace[421758118] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7413; }","duration":"116.425716ms","start":"2026-09-07T11:04:02.782059Z","end":"2026-09-07T11:04:02.898485Z","steps":["trace[421758118] 'agreement among raft nodes before linearized reading' (duration: 116.347674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:02.898644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:04:02.58099Z","time spent":"317.445771ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:04:03.031073Z","caller":"traceutil/trace.go:171","msg":"trace[353383253] linearizableReadLoop","detail":"{readStateIndex:7687; appliedIndex:7686; }","duration":"106.438645ms","start":"2026-09-07T11:04:02.924605Z","end":"2026-09-07T11:04:03.031043Z","steps":["trace[353383253] 'read index received' (duration: 69.120751ms)","trace[353383253] 'applied index is now lower than readState.Index' (duration: 37.316924ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:03.031149Z","caller":"traceutil/trace.go:171","msg":"trace[1945082802] transaction","detail":"{read_only:false; response_revision:7415; number_of_response:1; }","duration":"119.870754ms","start":"2026-09-07T11:04:02.911235Z","end":"2026-09-07T11:04:03.031106Z","steps":["trace[1945082802] 'process raft request' (duration: 82.558891ms)","trace[1945082802] 'compare' (duration: 37.094957ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:03.031335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.682321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9f79b8be-e928-4e20-884e-5b5c2777eb3e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-07T11:04:03.031398Z","caller":"traceutil/trace.go:171","msg":"trace[1031574803] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9f79b8be-e928-4e20-884e-5b5c2777eb3e; range_end:; response_count:1; response_revision:7415; }","duration":"106.828305ms","start":"2026-09-07T11:04:02.924552Z","end":"2026-09-07T11:04:03.031381Z","steps":["trace[1031574803] 'agreement among raft nodes before linearized reading' (duration: 106.602639ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:03.176876Z","caller":"traceutil/trace.go:171","msg":"trace[1996365939] transaction","detail":"{read_only:false; response_revision:7417; number_of_response:1; }","duration":"138.272914ms","start":"2026-09-07T11:04:03.03858Z","end":"2026-09-07T11:04:03.176853Z","steps":["trace[1996365939] 'process raft request' (duration: 138.059748ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:03.177451Z","caller":"traceutil/trace.go:171","msg":"trace[1579859888] transaction","detail":"{read_only:false; response_revision:7418; number_of_response:1; }","duration":"138.004706ms","start":"2026-09-07T11:04:03.039413Z","end":"2026-09-07T11:04:03.177418Z","steps":["trace[1579859888] 'process raft request' (duration: 137.380218ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:03.177499Z","caller":"traceutil/trace.go:171","msg":"trace[1755664743] transaction","detail":"{read_only:false; response_revision:7419; number_of_response:1; }","duration":"136.797732ms","start":"2026-09-07T11:04:03.040688Z","end":"2026-09-07T11:04:03.177486Z","steps":["trace[1755664743] 'process raft request' (duration: 136.341669ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:03.975611Z","caller":"traceutil/trace.go:171","msg":"trace[235050351] transaction","detail":"{read_only:false; response_revision:7422; number_of_response:1; }","duration":"167.091438ms","start":"2026-09-07T11:04:03.808494Z","end":"2026-09-07T11:04:03.975586Z","steps":["trace[235050351] 'process raft request' (duration: 166.909633ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.04794Z","caller":"traceutil/trace.go:171","msg":"trace[1987424674] linearizableReadLoop","detail":"{readStateIndex:7695; appliedIndex:7693; }","duration":"202.350532ms","start":"2026-09-07T11:04:03.845555Z","end":"2026-09-07T11:04:04.047906Z","steps":["trace[1987424674] 'read index received' (duration: 129.855436ms)","trace[1987424674] 'applied index is now lower than readState.Index' (duration: 72.493566ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:04.048002Z","caller":"traceutil/trace.go:171","msg":"trace[1205762707] transaction","detail":"{read_only:false; response_revision:7423; number_of_response:1; }","duration":"219.745513ms","start":"2026-09-07T11:04:03.828208Z","end":"2026-09-07T11:04:04.047954Z","steps":["trace[1205762707] 'process raft request' (duration: 219.488736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:04.048233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.65348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-09-07T11:04:04.048357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.258887ms","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-09-07T11:04:04.048365Z","caller":"traceutil/trace.go:171","msg":"trace[1042527883] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7423; }","duration":"202.808095ms","start":"2026-09-07T11:04:03.845524Z","end":"2026-09-07T11:04:04.048332Z","steps":["trace[1042527883] 'agreement among raft nodes before linearized reading' (duration: 202.541297ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.048411Z","caller":"traceutil/trace.go:171","msg":"trace[1746256514] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7423; }","duration":"108.362309ms","start":"2026-09-07T11:04:03.940036Z","end":"2026-09-07T11:04:04.048398Z","steps":["trace[1746256514] 'agreement among raft nodes before linearized reading' (duration: 108.219095ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.369024Z","caller":"traceutil/trace.go:171","msg":"trace[105251546] transaction","detail":"{read_only:false; response_revision:7425; number_of_response:1; }","duration":"242.460175ms","start":"2026-09-07T11:04:04.126523Z","end":"2026-09-07T11:04:04.368983Z","steps":["trace[105251546] 'process raft request' (duration: 153.00264ms)","trace[105251546] 'compare' (duration: 89.179967ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:04.369335Z","caller":"traceutil/trace.go:171","msg":"trace[1860346391] linearizableReadLoop","detail":"{readStateIndex:7699; appliedIndex:7696; }","duration":"234.526541ms","start":"2026-09-07T11:04:04.134781Z","end":"2026-09-07T11:04:04.369308Z","steps":["trace[1860346391] 'read index received' (duration: 10.922479ms)","trace[1860346391] 'applied index is now lower than readState.Index' (duration: 223.602992ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:04.369422Z","caller":"traceutil/trace.go:171","msg":"trace[850662961] transaction","detail":"{read_only:false; response_revision:7426; number_of_response:1; }","duration":"242.724881ms","start":"2026-09-07T11:04:04.126665Z","end":"2026-09-07T11:04:04.369389Z","steps":["trace[850662961] 'process raft request' (duration: 242.250178ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.369434Z","caller":"traceutil/trace.go:171","msg":"trace[376806752] transaction","detail":"{read_only:false; response_revision:7427; number_of_response:1; }","duration":"240.841929ms","start":"2026-09-07T11:04:04.128569Z","end":"2026-09-07T11:04:04.369411Z","steps":["trace[376806752] 'process raft request' (duration: 240.610643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:04.369464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.657725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:04:04.369504Z","caller":"traceutil/trace.go:171","msg":"trace[292370055] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:7427; }","duration":"234.716216ms","start":"2026-09-07T11:04:04.134772Z","end":"2026-09-07T11:04:04.369489Z","steps":["trace[292370055] 'agreement among raft nodes before linearized reading' (duration: 234.630674ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.613615Z","caller":"traceutil/trace.go:171","msg":"trace[1926982842] transaction","detail":"{read_only:false; response_revision:7428; number_of_response:1; }","duration":"117.042273ms","start":"2026-09-07T11:04:04.496546Z","end":"2026-09-07T11:04:04.613588Z","steps":["trace[1926982842] 'process raft request' (duration: 115.206292ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.825204Z","caller":"traceutil/trace.go:171","msg":"trace[1347609679] transaction","detail":"{read_only:false; response_revision:7430; number_of_response:1; }","duration":"136.451231ms","start":"2026-09-07T11:04:04.688704Z","end":"2026-09-07T11:04:04.825156Z","steps":["trace[1347609679] 'process raft request' (duration: 136.210695ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:04.839167Z","caller":"traceutil/trace.go:171","msg":"trace[494991389] transaction","detail":"{read_only:false; response_revision:7431; number_of_response:1; }","duration":"103.08807ms","start":"2026-09-07T11:04:04.736028Z","end":"2026-09-07T11:04:04.839116Z","steps":["trace[494991389] 'process raft request' (duration: 102.507974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:04.86296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.421559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xqq2m\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T11:04:04.863043Z","caller":"traceutil/trace.go:171","msg":"trace[977238859] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xqq2m; range_end:; response_count:1; response_revision:7432; }","duration":"126.545612ms","start":"2026-09-07T11:04:04.736478Z","end":"2026-09-07T11:04:04.863024Z","steps":["trace[977238859] 'agreement among raft nodes before linearized reading' (duration: 125.897535ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:07.072276Z","caller":"traceutil/trace.go:171","msg":"trace[735080137] transaction","detail":"{read_only:false; response_revision:7444; number_of_response:1; }","duration":"122.241461ms","start":"2026-09-07T11:04:06.950008Z","end":"2026-09-07T11:04:07.072249Z","steps":["trace[735080137] 'process raft request' (duration: 122.071066ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:07.127442Z","caller":"traceutil/trace.go:171","msg":"trace[919638705] transaction","detail":"{read_only:false; response_revision:7445; number_of_response:1; }","duration":"162.116407ms","start":"2026-09-07T11:04:06.965278Z","end":"2026-09-07T11:04:07.127394Z","steps":["trace[919638705] 'process raft request' (duration: 161.565611ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:08.565821Z","caller":"traceutil/trace.go:171","msg":"trace[452494685] transaction","detail":"{read_only:false; response_revision:7449; number_of_response:1; }","duration":"111.702203ms","start":"2026-09-07T11:04:08.454093Z","end":"2026-09-07T11:04:08.565796Z","steps":["trace[452494685] 'process raft request' (duration: 111.549169ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:08.578004Z","caller":"traceutil/trace.go:171","msg":"trace[738800297] transaction","detail":"{read_only:false; response_revision:7450; number_of_response:1; }","duration":"123.768105ms","start":"2026-09-07T11:04:08.454209Z","end":"2026-09-07T11:04:08.577977Z","steps":["trace[738800297] 'process raft request' (duration: 123.574079ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:08.578247Z","caller":"traceutil/trace.go:171","msg":"trace[2091328779] transaction","detail":"{read_only:false; response_revision:7451; number_of_response:1; }","duration":"122.952091ms","start":"2026-09-07T11:04:08.455264Z","end":"2026-09-07T11:04:08.578216Z","steps":["trace[2091328779] 'process raft request' (duration: 122.668294ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:26.140105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5889} {"level":"info","ts":"2026-09-07T11:05:26.23261Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5889,"took":"90.446749ms","hash":2329633194,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":26402816,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T11:05:26.232692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2329633194,"revision":5889,"compact-revision":2981} {"level":"warn","ts":"2026-09-07T11:06:23.4005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.039857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:06:23.400617Z","caller":"traceutil/trace.go:171","msg":"trace[1572909774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8562; }","duration":"226.212162ms","start":"2026-09-07T11:06:23.174384Z","end":"2026-09-07T11:06:23.400597Z","steps":["trace[1572909774] 'range keys from in-memory index tree' (duration: 225.933613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:23.400837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.756538ms","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-09-07T11:06:23.400872Z","caller":"traceutil/trace.go:171","msg":"trace[463700223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8562; }","duration":"364.82348ms","start":"2026-09-07T11:06:23.036038Z","end":"2026-09-07T11:06:23.400862Z","steps":["trace[463700223] 'range keys from in-memory index tree' (duration: 364.608535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:23.400898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:23.036022Z","time spent":"364.868082ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-07T11:06:23.59038Z","caller":"traceutil/trace.go:171","msg":"trace[1602532498] transaction","detail":"{read_only:false; response_revision:8563; number_of_response:1; }","duration":"186.624512ms","start":"2026-09-07T11:06:23.40373Z","end":"2026-09-07T11:06:23.590354Z","steps":["trace[1602532498] 'process raft request' (duration: 186.469797ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:23.591305Z","caller":"traceutil/trace.go:171","msg":"trace[1107484937] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8869; }","duration":"183.638799ms","start":"2026-09-07T11:06:23.407654Z","end":"2026-09-07T11:06:23.591292Z","steps":["trace[1107484937] 'read index received' (duration: 182.562369ms)","trace[1107484937] 'applied index is now lower than readState.Index' (duration: 1.07537ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:23.591385Z","caller":"traceutil/trace.go:171","msg":"trace[1257636035] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"186.747067ms","start":"2026-09-07T11:06:23.404607Z","end":"2026-09-07T11:06:23.591354Z","steps":["trace[1257636035] 'process raft request' (duration: 186.608223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:23.59152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.843394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:06:23.591567Z","caller":"traceutil/trace.go:171","msg":"trace[1675953409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8564; }","duration":"183.903606ms","start":"2026-09-07T11:06:23.40765Z","end":"2026-09-07T11:06:23.591553Z","steps":["trace[1675953409] 'agreement among raft nodes before linearized reading' (duration: 183.742912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:23.591602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.187296ms","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-09-07T11:06:23.591681Z","caller":"traceutil/trace.go:171","msg":"trace[1504572524] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8564; }","duration":"179.297149ms","start":"2026-09-07T11:06:23.412362Z","end":"2026-09-07T11:06:23.591659Z","steps":["trace[1504572524] 'agreement among raft nodes before linearized reading' (duration: 179.065993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:35.408023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.100327ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-07T11:06:35.408118Z","caller":"traceutil/trace.go:171","msg":"trace[1839162721] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8936; }","duration":"235.1822ms","start":"2026-09-07T11:06:35.172918Z","end":"2026-09-07T11:06:35.4081Z","steps":["trace[1839162721] 'read index received' (duration: 69.933µs)","trace[1839162721] 'applied index is now lower than readState.Index' (duration: 235.110967ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:06:35.408268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.348336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:06:35.408298Z","caller":"traceutil/trace.go:171","msg":"trace[152813241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8626; }","duration":"235.418058ms","start":"2026-09-07T11:06:35.172867Z","end":"2026-09-07T11:06:35.408285Z","steps":["trace[152813241] 'agreement among raft nodes before linearized reading' (duration: 235.356346ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:36.138172Z","caller":"traceutil/trace.go:171","msg":"trace[1016705638] linearizableReadLoop","detail":"{readStateIndex:8944; appliedIndex:8943; }","duration":"130.414191ms","start":"2026-09-07T11:06:36.007686Z","end":"2026-09-07T11:06:36.1381Z","steps":["trace[1016705638] 'read index received' (duration: 130.097773ms)","trace[1016705638] 'applied index is now lower than readState.Index' (duration: 314.488µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:36.138201Z","caller":"traceutil/trace.go:171","msg":"trace[1937468075] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"243.144582ms","start":"2026-09-07T11:06:35.895005Z","end":"2026-09-07T11:06:36.13815Z","steps":["trace[1937468075] 'process raft request' (duration: 242.808763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:36.138516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.801791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-07T11:06:36.138602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.403107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xqq2m\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-07T11:06:36.138654Z","caller":"traceutil/trace.go:171","msg":"trace[481410554] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xqq2m; range_end:; response_count:1; response_revision:8633; }","duration":"118.569432ms","start":"2026-09-07T11:06:36.020072Z","end":"2026-09-07T11:06:36.138641Z","steps":["trace[481410554] 'agreement among raft nodes before linearized reading' (duration: 118.350136ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:36.138657Z","caller":"traceutil/trace.go:171","msg":"trace[1528974424] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8633; }","duration":"131.001417ms","start":"2026-09-07T11:06:36.007611Z","end":"2026-09-07T11:06:36.138612Z","steps":["trace[1528974424] 'agreement among raft nodes before linearized reading' (duration: 130.677648ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:36.244939Z","caller":"traceutil/trace.go:171","msg":"trace[553772064] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"100.590063ms","start":"2026-09-07T11:06:36.144317Z","end":"2026-09-07T11:06:36.244907Z","steps":["trace[553772064] 'process raft request' (duration: 90.118353ms)","trace[553772064] 'compare' (duration: 10.312166ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:36.244943Z","caller":"traceutil/trace.go:171","msg":"trace[579049759] linearizableReadLoop","detail":"{readStateIndex:8945; appliedIndex:8944; }","duration":"100.400878ms","start":"2026-09-07T11:06:36.144485Z","end":"2026-09-07T11:06:36.244886Z","steps":["trace[579049759] 'read index received' (duration: 89.907417ms)","trace[579049759] 'applied index is now lower than readState.Index' (duration: 10.492161ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:06:36.245365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.85686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223762"} {"level":"info","ts":"2026-09-07T11:06:36.245422Z","caller":"traceutil/trace.go:171","msg":"trace[374987246] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8634; }","duration":"100.931212ms","start":"2026-09-07T11:06:36.144477Z","end":"2026-09-07T11:06:36.245409Z","steps":["trace[374987246] 'agreement among raft nodes before linearized reading' (duration: 100.511761ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.145347Z","caller":"traceutil/trace.go:171","msg":"trace[160666335] linearizableReadLoop","detail":"{readStateIndex:9162; appliedIndex:9161; }","duration":"293.616282ms","start":"2026-09-07T11:07:05.851707Z","end":"2026-09-07T11:07:06.145323Z","steps":["trace[160666335] 'read index received' (duration: 293.419476ms)","trace[160666335] 'applied index is now lower than readState.Index' (duration: 195.416µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:06.145416Z","caller":"traceutil/trace.go:171","msg":"trace[1194752934] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"297.371126ms","start":"2026-09-07T11:07:05.848004Z","end":"2026-09-07T11:07:06.145375Z","steps":["trace[1194752934] 'process raft request' (duration: 297.13664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.145717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.980992ms","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-09-07T11:07:06.145782Z","caller":"traceutil/trace.go:171","msg":"trace[788435620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8843; }","duration":"294.093586ms","start":"2026-09-07T11:07:05.851672Z","end":"2026-09-07T11:07:06.145765Z","steps":["trace[788435620] 'agreement among raft nodes before linearized reading' (duration: 293.801547ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.405016Z","caller":"traceutil/trace.go:171","msg":"trace[56087197] linearizableReadLoop","detail":"{readStateIndex:9163; appliedIndex:9162; }","duration":"259.552567ms","start":"2026-09-07T11:07:06.14544Z","end":"2026-09-07T11:07:06.404993Z","steps":["trace[56087197] 'read index received' (duration: 259.399943ms)","trace[56087197] 'applied index is now lower than readState.Index' (duration: 150.964µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:07:06.405139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.13322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:07:06.405182Z","caller":"traceutil/trace.go:171","msg":"trace[1524259299] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"519.555936ms","start":"2026-09-07T11:07:05.885578Z","end":"2026-09-07T11:07:06.405134Z","steps":["trace[1524259299] 'process raft request' (duration: 519.164826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.405299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.502686ms","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-09-07T11:07:06.405386Z","caller":"traceutil/trace.go:171","msg":"trace[197482622] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8844; }","duration":"496.622139ms","start":"2026-09-07T11:07:05.908745Z","end":"2026-09-07T11:07:06.405367Z","steps":["trace[197482622] 'agreement among raft nodes before linearized reading' (duration: 496.406644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.405398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.347237ms","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-09-07T11:07:06.40523Z","caller":"traceutil/trace.go:171","msg":"trace[1716515311] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8844; }","duration":"468.285093ms","start":"2026-09-07T11:07:05.936928Z","end":"2026-09-07T11:07:06.405213Z","steps":["trace[1716515311] 'agreement among raft nodes before linearized reading' (duration: 468.080938ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.405488Z","caller":"traceutil/trace.go:171","msg":"trace[1887032711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8844; }","duration":"534.469051ms","start":"2026-09-07T11:07:05.870985Z","end":"2026-09-07T11:07:06.405454Z","steps":["trace[1887032711] 'agreement among raft nodes before linearized reading' (duration: 534.289556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.405501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.936919Z","time spent":"468.56306ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:07:06.405545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.870965Z","time spent":"534.567664ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T11:07:06.40544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.908726Z","time spent":"496.698832ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-07T11:07:06.405406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.156409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-07T11:07:06.405588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.885558Z","time spent":"519.794483ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:07:06.405684Z","caller":"traceutil/trace.go:171","msg":"trace[1760053011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8844; }","duration":"495.446397ms","start":"2026-09-07T11:07:05.91022Z","end":"2026-09-07T11:07:06.405667Z","steps":["trace[1760053011] 'agreement among raft nodes before linearized reading' (duration: 494.83103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.405761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.910215Z","time spent":"495.524699ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T11:07:06.406916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.471678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-07T11:07:06.406983Z","caller":"traceutil/trace.go:171","msg":"trace[271177348] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8846; }","duration":"244.583871ms","start":"2026-09-07T11:07:06.162384Z","end":"2026-09-07T11:07:06.406968Z","steps":["trace[271177348] 'agreement among raft nodes before linearized reading' (duration: 244.409736ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.407355Z","caller":"traceutil/trace.go:171","msg":"trace[2044793057] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"255.755541ms","start":"2026-09-07T11:07:06.151551Z","end":"2026-09-07T11:07:06.407306Z","steps":["trace[2044793057] 'process raft request' (duration: 255.070322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.407371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.40781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T11:07:06.407435Z","caller":"traceutil/trace.go:171","msg":"trace[1281990260] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8846; }","duration":"144.470883ms","start":"2026-09-07T11:07:06.262946Z","end":"2026-09-07T11:07:06.407417Z","steps":["trace[1281990260] 'agreement among raft nodes before linearized reading' (duration: 144.292317ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:14.588277Z","caller":"traceutil/trace.go:171","msg":"trace[1650530941] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"144.848707ms","start":"2026-09-07T11:07:14.443406Z","end":"2026-09-07T11:07:14.588255Z","steps":["trace[1650530941] 'process raft request' (duration: 98.52241ms)","trace[1650530941] 'compare' (duration: 46.220144ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:14.610667Z","caller":"traceutil/trace.go:171","msg":"trace[1461630411] linearizableReadLoop","detail":"{readStateIndex:9209; appliedIndex:9207; }","duration":"161.479816ms","start":"2026-09-07T11:07:14.449168Z","end":"2026-09-07T11:07:14.610647Z","steps":["trace[1461630411] 'read index received' (duration: 92.769227ms)","trace[1461630411] 'applied index is now lower than readState.Index' (duration: 68.709379ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:14.610792Z","caller":"traceutil/trace.go:171","msg":"trace[1635456247] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"166.398454ms","start":"2026-09-07T11:07:14.444362Z","end":"2026-09-07T11:07:14.610761Z","steps":["trace[1635456247] 'process raft request' (duration: 166.169918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:14.610914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.664042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-07T11:07:14.610924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.733284ms","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-09-07T11:07:14.610958Z","caller":"traceutil/trace.go:171","msg":"trace[1496393007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8889; }","duration":"161.755394ms","start":"2026-09-07T11:07:14.449191Z","end":"2026-09-07T11:07:14.610946Z","steps":["trace[1496393007] 'agreement among raft nodes before linearized reading' (duration: 161.585718ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:14.610974Z","caller":"traceutil/trace.go:171","msg":"trace[1809555577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8889; }","duration":"161.820316ms","start":"2026-09-07T11:07:14.449142Z","end":"2026-09-07T11:07:14.610962Z","steps":["trace[1809555577] 'agreement among raft nodes before linearized reading' (duration: 161.60888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:14.610997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.560655ms","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-09-07T11:07:14.611062Z","caller":"traceutil/trace.go:171","msg":"trace[665303757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8889; }","duration":"152.644237ms","start":"2026-09-07T11:07:14.458405Z","end":"2026-09-07T11:07:14.611049Z","steps":["trace[665303757] 'agreement among raft nodes before linearized reading' (duration: 152.489433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:14.611151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.569707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:07:14.611186Z","caller":"traceutil/trace.go:171","msg":"trace[232768341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8889; }","duration":"156.625269ms","start":"2026-09-07T11:07:14.454553Z","end":"2026-09-07T11:07:14.611178Z","steps":["trace[232768341] 'agreement among raft nodes before linearized reading' (duration: 156.507206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:14.611191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.308513ms","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-09-07T11:07:14.611234Z","caller":"traceutil/trace.go:171","msg":"trace[2133866864] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8889; }","duration":"154.361015ms","start":"2026-09-07T11:07:14.456859Z","end":"2026-09-07T11:07:14.61122Z","steps":["trace[2133866864] 'agreement among raft nodes before linearized reading' (duration: 154.165469ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:19.794143Z","caller":"traceutil/trace.go:171","msg":"trace[600160012] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"103.83865ms","start":"2026-09-07T11:07:19.690279Z","end":"2026-09-07T11:07:19.794118Z","steps":["trace[600160012] 'process raft request' (duration: 81.140329ms)","trace[600160012] 'compare' (duration: 22.549546ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:07:19.794679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.168387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-07T11:07:19.794747Z","caller":"traceutil/trace.go:171","msg":"trace[1329928528] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8950; }","duration":"105.26199ms","start":"2026-09-07T11:07:19.68947Z","end":"2026-09-07T11:07:19.794732Z","steps":["trace[1329928528] 'agreement among raft nodes before linearized reading' (duration: 104.694104ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:19.945239Z","caller":"traceutil/trace.go:171","msg":"trace[1525818907] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8952; }","duration":"102.370948ms","start":"2026-09-07T11:07:19.842843Z","end":"2026-09-07T11:07:19.945214Z","steps":["trace[1525818907] 'process raft request' (duration: 102.184723ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:19.996705Z","caller":"traceutil/trace.go:171","msg":"trace[1845922949] linearizableReadLoop","detail":"{readStateIndex:9277; appliedIndex:9274; }","duration":"151.270908ms","start":"2026-09-07T11:07:19.84541Z","end":"2026-09-07T11:07:19.996681Z","steps":["trace[1845922949] 'read index received' (duration: 99.721713ms)","trace[1845922949] 'applied index is now lower than readState.Index' (duration: 51.548025ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:19.996998Z","caller":"traceutil/trace.go:171","msg":"trace[1759410664] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"154.089287ms","start":"2026-09-07T11:07:19.842896Z","end":"2026-09-07T11:07:19.996986Z","steps":["trace[1759410664] 'process raft request' (duration: 153.46489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:19.997422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.991539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-09-07T11:07:19.99747Z","caller":"traceutil/trace.go:171","msg":"trace[821283958] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8955; }","duration":"152.086721ms","start":"2026-09-07T11:07:19.84537Z","end":"2026-09-07T11:07:19.997457Z","steps":["trace[821283958] 'agreement among raft nodes before linearized reading' (duration: 151.930577ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:19.997593Z","caller":"traceutil/trace.go:171","msg":"trace[1693789162] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"148.186911ms","start":"2026-09-07T11:07:19.84937Z","end":"2026-09-07T11:07:19.997557Z","steps":["trace[1693789162] 'process raft request' (duration: 147.180492ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:19.997637Z","caller":"traceutil/trace.go:171","msg":"trace[2044984477] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8955; }","duration":"142.276295ms","start":"2026-09-07T11:07:19.855349Z","end":"2026-09-07T11:07:19.997626Z","steps":["trace[2044984477] 'process raft request' (duration: 141.298607ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:19.9979Z","caller":"traceutil/trace.go:171","msg":"trace[196049856] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"152.719788ms","start":"2026-09-07T11:07:19.845101Z","end":"2026-09-07T11:07:19.997821Z","steps":["trace[196049856] 'process raft request' (duration: 151.384791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:19.998047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.946279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-07T11:07:19.99811Z","caller":"traceutil/trace.go:171","msg":"trace[84842707] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8955; }","duration":"151.025932ms","start":"2026-09-07T11:07:19.847055Z","end":"2026-09-07T11:07:19.998081Z","steps":["trace[84842707] 'agreement among raft nodes before linearized reading' (duration: 150.897208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.15307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.976342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:07:20.153203Z","caller":"traceutil/trace.go:171","msg":"trace[1134162689] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8956; }","duration":"142.652345ms","start":"2026-09-07T11:07:20.010534Z","end":"2026-09-07T11:07:20.153186Z","steps":["trace[1134162689] 'process raft request' (duration: 25.14997ms)","trace[1134162689] 'compare' (duration: 116.742833ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:20.198625Z","caller":"traceutil/trace.go:171","msg":"trace[978776042] linearizableReadLoop","detail":"{readStateIndex:9281; appliedIndex:9279; }","duration":"182.447197ms","start":"2026-09-07T11:07:20.016158Z","end":"2026-09-07T11:07:20.198605Z","steps":["trace[978776042] 'read index received' (duration: 19.541531ms)","trace[978776042] 'applied index is now lower than readState.Index' (duration: 162.904576ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:20.198703Z","caller":"traceutil/trace.go:171","msg":"trace[88704624] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"186.869772ms","start":"2026-09-07T11:07:20.011808Z","end":"2026-09-07T11:07:20.198677Z","steps":["trace[88704624] 'process raft request' (duration: 186.607445ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:20.198735Z","caller":"traceutil/trace.go:171","msg":"trace[1232168715] transaction","detail":"{read_only:false; response_revision:8958; number_of_response:1; }","duration":"164.039458ms","start":"2026-09-07T11:07:20.034684Z","end":"2026-09-07T11:07:20.198724Z","steps":["trace[1232168715] 'process raft request' (duration: 163.854493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.199238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.054705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6bb5d\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-09-07T11:07:20.199367Z","caller":"traceutil/trace.go:171","msg":"trace[1012212112] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6bb5d; range_end:; response_count:1; response_revision:8958; }","duration":"183.21821ms","start":"2026-09-07T11:07:20.01613Z","end":"2026-09-07T11:07:20.199348Z","steps":["trace[1012212112] 'agreement among raft nodes before linearized reading' (duration: 182.836659ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:20.416392Z","caller":"traceutil/trace.go:171","msg":"trace[2002343640] linearizableReadLoop","detail":"{readStateIndex:9283; appliedIndex:9282; }","duration":"217.678472ms","start":"2026-09-07T11:07:20.198692Z","end":"2026-09-07T11:07:20.41637Z","steps":["trace[2002343640] 'read index received' (duration: 217.568619ms)","trace[2002343640] 'applied index is now lower than readState.Index' (duration: 108.663µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:07:20.416467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.692441ms","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:5"} {"level":"info","ts":"2026-09-07T11:07:20.416488Z","caller":"traceutil/trace.go:171","msg":"trace[248100639] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8958; }","duration":"244.134658ms","start":"2026-09-07T11:07:20.172344Z","end":"2026-09-07T11:07:20.416478Z","steps":["trace[248100639] 'process raft request' (duration: 243.931792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.416487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.351721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:07:20.416521Z","caller":"traceutil/trace.go:171","msg":"trace[622532835] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:8958; }","duration":"229.794504ms","start":"2026-09-07T11:07:20.186713Z","end":"2026-09-07T11:07:20.416508Z","steps":["trace[622532835] 'agreement among raft nodes before linearized reading' (duration: 229.678711ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:20.416553Z","caller":"traceutil/trace.go:171","msg":"trace[2147126044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8958; }","duration":"240.487676ms","start":"2026-09-07T11:07:20.176049Z","end":"2026-09-07T11:07:20.416536Z","steps":["trace[2147126044] 'agreement among raft nodes before linearized reading' (duration: 240.358362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.416727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.450938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-09-07T11:07:20.416776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.545751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-07T11:07:20.416865Z","caller":"traceutil/trace.go:171","msg":"trace[1971637959] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8958; }","duration":"400.687385ms","start":"2026-09-07T11:07:20.016151Z","end":"2026-09-07T11:07:20.416839Z","steps":["trace[1971637959] 'agreement among raft nodes before linearized reading' (duration: 400.328105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.416921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:20.01613Z","time spent":"400.777988ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"info","ts":"2026-09-07T11:07:20.416808Z","caller":"traceutil/trace.go:171","msg":"trace[785095346] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8958; }","duration":"367.52771ms","start":"2026-09-07T11:07:20.049266Z","end":"2026-09-07T11:07:20.416794Z","steps":["trace[785095346] 'agreement among raft nodes before linearized reading' (duration: 367.17484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.416996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:20.049222Z","time spent":"367.763626ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-09-07T11:07:20.536692Z","caller":"traceutil/trace.go:171","msg":"trace[609158566] transaction","detail":"{read_only:false; response_revision:8959; number_of_response:1; }","duration":"271.380517ms","start":"2026-09-07T11:07:20.265286Z","end":"2026-09-07T11:07:20.536667Z","steps":["trace[609158566] 'process raft request' (duration: 271.168331ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:20.536891Z","caller":"traceutil/trace.go:171","msg":"trace[2060060500] linearizableReadLoop","detail":"{readStateIndex:9284; appliedIndex:9284; }","duration":"120.412028ms","start":"2026-09-07T11:07:20.41645Z","end":"2026-09-07T11:07:20.536862Z","steps":["trace[2060060500] 'read index received' (duration: 120.400627ms)","trace[2060060500] 'applied index is now lower than readState.Index' (duration: 8.951µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:07:20.537126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.180339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:07:20.537162Z","caller":"traceutil/trace.go:171","msg":"trace[1301252906] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8959; }","duration":"228.27491ms","start":"2026-09-07T11:07:20.308873Z","end":"2026-09-07T11:07:20.537148Z","steps":["trace[1301252906] 'agreement among raft nodes before linearized reading' (duration: 228.087835ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:20.585473Z","caller":"traceutil/trace.go:171","msg":"trace[701145301] transaction","detail":"{read_only:false; response_revision:8960; number_of_response:1; }","duration":"147.256896ms","start":"2026-09-07T11:07:20.438193Z","end":"2026-09-07T11:07:20.58545Z","steps":["trace[701145301] 'process raft request' (duration: 147.125992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.643334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.831057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-09-07T11:07:20.643367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.45636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-09-07T11:07:20.643421Z","caller":"traceutil/trace.go:171","msg":"trace[1893175724] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:8960; }","duration":"222.960771ms","start":"2026-09-07T11:07:20.420438Z","end":"2026-09-07T11:07:20.643399Z","steps":["trace[1893175724] 'agreement among raft nodes before linearized reading' (duration: 166.007023ms)","trace[1893175724] 'range keys from in-memory index tree' (duration: 56.748991ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:20.643432Z","caller":"traceutil/trace.go:171","msg":"trace[904845002] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8960; }","duration":"198.563983ms","start":"2026-09-07T11:07:20.44485Z","end":"2026-09-07T11:07:20.643414Z","steps":["trace[904845002] 'agreement among raft nodes before linearized reading' (duration: 141.579615ms)","trace[904845002] 'range keys from in-memory index tree' (duration: 56.794793ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:07:20.643626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.111969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:07:20.643688Z","caller":"traceutil/trace.go:171","msg":"trace[1077381723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8960; }","duration":"226.204622ms","start":"2026-09-07T11:07:20.417467Z","end":"2026-09-07T11:07:20.643672Z","steps":["trace[1077381723] 'agreement among raft nodes before linearized reading' (duration: 168.981317ms)","trace[1077381723] 'range keys from in-memory index tree' (duration: 57.149143ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:20.682588Z","caller":"traceutil/trace.go:171","msg":"trace[1646477528] transaction","detail":"{read_only:false; response_revision:8961; number_of_response:1; }","duration":"125.726158ms","start":"2026-09-07T11:07:20.556837Z","end":"2026-09-07T11:07:20.682564Z","steps":["trace[1646477528] 'process raft request' (duration: 125.404709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.682913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.316465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6bb5d\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-09-07T11:07:20.682983Z","caller":"traceutil/trace.go:171","msg":"trace[354268251] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6bb5d; range_end:; response_count:1; response_revision:8962; }","duration":"125.422769ms","start":"2026-09-07T11:07:20.557544Z","end":"2026-09-07T11:07:20.682967Z","steps":["trace[354268251] 'agreement among raft nodes before linearized reading' (duration: 125.239614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:20.683156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.402013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:07:20.683241Z","caller":"traceutil/trace.go:171","msg":"trace[191205851] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8962; }","duration":"122.654381ms","start":"2026-09-07T11:07:20.560562Z","end":"2026-09-07T11:07:20.683217Z","steps":["trace[191205851] 'agreement among raft nodes before linearized reading' (duration: 122.377903ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:21.442137Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002456.wal"} {"level":"info","ts":"2026-09-07T11:08:27.603231Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d9b565cb482924cf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:08:27.610122Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T11:08:27.610251Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-07T11:08:43.912532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.598981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:415365"} {"level":"info","ts":"2026-09-07T11:08:43.912636Z","caller":"traceutil/trace.go:171","msg":"trace[1215593350] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:9751; }","duration":"109.750456ms","start":"2026-09-07T11:08:43.802864Z","end":"2026-09-07T11:08:43.912614Z","steps":["trace[1215593350] 'range keys from in-memory index tree' (duration: 109.257081ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:10:26.148067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8051} {"level":"info","ts":"2026-09-07T11:10:26.228892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8051,"took":"78.518473ms","hash":1131974797,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":28663808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-07T11:10:26.228987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1131974797,"revision":8051,"compact-revision":5889} {"level":"warn","ts":"2026-09-07T11:12:00.157947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.047563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:12:00.158065Z","caller":"traceutil/trace.go:171","msg":"trace[1016874260] linearizableReadLoop","detail":"{readStateIndex:11816; appliedIndex:11815; }","duration":"140.039292ms","start":"2026-09-07T11:12:00.018009Z","end":"2026-09-07T11:12:00.158049Z","steps":["trace[1016874260] 'read index received' (duration: 35.391µs)","trace[1016874260] 'applied index is now lower than readState.Index' (duration: 140.002721ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:00.158103Z","caller":"traceutil/trace.go:171","msg":"trace[148396936] transaction","detail":"{read_only:false; response_revision:11422; number_of_response:1; }","duration":"277.652719ms","start":"2026-09-07T11:11:59.880421Z","end":"2026-09-07T11:12:00.158073Z","steps":["trace[148396936] 'process raft request' (duration: 137.392511ms)","trace[148396936] 'compare' (duration: 139.917949ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:12:00.158186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.430646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:00.158214Z","caller":"traceutil/trace.go:171","msg":"trace[289184636] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11422; }","duration":"104.493117ms","start":"2026-09-07T11:12:00.053712Z","end":"2026-09-07T11:12:00.158205Z","steps":["trace[289184636] 'agreement among raft nodes before linearized reading' (duration: 104.439996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:00.158191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.165026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:00.158284Z","caller":"traceutil/trace.go:171","msg":"trace[1268823575] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:11422; }","duration":"140.30877ms","start":"2026-09-07T11:12:00.01795Z","end":"2026-09-07T11:12:00.158259Z","steps":["trace[1268823575] 'agreement among raft nodes before linearized reading' (duration: 140.177286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:00.15881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.779095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:12:00.158855Z","caller":"traceutil/trace.go:171","msg":"trace[91181474] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11422; }","duration":"104.836976ms","start":"2026-09-07T11:12:00.054005Z","end":"2026-09-07T11:12:00.158842Z","steps":["trace[91181474] 'agreement among raft nodes before linearized reading' (duration: 104.777295ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:10.401402Z","caller":"traceutil/trace.go:171","msg":"trace[231156503] linearizableReadLoop","detail":"{readStateIndex:11896; appliedIndex:11895; }","duration":"113.125355ms","start":"2026-09-07T11:12:10.288251Z","end":"2026-09-07T11:12:10.401376Z","steps":["trace[231156503] 'read index received' (duration: 112.961981ms)","trace[231156503] 'applied index is now lower than readState.Index' (duration: 162.354µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:12:10.401484Z","caller":"traceutil/trace.go:171","msg":"trace[607382704] transaction","detail":"{read_only:false; response_revision:11500; number_of_response:1; }","duration":"114.311347ms","start":"2026-09-07T11:12:10.287136Z","end":"2026-09-07T11:12:10.401448Z","steps":["trace[607382704] 'process raft request' (duration: 114.095941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:10.401664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.395062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:12:10.401706Z","caller":"traceutil/trace.go:171","msg":"trace[1543452314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11500; }","duration":"113.490895ms","start":"2026-09-07T11:12:10.288205Z","end":"2026-09-07T11:12:10.401696Z","steps":["trace[1543452314] 'agreement among raft nodes before linearized reading' (duration: 113.343051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:12:10.402122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.522146ms","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-09-07T11:12:10.402194Z","caller":"traceutil/trace.go:171","msg":"trace[949797625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11500; }","duration":"113.649568ms","start":"2026-09-07T11:12:10.288527Z","end":"2026-09-07T11:12:10.402177Z","steps":["trace[949797625] 'agreement among raft nodes before linearized reading' (duration: 113.091203ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:15:26.156828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10462} {"level":"info","ts":"2026-09-07T11:15:26.244175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10462,"took":"85.102968ms","hash":739194192,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":27660288,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T11:15:26.244253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":739194192,"revision":10462,"compact-revision":8051} {"level":"info","ts":"2026-09-07T11:18:45.41763Z","caller":"traceutil/trace.go:171","msg":"trace[1688136903] transaction","detail":"{read_only:false; response_revision:14138; number_of_response:1; }","duration":"113.627738ms","start":"2026-09-07T11:18:45.303973Z","end":"2026-09-07T11:18:45.417601Z","steps":["trace[1688136903] 'process raft request' (duration: 113.233287ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:19:03.588116Z","caller":"traceutil/trace.go:171","msg":"trace[2147381580] transaction","detail":"{read_only:false; response_revision:14245; number_of_response:1; }","duration":"103.660154ms","start":"2026-09-07T11:19:03.484424Z","end":"2026-09-07T11:19:03.588084Z","steps":["trace[2147381580] 'process raft request' (duration: 103.405057ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:19:13.258354Z","caller":"traceutil/trace.go:171","msg":"trace[1687223018] linearizableReadLoop","detail":"{readStateIndex:14912; appliedIndex:14911; }","duration":"168.816248ms","start":"2026-09-07T11:19:13.089509Z","end":"2026-09-07T11:19:13.258325Z","steps":["trace[1687223018] 'read index received' (duration: 81.927716ms)","trace[1687223018] 'applied index is now lower than readState.Index' (duration: 86.887252ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:19:13.258392Z","caller":"traceutil/trace.go:171","msg":"trace[232021842] transaction","detail":"{read_only:false; response_revision:14416; number_of_response:1; }","duration":"199.505922ms","start":"2026-09-07T11:19:13.058843Z","end":"2026-09-07T11:19:13.258349Z","steps":["trace[232021842] 'process raft request' (duration: 112.572178ms)","trace[232021842] 'compare' (duration: 86.728708ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:19:13.258498Z","caller":"traceutil/trace.go:171","msg":"trace[790685379] transaction","detail":"{read_only:false; response_revision:14417; number_of_response:1; }","duration":"165.234139ms","start":"2026-09-07T11:19:13.093234Z","end":"2026-09-07T11:19:13.258468Z","steps":["trace[790685379] 'process raft request' (duration: 165.049494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:19:13.258579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.697161ms","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-09-07T11:19:13.258615Z","caller":"traceutil/trace.go:171","msg":"trace[1894211547] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14417; }","duration":"165.794573ms","start":"2026-09-07T11:19:13.092809Z","end":"2026-09-07T11:19:13.258604Z","steps":["trace[1894211547] 'agreement among raft nodes before linearized reading' (duration: 165.690121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:19:13.258672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.149656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc88f6a2-cbf1-458d-a959-c59a01942130\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-09-07T11:19:13.258759Z","caller":"traceutil/trace.go:171","msg":"trace[684258752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc88f6a2-cbf1-458d-a959-c59a01942130; range_end:; response_count:1; response_revision:14417; }","duration":"169.296641ms","start":"2026-09-07T11:19:13.08944Z","end":"2026-09-07T11:19:13.258737Z","steps":["trace[684258752] 'agreement among raft nodes before linearized reading' (duration: 169.024404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:19:13.571015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.730089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:19:13.571186Z","caller":"traceutil/trace.go:171","msg":"trace[825092457] linearizableReadLoop","detail":"{readStateIndex:14914; appliedIndex:14913; }","duration":"306.737583ms","start":"2026-09-07T11:19:13.264425Z","end":"2026-09-07T11:19:13.571163Z","steps":["trace[825092457] 'read index received' (duration: 143.770207ms)","trace[825092457] 'applied index is now lower than readState.Index' (duration: 162.965606ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:19:13.571279Z","caller":"traceutil/trace.go:171","msg":"trace[160466975] transaction","detail":"{read_only:false; response_revision:14418; number_of_response:1; }","duration":"308.121623ms","start":"2026-09-07T11:19:13.26314Z","end":"2026-09-07T11:19:13.571262Z","steps":["trace[160466975] 'process raft request' (duration: 145.040153ms)","trace[160466975] 'compare' (duration: 162.592056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:19:13.571352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:19:13.263116Z","time spent":"308.193765ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:19:13.571346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.885089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:19:13.571468Z","caller":"traceutil/trace.go:171","msg":"trace[386897136] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14418; }","duration":"307.046713ms","start":"2026-09-07T11:19:13.264385Z","end":"2026-09-07T11:19:13.571432Z","steps":["trace[386897136] 'agreement among raft nodes before linearized reading' (duration: 306.872979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:19:13.571548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:19:13.264369Z","time spent":"307.162256ms","remote":"127.0.0.1:42044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-07T11:19:13.592296Z","caller":"traceutil/trace.go:171","msg":"trace[30269146] transaction","detail":"{read_only:false; response_revision:14419; number_of_response:1; }","duration":"325.934302ms","start":"2026-09-07T11:19:13.266314Z","end":"2026-09-07T11:19:13.592248Z","steps":["trace[30269146] 'process raft request' (duration: 325.592362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:19:13.59249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:19:13.266298Z","time spent":"326.069636ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:20:26.165021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12777} {"level":"info","ts":"2026-09-07T11:20:26.248012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12777,"took":"80.315528ms","hash":3375142205,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":31043584,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T11:20:26.248116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3375142205,"revision":12777,"compact-revision":10462} {"level":"info","ts":"2026-09-07T11:20:59.853275Z","caller":"traceutil/trace.go:171","msg":"trace[622813299] linearizableReadLoop","detail":"{readStateIndex:16067; appliedIndex:16066; }","duration":"124.087591ms","start":"2026-09-07T11:20:59.729156Z","end":"2026-09-07T11:20:59.853243Z","steps":["trace[622813299] 'read index received' (duration: 32.808221ms)","trace[622813299] 'applied index is now lower than readState.Index' (duration: 91.27768ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:20:59.853459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.287978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:20:59.853502Z","caller":"traceutil/trace.go:171","msg":"trace[1532174395] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:15534; }","duration":"124.39139ms","start":"2026-09-07T11:20:59.729097Z","end":"2026-09-07T11:20:59.853489Z","steps":["trace[1532174395] 'agreement among raft nodes before linearized reading' (duration: 124.297867ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:59.993312Z","caller":"traceutil/trace.go:171","msg":"trace[1256638491] transaction","detail":"{read_only:false; response_revision:15536; number_of_response:1; }","duration":"134.549319ms","start":"2026-09-07T11:20:59.85873Z","end":"2026-09-07T11:20:59.99328Z","steps":["trace[1256638491] 'process raft request' (duration: 134.203739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:21:00.756285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.351301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-09-07T11:21:00.756389Z","caller":"traceutil/trace.go:171","msg":"trace[326447842] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15540; }","duration":"151.524205ms","start":"2026-09-07T11:21:00.604843Z","end":"2026-09-07T11:21:00.756367Z","steps":["trace[326447842] 'range keys from in-memory index tree' (duration: 151.142365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:21:00.756387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.03721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:21:00.756385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.329427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:21:00.756478Z","caller":"traceutil/trace.go:171","msg":"trace[1442454679] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15540; }","duration":"152.182373ms","start":"2026-09-07T11:21:00.604273Z","end":"2026-09-07T11:21:00.756455Z","steps":["trace[1442454679] 'range keys from in-memory index tree' (duration: 151.951577ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:21:00.756502Z","caller":"traceutil/trace.go:171","msg":"trace[2010147905] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15540; }","duration":"152.504972ms","start":"2026-09-07T11:21:00.603965Z","end":"2026-09-07T11:21:00.75647Z","steps":["trace[2010147905] 'range keys from in-memory index tree' (duration: 152.099451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:21:00.756582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.666419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-07T11:21:00.75665Z","caller":"traceutil/trace.go:171","msg":"trace[1353886958] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15540; }","duration":"227.786543ms","start":"2026-09-07T11:21:00.528844Z","end":"2026-09-07T11:21:00.756631Z","steps":["trace[1353886958] 'range keys from in-memory index tree' (duration: 227.394382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:21:00.75677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.390374ms","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-09-07T11:21:00.756827Z","caller":"traceutil/trace.go:171","msg":"trace[1583375791] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15540; }","duration":"130.467127ms","start":"2026-09-07T11:21:00.626342Z","end":"2026-09-07T11:21:00.75681Z","steps":["trace[1583375791] 'range keys from in-memory index tree' (duration: 130.257161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:21:00.756399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.265095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"warn","ts":"2026-09-07T11:21:00.756933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.240174ms","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-09-07T11:21:00.756996Z","caller":"traceutil/trace.go:171","msg":"trace[971090147] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15540; }","duration":"149.343117ms","start":"2026-09-07T11:21:00.607639Z","end":"2026-09-07T11:21:00.756983Z","steps":["trace[971090147] 'range keys from in-memory index tree' (duration: 148.459202ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:21:00.756992Z","caller":"traceutil/trace.go:171","msg":"trace[1168041573] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15540; }","duration":"152.894023ms","start":"2026-09-07T11:21:00.604078Z","end":"2026-09-07T11:21:00.756972Z","steps":["trace[1168041573] 'range keys from in-memory index tree' (duration: 152.100771ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:25:26.177368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15359} {"level":"info","ts":"2026-09-07T11:25:26.290101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15359,"took":"109.052707ms","hash":176092252,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":31977472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T11:25:26.290174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176092252,"revision":15359,"compact-revision":12777} {"level":"warn","ts":"2026-09-07T11:26:32.314683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.687819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:26:32.314797Z","caller":"traceutil/trace.go:171","msg":"trace[606695367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17962; }","duration":"141.865864ms","start":"2026-09-07T11:26:32.172909Z","end":"2026-09-07T11:26:32.314775Z","steps":["trace[606695367] 'range keys from in-memory index tree' (duration: 141.564576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:26:39.340679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.580982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:26:39.340773Z","caller":"traceutil/trace.go:171","msg":"trace[1749728608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18003; }","duration":"168.714056ms","start":"2026-09-07T11:26:39.172037Z","end":"2026-09-07T11:26:39.340751Z","steps":["trace[1749728608] 'range keys from in-memory index tree' (duration: 168.426238ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:26:39.642487Z","caller":"traceutil/trace.go:171","msg":"trace[985612871] transaction","detail":"{read_only:false; response_revision:18008; number_of_response:1; }","duration":"102.951908ms","start":"2026-09-07T11:26:39.539501Z","end":"2026-09-07T11:26:39.642453Z","steps":["trace[985612871] 'process raft request' (duration: 40.934572ms)","trace[985612871] 'compare' (duration: 61.662646ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:26:40.245836Z","caller":"traceutil/trace.go:171","msg":"trace[603551229] linearizableReadLoop","detail":"{readStateIndex:18629; appliedIndex:18628; }","duration":"166.095304ms","start":"2026-09-07T11:26:40.079715Z","end":"2026-09-07T11:26:40.24581Z","steps":["trace[603551229] 'read index received' (duration: 165.813435ms)","trace[603551229] 'applied index is now lower than readState.Index' (duration: 280.809µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:26:40.245946Z","caller":"traceutil/trace.go:171","msg":"trace[1079799092] transaction","detail":"{read_only:false; response_revision:18012; number_of_response:1; }","duration":"246.715013ms","start":"2026-09-07T11:26:39.99917Z","end":"2026-09-07T11:26:40.245885Z","steps":["trace[1079799092] 'process raft request' (duration: 246.474846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:26:40.246151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.414333ms","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-09-07T11:26:40.246267Z","caller":"traceutil/trace.go:171","msg":"trace[1843153276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18012; }","duration":"166.578917ms","start":"2026-09-07T11:26:40.07966Z","end":"2026-09-07T11:26:40.246239Z","steps":["trace[1843153276] 'agreement among raft nodes before linearized reading' (duration: 166.239518ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:26:40.474147Z","caller":"traceutil/trace.go:171","msg":"trace[82215961] transaction","detail":"{read_only:false; response_revision:18013; number_of_response:1; }","duration":"222.453283ms","start":"2026-09-07T11:26:40.251661Z","end":"2026-09-07T11:26:40.474115Z","steps":["trace[82215961] 'process raft request' (duration: 222.122164ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:26:49.607724Z","caller":"traceutil/trace.go:171","msg":"trace[277701350] transaction","detail":"{read_only:false; response_revision:18193; number_of_response:1; }","duration":"139.904379ms","start":"2026-09-07T11:26:49.467797Z","end":"2026-09-07T11:26:49.607702Z","steps":["trace[277701350] 'process raft request' (duration: 139.544419ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:26:49.607942Z","caller":"traceutil/trace.go:171","msg":"trace[30281650] transaction","detail":"{read_only:false; response_revision:18192; number_of_response:1; }","duration":"151.222062ms","start":"2026-09-07T11:26:49.456688Z","end":"2026-09-07T11:26:49.60791Z","steps":["trace[30281650] 'process raft request' (duration: 97.899327ms)","trace[30281650] 'compare' (duration: 52.346328ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:26:49.607952Z","caller":"traceutil/trace.go:171","msg":"trace[1340502751] transaction","detail":"{read_only:false; response_revision:18194; number_of_response:1; }","duration":"139.22152ms","start":"2026-09-07T11:26:49.468689Z","end":"2026-09-07T11:26:49.60791Z","steps":["trace[1340502751] 'process raft request' (duration: 138.732156ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:26:49.608013Z","caller":"traceutil/trace.go:171","msg":"trace[1897195890] linearizableReadLoop","detail":"{readStateIndex:18814; appliedIndex:18813; }","duration":"148.776365ms","start":"2026-09-07T11:26:49.459207Z","end":"2026-09-07T11:26:49.607983Z","steps":["trace[1897195890] 'read index received' (duration: 95.366428ms)","trace[1897195890] 'applied index is now lower than readState.Index' (duration: 53.407457ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:26:49.608287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.073152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-07T11:26:49.608338Z","caller":"traceutil/trace.go:171","msg":"trace[1707019841] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18195; }","duration":"149.174886ms","start":"2026-09-07T11:26:49.459146Z","end":"2026-09-07T11:26:49.608321Z","steps":["trace[1707019841] 'agreement among raft nodes before linearized reading' (duration: 149.024951ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:29:08.485164Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d9b565cb482924cf","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:29:08.493934Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T11:29:08.494075Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T11:30:26.185029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17512} {"level":"info","ts":"2026-09-07T11:30:26.293179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17512,"took":"104.206068ms","hash":1829380943,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":34091008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T11:30:26.293252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1829380943,"revision":17512,"compact-revision":15359} {"level":"info","ts":"2026-09-07T11:32:20.298608Z","caller":"traceutil/trace.go:171","msg":"trace[1102484215] linearizableReadLoop","detail":"{readStateIndex:21388; appliedIndex:21387; }","duration":"124.064828ms","start":"2026-09-07T11:32:20.174521Z","end":"2026-09-07T11:32:20.298586Z","steps":["trace[1102484215] 'read index received' (duration: 123.928034ms)","trace[1102484215] 'applied index is now lower than readState.Index' (duration: 135.604µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:32:20.298703Z","caller":"traceutil/trace.go:171","msg":"trace[414639842] transaction","detail":"{read_only:false; response_revision:20686; number_of_response:1; }","duration":"139.656823ms","start":"2026-09-07T11:32:20.158994Z","end":"2026-09-07T11:32:20.298651Z","steps":["trace[414639842] 'process raft request' (duration: 139.448977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:32:20.298751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.220593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:32:20.298837Z","caller":"traceutil/trace.go:171","msg":"trace[1018108515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20686; }","duration":"124.367856ms","start":"2026-09-07T11:32:20.174448Z","end":"2026-09-07T11:32:20.298815Z","steps":["trace[1018108515] 'agreement among raft nodes before linearized reading' (duration: 124.218872ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:21.155134Z","caller":"traceutil/trace.go:171","msg":"trace[824398302] transaction","detail":"{read_only:false; response_revision:20689; number_of_response:1; }","duration":"252.501468ms","start":"2026-09-07T11:32:20.902584Z","end":"2026-09-07T11:32:21.155086Z","steps":["trace[824398302] 'process raft request' (duration: 225.535947ms)","trace[824398302] 'compare' (duration: 26.621332ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:32:21.190153Z","caller":"traceutil/trace.go:171","msg":"trace[1537305827] transaction","detail":"{read_only:false; response_revision:20690; number_of_response:1; }","duration":"177.400095ms","start":"2026-09-07T11:32:21.012707Z","end":"2026-09-07T11:32:21.190107Z","steps":["trace[1537305827] 'process raft request' (duration: 176.763728ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:21.190327Z","caller":"traceutil/trace.go:171","msg":"trace[1346782271] transaction","detail":"{read_only:false; response_revision:20691; number_of_response:1; }","duration":"171.902132ms","start":"2026-09-07T11:32:21.018407Z","end":"2026-09-07T11:32:21.190309Z","steps":["trace[1346782271] 'process raft request' (duration: 171.208103ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:21.322251Z","caller":"traceutil/trace.go:171","msg":"trace[935922386] transaction","detail":"{read_only:false; response_revision:20692; number_of_response:1; }","duration":"127.660998ms","start":"2026-09-07T11:32:21.194498Z","end":"2026-09-07T11:32:21.322159Z","steps":["trace[935922386] 'process raft request' (duration: 125.951801ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:21.525994Z","caller":"traceutil/trace.go:171","msg":"trace[273330842] transaction","detail":"{read_only:false; response_revision:20694; number_of_response:1; }","duration":"167.457388ms","start":"2026-09-07T11:32:21.358472Z","end":"2026-09-07T11:32:21.525929Z","steps":["trace[273330842] 'process raft request' (duration: 150.977098ms)","trace[273330842] 'compare' (duration: 16.149001ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:32:21.805703Z","caller":"traceutil/trace.go:171","msg":"trace[1408141018] linearizableReadLoop","detail":"{readStateIndex:21397; appliedIndex:21396; }","duration":"239.302631ms","start":"2026-09-07T11:32:21.566365Z","end":"2026-09-07T11:32:21.805668Z","steps":["trace[1408141018] 'read index received' (duration: 214.572351ms)","trace[1408141018] 'applied index is now lower than readState.Index' (duration: 24.72869ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:32:21.805905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.515786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:32:21.80593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.867209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:32:21.805982Z","caller":"traceutil/trace.go:171","msg":"trace[1418087755] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:20695; }","duration":"239.766913ms","start":"2026-09-07T11:32:21.566194Z","end":"2026-09-07T11:32:21.805961Z","steps":["trace[1418087755] 'agreement among raft nodes before linearized reading' (duration: 239.615609ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:21.805996Z","caller":"traceutil/trace.go:171","msg":"trace[183941338] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:20695; }","duration":"153.984982ms","start":"2026-09-07T11:32:21.651984Z","end":"2026-09-07T11:32:21.805969Z","steps":["trace[183941338] 'agreement among raft nodes before linearized reading' (duration: 153.868009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:32:21.806036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.326569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-07T11:32:21.806094Z","caller":"traceutil/trace.go:171","msg":"trace[1134584432] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:20695; }","duration":"118.410861ms","start":"2026-09-07T11:32:21.687667Z","end":"2026-09-07T11:32:21.806078Z","steps":["trace[1134584432] 'agreement among raft nodes before linearized reading' (duration: 118.233746ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:21.805781Z","caller":"traceutil/trace.go:171","msg":"trace[1111381715] transaction","detail":"{read_only:false; response_revision:20695; number_of_response:1; }","duration":"274.825842ms","start":"2026-09-07T11:32:21.530916Z","end":"2026-09-07T11:32:21.805742Z","steps":["trace[1111381715] 'process raft request' (duration: 250.090852ms)","trace[1111381715] 'compare' (duration: 24.359619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:34:33.112859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.960109ms","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":"info","ts":"2026-09-07T11:34:33.113007Z","caller":"traceutil/trace.go:171","msg":"trace[927535450] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:21720; }","duration":"466.163635ms","start":"2026-09-07T11:34:32.646819Z","end":"2026-09-07T11:34:33.112983Z","steps":["trace[927535450] 'count revisions from in-memory index tree' (duration: 465.821145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:34:33.113068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:34:32.646768Z","time spent":"466.271617ms","remote":"127.0.0.1:42064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:34:33.113196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.977347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:34:33.113252Z","caller":"traceutil/trace.go:171","msg":"trace[469369101] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:21720; }","duration":"357.047479ms","start":"2026-09-07T11:34:32.756189Z","end":"2026-09-07T11:34:33.113237Z","steps":["trace[469369101] 'count revisions from in-memory index tree' (duration: 356.882264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:34:33.113272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:34:32.756121Z","time spent":"357.145701ms","remote":"127.0.0.1:58784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:34:33.113323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.163045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T11:34:33.113398Z","caller":"traceutil/trace.go:171","msg":"trace[102460918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21720; }","duration":"580.287738ms","start":"2026-09-07T11:34:32.53309Z","end":"2026-09-07T11:34:33.113378Z","steps":["trace[102460918] 'range keys from in-memory index tree' (duration: 579.905228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:34:33.113437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:34:32.533068Z","time spent":"580.35921ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-07T11:35:26.206891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19901} {"level":"info","ts":"2026-09-07T11:35:26.326684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19901,"took":"115.894403ms","hash":2348990095,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T11:35:26.32677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2348990095,"revision":19901,"compact-revision":17512} {"level":"info","ts":"2026-09-07T11:36:21.067874Z","caller":"traceutil/trace.go:171","msg":"trace[836585436] transaction","detail":"{read_only:false; response_revision:23014; number_of_response:1; }","duration":"109.700962ms","start":"2026-09-07T11:36:20.958138Z","end":"2026-09-07T11:36:21.067839Z","steps":["trace[836585436] 'process raft request' (duration: 31.431488ms)","trace[836585436] 'compare' (duration: 77.993766ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:21.068945Z","caller":"traceutil/trace.go:171","msg":"trace[511493112] linearizableReadLoop","detail":"{readStateIndex:23791; appliedIndex:23790; }","duration":"110.259458ms","start":"2026-09-07T11:36:20.958464Z","end":"2026-09-07T11:36:21.068723Z","steps":["trace[511493112] 'read index received' (duration: 31.174281ms)","trace[511493112] 'applied index is now lower than readState.Index' (duration: 79.082556ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:21.069276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.792863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capo-system/capo-controller-manager-8c5df4f-n6cp5\" ","response":"range_response_count:1 size:4467"} {"level":"info","ts":"2026-09-07T11:36:21.069347Z","caller":"traceutil/trace.go:171","msg":"trace[628748402] range","detail":"{range_begin:/registry/pods/capo-system/capo-controller-manager-8c5df4f-n6cp5; range_end:; response_count:1; response_revision:23015; }","duration":"110.867625ms","start":"2026-09-07T11:36:20.958456Z","end":"2026-09-07T11:36:21.069324Z","steps":["trace[628748402] 'agreement among raft nodes before linearized reading' (duration: 110.285929ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:23.820261Z","caller":"traceutil/trace.go:171","msg":"trace[74895301] linearizableReadLoop","detail":"{readStateIndex:23866; appliedIndex:23865; }","duration":"104.55045ms","start":"2026-09-07T11:36:23.715684Z","end":"2026-09-07T11:36:23.820234Z","steps":["trace[74895301] 'read index received' (duration: 104.358815ms)","trace[74895301] 'applied index is now lower than readState.Index' (duration: 190.285µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:23.820488Z","caller":"traceutil/trace.go:171","msg":"trace[1210446035] transaction","detail":"{read_only:false; response_revision:23089; number_of_response:1; }","duration":"106.605647ms","start":"2026-09-07T11:36:23.713701Z","end":"2026-09-07T11:36:23.820307Z","steps":["trace[1210446035] 'process raft request' (duration: 106.320999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:23.82125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.699075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/cert-manager-test/\" range_end:\"/registry/cilium.io/ciliumendpoints/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:23.821367Z","caller":"traceutil/trace.go:171","msg":"trace[1630997142] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/cert-manager-test/; range_end:/registry/cilium.io/ciliumendpoints/cert-manager-test0; response_count:0; response_revision:23089; }","duration":"105.715723ms","start":"2026-09-07T11:36:23.715627Z","end":"2026-09-07T11:36:23.821342Z","steps":["trace[1630997142] 'agreement among raft nodes before linearized reading' (duration: 104.692084ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:30.24612Z","caller":"traceutil/trace.go:171","msg":"trace[919166510] transaction","detail":"{read_only:false; response_revision:23220; number_of_response:1; }","duration":"376.62771ms","start":"2026-09-07T11:36:29.869463Z","end":"2026-09-07T11:36:30.24609Z","steps":["trace[919166510] 'process raft request' (duration: 376.444214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:30.246309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:29.869449Z","time spent":"376.768293ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:36:30.26098Z","caller":"traceutil/trace.go:171","msg":"trace[445322436] transaction","detail":"{read_only:false; response_revision:23221; number_of_response:1; }","duration":"391.46144ms","start":"2026-09-07T11:36:29.869501Z","end":"2026-09-07T11:36:30.260962Z","steps":["trace[445322436] 'process raft request' (duration: 391.290685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:30.261081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:29.869323Z","time spent":"391.709068ms","remote":"127.0.0.1:42116","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-09-07T11:36:30.261232Z","caller":"traceutil/trace.go:171","msg":"trace[936044641] transaction","detail":"{read_only:false; response_revision:23222; number_of_response:1; }","duration":"294.852069ms","start":"2026-09-07T11:36:29.966356Z","end":"2026-09-07T11:36:30.261208Z","steps":["trace[936044641] 'process raft request' (duration: 294.556721ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:30.261384Z","caller":"traceutil/trace.go:171","msg":"trace[2107910295] transaction","detail":"{read_only:false; response_revision:23223; number_of_response:1; }","duration":"222.50437ms","start":"2026-09-07T11:36:30.038863Z","end":"2026-09-07T11:36:30.261367Z","steps":["trace[2107910295] 'process raft request' (duration: 222.254733ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:30.261446Z","caller":"traceutil/trace.go:171","msg":"trace[1306197607] linearizableReadLoop","detail":"{readStateIndex:24000; appliedIndex:23998; }","duration":"388.94359ms","start":"2026-09-07T11:36:29.872486Z","end":"2026-09-07T11:36:30.261429Z","steps":["trace[1306197607] 'read index received' (duration: 373.479352ms)","trace[1306197607] 'applied index is now lower than readState.Index' (duration: 15.463358ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:30.26155Z","caller":"traceutil/trace.go:171","msg":"trace[1585852275] transaction","detail":"{read_only:false; response_revision:23224; number_of_response:1; }","duration":"149.597145ms","start":"2026-09-07T11:36:30.111943Z","end":"2026-09-07T11:36:30.26154Z","steps":["trace[1585852275] 'process raft request' (duration: 149.268785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:30.261815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.316731ms","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-09-07T11:36:30.261849Z","caller":"traceutil/trace.go:171","msg":"trace[1021852962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23226; }","duration":"389.364712ms","start":"2026-09-07T11:36:29.872473Z","end":"2026-09-07T11:36:30.261838Z","steps":["trace[1021852962] 'agreement among raft nodes before linearized reading' (duration: 389.239449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:30.261877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:29.872384Z","time spent":"389.484095ms","remote":"127.0.0.1:42116","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-09-07T11:40:26.213865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22108} {"level":"info","ts":"2026-09-07T11:40:26.337599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22108,"took":"119.39516ms","hash":4021859852,"current-db-size-bytes":58228736,"current-db-size":"58 MB","current-db-size-in-use-bytes":42864640,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-07T11:40:26.337792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4021859852,"revision":22108,"compact-revision":19901} {"level":"info","ts":"2026-09-07T11:44:24.236264Z","caller":"traceutil/trace.go:171","msg":"trace[304329351] linearizableReadLoop","detail":"{readStateIndex:27921; appliedIndex:27920; }","duration":"119.055243ms","start":"2026-09-07T11:44:24.117186Z","end":"2026-09-07T11:44:24.236241Z","steps":["trace[304329351] 'read index received' (duration: 118.986101ms)","trace[304329351] 'applied index is now lower than readState.Index' (duration: 67.862µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:44:24.23638Z","caller":"traceutil/trace.go:171","msg":"trace[730827502] transaction","detail":"{read_only:false; response_revision:27036; number_of_response:1; }","duration":"119.991798ms","start":"2026-09-07T11:44:24.116356Z","end":"2026-09-07T11:44:24.236348Z","steps":["trace[730827502] 'process raft request' (duration: 119.737971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:44:24.236477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.26853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ngdx6\" ","response":"range_response_count:1 size:9576"} {"level":"info","ts":"2026-09-07T11:44:24.236519Z","caller":"traceutil/trace.go:171","msg":"trace[805457095] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ngdx6; range_end:; response_count:1; response_revision:27036; }","duration":"119.326831ms","start":"2026-09-07T11:44:24.117179Z","end":"2026-09-07T11:44:24.236506Z","steps":["trace[805457095] 'agreement among raft nodes before linearized reading' (duration: 119.181076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:44:24.250978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.142032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-qmspz\" ","response":"range_response_count:1 size:5161"} {"level":"info","ts":"2026-09-07T11:44:24.251062Z","caller":"traceutil/trace.go:171","msg":"trace[191910435] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-qmspz; range_end:; response_count:1; response_revision:27038; }","duration":"121.260415ms","start":"2026-09-07T11:44:24.12978Z","end":"2026-09-07T11:44:24.251041Z","steps":["trace[191910435] 'agreement among raft nodes before linearized reading' (duration: 121.040219ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:45:26.220663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25293} {"level":"info","ts":"2026-09-07T11:45:26.372009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25293,"took":"147.341324ms","hash":3523303069,"current-db-size-bytes":58228736,"current-db-size":"58 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T11:45:26.372064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3523303069,"revision":25293,"compact-revision":22108} {"level":"info","ts":"2026-09-07T11:47:07.348247Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000071fe.wal"}