{"level":"warn","ts":"2026-04-03T02:17:31.33781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T02:17:31.337958Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-04-03T02:17:31.338072Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T02:17:31.33812Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-03T02:17:31.338164Z","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-04-03T02:17:31.33943Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-04-03T02:17:31.339586Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-04-03T02:17:32.239107Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"899.199653ms"} {"level":"info","ts":"2026-04-03T02:17:32.258666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-04-03T02:17:32.258754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T02:17:32.258776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-04-03T02:17:32.258799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T02:17:32.258808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-04-03T02:17:32.258844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-04-03T02:17:32.265854Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T02:17:32.267176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T02:17:32.268451Z","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-04-03T02:17:32.270761Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T02:17:32.27094Z","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-04-03T02:17:32.271044Z","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-04-03T02:17:32.271062Z","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-04-03T02:17:32.272152Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T02:17:32.273188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-04-03T02:17:32.273394Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-03T02:17:32.27581Z","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-04-03T02:17:32.276022Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T02:17:32.276039Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-03T02:17:32.276067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T02:17:32.276084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-03T02:17:33.059927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T02:17:33.059982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T02:17:33.06Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-04-03T02:17:33.060012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-04-03T02:17:33.060017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-03T02:17:33.06003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-04-03T02:17:33.060037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-03T02:17:33.061144Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:17:33.061871Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T02:17:33.061886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:17:33.061991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:17:33.062552Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T02:17:33.062667Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T02:17:33.062894Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:17:33.06308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:17:33.063129Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:17:33.065239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T02:17:33.065416Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-04-03T02:18:01.327221Z","caller":"traceutil/trace.go:171","msg":"trace[1766111427] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"103.929794ms","start":"2026-04-03T02:18:01.223261Z","end":"2026-04-03T02:18:01.327191Z","steps":["trace[1766111427] 'process raft request' (duration: 103.055139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:02.681952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.799623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:18:02.682478Z","caller":"traceutil/trace.go:171","msg":"trace[1229559308] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"246.196632ms","start":"2026-04-03T02:18:02.436255Z","end":"2026-04-03T02:18:02.682452Z","steps":["trace[1229559308] 'process raft request' (duration: 47.494775ms)","trace[1229559308] 'compare' (duration: 196.603147ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:18:02.682604Z","caller":"traceutil/trace.go:171","msg":"trace[765525861] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"240.959063ms","start":"2026-04-03T02:18:02.441613Z","end":"2026-04-03T02:18:02.682572Z","steps":["trace[765525861] 'read index received' (duration: 42.089842ms)","trace[765525861] 'applied index is now lower than readState.Index' (duration: 198.866021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:18:02.682894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.252438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-03T02:18:02.683012Z","caller":"traceutil/trace.go:171","msg":"trace[401695273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:558; }","duration":"241.409192ms","start":"2026-04-03T02:18:02.441585Z","end":"2026-04-03T02:18:02.682995Z","steps":["trace[401695273] 'agreement among raft nodes before linearized reading' (duration: 241.120317ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:02.683378Z","caller":"traceutil/trace.go:171","msg":"trace[694349094] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"202.13898ms","start":"2026-04-03T02:18:02.481223Z","end":"2026-04-03T02:18:02.683362Z","steps":["trace[694349094] 'process raft request' (duration: 201.797121ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:03.021302Z","caller":"traceutil/trace.go:171","msg":"trace[1032908647] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"233.298919ms","start":"2026-04-03T02:18:02.787986Z","end":"2026-04-03T02:18:03.021285Z","steps":["trace[1032908647] 'process raft request' (duration: 232.985732ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:03.021231Z","caller":"traceutil/trace.go:171","msg":"trace[264769534] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"228.722928ms","start":"2026-04-03T02:18:02.79248Z","end":"2026-04-03T02:18:03.021203Z","steps":["trace[264769534] 'read index received' (duration: 228.51732ms)","trace[264769534] 'applied index is now lower than readState.Index' (duration: 204.818µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:18:03.021579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.055906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-h4kg5\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-04-03T02:18:03.02182Z","caller":"traceutil/trace.go:171","msg":"trace[2097772952] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-h4kg5; range_end:; response_count:1; response_revision:564; }","duration":"229.335891ms","start":"2026-04-03T02:18:02.792443Z","end":"2026-04-03T02:18:03.021779Z","steps":["trace[2097772952] 'agreement among raft nodes before linearized reading' (duration: 228.929586ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:03.035585Z","caller":"traceutil/trace.go:171","msg":"trace[12179649] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"195.56067ms","start":"2026-04-03T02:18:02.840002Z","end":"2026-04-03T02:18:03.035563Z","steps":["trace[12179649] 'process raft request' (duration: 195.393946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:03.035777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.207467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-03T02:18:03.035858Z","caller":"traceutil/trace.go:171","msg":"trace[1277899980] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:565; }","duration":"239.328597ms","start":"2026-04-03T02:18:02.796511Z","end":"2026-04-03T02:18:03.035839Z","steps":["trace[1277899980] 'agreement among raft nodes before linearized reading' (duration: 239.018631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:06.935122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958579199,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:18:06.991256Z","caller":"traceutil/trace.go:171","msg":"trace[538535218] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"556.994937ms","start":"2026-04-03T02:18:06.434243Z","end":"2026-04-03T02:18:06.991238Z","steps":["trace[538535218] 'read index received' (duration: 556.318822ms)","trace[538535218] 'applied index is now lower than readState.Index' (duration: 675.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:18:06.991537Z","caller":"traceutil/trace.go:171","msg":"trace[1192087802] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"580.772144ms","start":"2026-04-03T02:18:06.410733Z","end":"2026-04-03T02:18:06.991505Z","steps":["trace[1192087802] 'process raft request' (duration: 580.006774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:06.991741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.489472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-04-03T02:18:06.99178Z","caller":"traceutil/trace.go:171","msg":"trace[781626439] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"557.607855ms","start":"2026-04-03T02:18:06.434161Z","end":"2026-04-03T02:18:06.991769Z","steps":["trace[781626439] 'agreement among raft nodes before linearized reading' (duration: 557.364928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:06.991799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:06.434138Z","time spent":"557.654493ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-03T02:18:06.991912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:06.410696Z","time spent":"580.903147ms","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:18:07.984563Z","caller":"traceutil/trace.go:171","msg":"trace[961325174] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"116.065233ms","start":"2026-04-03T02:18:07.868468Z","end":"2026-04-03T02:18:07.984533Z","steps":["trace[961325174] 'process raft request' (duration: 93.327699ms)","trace[961325174] 'compare' (duration: 22.517415ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:18:08.127344Z","caller":"traceutil/trace.go:171","msg":"trace[463241790] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"141.796289ms","start":"2026-04-03T02:18:07.985521Z","end":"2026-04-03T02:18:08.127317Z","steps":["trace[463241790] 'read index received' (duration: 132.810437ms)","trace[463241790] 'applied index is now lower than readState.Index' (duration: 8.985182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:18:08.127482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.933192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:18:08.127514Z","caller":"traceutil/trace.go:171","msg":"trace[1671208289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"142.015689ms","start":"2026-04-03T02:18:07.985489Z","end":"2026-04-03T02:18:08.127505Z","steps":["trace[1671208289] 'agreement among raft nodes before linearized reading' (duration: 141.942762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:08.127665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.114842ms","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-04-03T02:18:08.127747Z","caller":"traceutil/trace.go:171","msg":"trace[768565648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"140.223187ms","start":"2026-04-03T02:18:07.987487Z","end":"2026-04-03T02:18:08.12771Z","steps":["trace[768565648] 'agreement among raft nodes before linearized reading' (duration: 140.027396ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:08.127452Z","caller":"traceutil/trace.go:171","msg":"trace[1313108995] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"165.706195ms","start":"2026-04-03T02:18:07.961715Z","end":"2026-04-03T02:18:08.127421Z","steps":["trace[1313108995] 'process raft request' (duration: 156.870396ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:08.405085Z","caller":"traceutil/trace.go:171","msg":"trace[125826726] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"213.510229ms","start":"2026-04-03T02:18:08.191538Z","end":"2026-04-03T02:18:08.405049Z","steps":["trace[125826726] 'process raft request' (duration: 213.415834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:09.315759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958579248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:18:09.431246Z","caller":"traceutil/trace.go:171","msg":"trace[1834087279] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"712.432186ms","start":"2026-04-03T02:18:08.718792Z","end":"2026-04-03T02:18:09.431224Z","steps":["trace[1834087279] 'process raft request' (duration: 712.305862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:09.431377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:08.718768Z","time spent":"712.532812ms","remote":"127.0.0.1:32940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4612,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:18:09.782525Z","caller":"traceutil/trace.go:171","msg":"trace[606834045] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"967.792658ms","start":"2026-04-03T02:18:08.814709Z","end":"2026-04-03T02:18:09.782502Z","steps":["trace[606834045] 'read index received' (duration: 616.5441ms)","trace[606834045] 'applied index is now lower than readState.Index' (duration: 351.247638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:18:09.782755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.3367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:18:09.782819Z","caller":"traceutil/trace.go:171","msg":"trace[843567902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"826.440085ms","start":"2026-04-03T02:18:08.956366Z","end":"2026-04-03T02:18:09.782806Z","steps":["trace[843567902] 'agreement among raft nodes before linearized reading' (duration: 826.34199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:09.782851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:08.956337Z","time spent":"826.506943ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:18:09.783016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.977522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-03T02:18:09.783124Z","caller":"traceutil/trace.go:171","msg":"trace[344421654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"572.200042ms","start":"2026-04-03T02:18:09.210904Z","end":"2026-04-03T02:18:09.783104Z","steps":["trace[344421654] 'agreement among raft nodes before linearized reading' (duration: 571.843149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:09.783181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:09.210877Z","time spent":"572.294068ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T02:18:09.783359Z","caller":"traceutil/trace.go:171","msg":"trace[1675430501] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"937.892052ms","start":"2026-04-03T02:18:08.844748Z","end":"2026-04-03T02:18:09.78264Z","steps":["trace[1675430501] 'process raft request' (duration: 937.076539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:09.783431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.692098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c2hr6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-03T02:18:09.783689Z","caller":"traceutil/trace.go:171","msg":"trace[710199137] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c2hr6; range_end:; response_count:1; response_revision:626; }","duration":"968.951385ms","start":"2026-04-03T02:18:08.814678Z","end":"2026-04-03T02:18:09.78363Z","steps":["trace[710199137] 'agreement among raft nodes before linearized reading' (duration: 968.009428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:09.783954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:08.814665Z","time spent":"969.149007ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-c2hr6\" "} {"level":"warn","ts":"2026-04-03T02:18:09.784102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:08.844731Z","time spent":"938.864337ms","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:18:24.214104Z","caller":"traceutil/trace.go:171","msg":"trace[1745100069] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"110.690216ms","start":"2026-04-03T02:18:24.103206Z","end":"2026-04-03T02:18:24.213897Z","steps":["trace[1745100069] 'process raft request' (duration: 110.546929ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:09.390248Z","caller":"traceutil/trace.go:171","msg":"trace[131437496] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"103.661272ms","start":"2026-04-03T02:19:09.286557Z","end":"2026-04-03T02:19:09.390218Z","steps":["trace[131437496] 'process raft request' (duration: 103.455836ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:14.622466Z","caller":"traceutil/trace.go:171","msg":"trace[1886763434] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"139.050749ms","start":"2026-04-03T02:19:14.483382Z","end":"2026-04-03T02:19:14.622433Z","steps":["trace[1886763434] 'read index received' (duration: 138.888334ms)","trace[1886763434] 'applied index is now lower than readState.Index' (duration: 161.315µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:19:14.622781Z","caller":"traceutil/trace.go:171","msg":"trace[1124457828] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"140.493243ms","start":"2026-04-03T02:19:14.482256Z","end":"2026-04-03T02:19:14.622749Z","steps":["trace[1124457828] 'process raft request' (duration: 140.013888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:14.622943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.633797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-03T02:19:14.622942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.510354ms","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-04-03T02:19:14.623005Z","caller":"traceutil/trace.go:171","msg":"trace[1632875526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1390; }","duration":"138.754661ms","start":"2026-04-03T02:19:14.484236Z","end":"2026-04-03T02:19:14.622991Z","steps":["trace[1632875526] 'agreement among raft nodes before linearized reading' (duration: 138.504322ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:14.623016Z","caller":"traceutil/trace.go:171","msg":"trace[1025891337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1390; }","duration":"139.637297ms","start":"2026-04-03T02:19:14.483363Z","end":"2026-04-03T02:19:14.623Z","steps":["trace[1025891337] 'agreement among raft nodes before linearized reading' (duration: 139.435011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:14.623086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.004436ms","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-04-03T02:19:14.623145Z","caller":"traceutil/trace.go:171","msg":"trace[1570802704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"138.072519ms","start":"2026-04-03T02:19:14.485051Z","end":"2026-04-03T02:19:14.623124Z","steps":["trace[1570802704] 'agreement among raft nodes before linearized reading' (duration: 137.920073ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:16.602775Z","caller":"traceutil/trace.go:171","msg":"trace[1511316456] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1443; }","duration":"122.073081ms","start":"2026-04-03T02:19:16.480678Z","end":"2026-04-03T02:19:16.602751Z","steps":["trace[1511316456] 'read index received' (duration: 121.962227ms)","trace[1511316456] 'applied index is now lower than readState.Index' (duration: 110.084µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:16.602895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.189634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:19:16.602953Z","caller":"traceutil/trace.go:171","msg":"trace[1183482453] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1404; }","duration":"122.301677ms","start":"2026-04-03T02:19:16.480641Z","end":"2026-04-03T02:19:16.602942Z","steps":["trace[1183482453] 'agreement among raft nodes before linearized reading' (duration: 122.204984ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:16.602971Z","caller":"traceutil/trace.go:171","msg":"trace[2002975312] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"151.897494ms","start":"2026-04-03T02:19:16.450977Z","end":"2026-04-03T02:19:16.602874Z","steps":["trace[2002975312] 'process raft request' (duration: 151.641225ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:32.825503Z","caller":"traceutil/trace.go:171","msg":"trace[2105963070] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"103.90538ms","start":"2026-04-03T02:19:32.721572Z","end":"2026-04-03T02:19:32.825478Z","steps":["trace[2105963070] 'process raft request' (duration: 103.758354ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:32.825535Z","caller":"traceutil/trace.go:171","msg":"trace[1663894013] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"104.032465ms","start":"2026-04-03T02:19:32.721464Z","end":"2026-04-03T02:19:32.825497Z","steps":["trace[1663894013] 'process raft request' (duration: 102.802424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:40.724693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958581619,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:19:40.744945Z","caller":"traceutil/trace.go:171","msg":"trace[2133043991] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"555.895997ms","start":"2026-04-03T02:19:40.189019Z","end":"2026-04-03T02:19:40.744915Z","steps":["trace[2133043991] 'process raft request' (duration: 555.681157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:40.745265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.18899Z","time spent":"556.172429ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:19:41.002247Z","caller":"traceutil/trace.go:171","msg":"trace[1297296231] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"795.94714ms","start":"2026-04-03T02:19:40.206268Z","end":"2026-04-03T02:19:41.002216Z","steps":["trace[1297296231] 'process raft request' (duration: 795.707609ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:41.002408Z","caller":"traceutil/trace.go:171","msg":"trace[875984195] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"279.360882ms","start":"2026-04-03T02:19:40.72301Z","end":"2026-04-03T02:19:41.002371Z","steps":["trace[875984195] 'process raft request' (duration: 279.150903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.002586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.20624Z","time spent":"796.17983ms","remote":"127.0.0.1:33032","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-04-03T02:19:41.002844Z","caller":"traceutil/trace.go:171","msg":"trace[1162828002] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1879; }","duration":"778.812519ms","start":"2026-04-03T02:19:40.224015Z","end":"2026-04-03T02:19:41.002828Z","steps":["trace[1162828002] 'read index received' (duration: 520.644992ms)","trace[1162828002] 'applied index is now lower than readState.Index' (duration: 258.165657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:41.003098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.079501ms","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-04-03T02:19:41.003162Z","caller":"traceutil/trace.go:171","msg":"trace[6819844] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1815; }","duration":"779.180375ms","start":"2026-04-03T02:19:40.223966Z","end":"2026-04-03T02:19:41.003146Z","steps":["trace[6819844] 'agreement among raft nodes before linearized reading' (duration: 778.991776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.003199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.223952Z","time spent":"779.238518ms","remote":"127.0.0.1:43122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-03T02:19:41.278552Z","caller":"traceutil/trace.go:171","msg":"trace[720803495] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1882; }","duration":"275.613976ms","start":"2026-04-03T02:19:41.002908Z","end":"2026-04-03T02:19:41.278522Z","steps":["trace[720803495] 'read index received' (duration: 231.497341ms)","trace[720803495] 'applied index is now lower than readState.Index' (duration: 44.115715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:41.278761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027742844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-03T02:19:41.278802Z","caller":"traceutil/trace.go:171","msg":"trace[1103634401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1816; }","duration":"1.027839298s","start":"2026-04-03T02:19:40.250952Z","end":"2026-04-03T02:19:41.278791Z","steps":["trace[1103634401] 'agreement among raft nodes before linearized reading' (duration: 1.027694681s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:41.278811Z","caller":"traceutil/trace.go:171","msg":"trace[1005856329] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"390.981367ms","start":"2026-04-03T02:19:40.887815Z","end":"2026-04-03T02:19:41.278796Z","steps":["trace[1005856329] 'process raft request' (duration: 346.60656ms)","trace[1005856329] 'compare' (duration: 43.98663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:41.27885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.250894Z","time spent":"1.027947523s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T02:19:41.279154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.481546ms","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-04-03T02:19:41.279191Z","caller":"traceutil/trace.go:171","msg":"trace[1885747771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1816; }","duration":"532.57789ms","start":"2026-04-03T02:19:40.746604Z","end":"2026-04-03T02:19:41.279182Z","steps":["trace[1885747771] 'agreement among raft nodes before linearized reading' (duration: 532.463065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.279196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.425803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-03T02:19:41.279222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.746586Z","time spent":"532.627143ms","remote":"127.0.0.1:33032","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":"info","ts":"2026-04-03T02:19:41.279239Z","caller":"traceutil/trace.go:171","msg":"trace[806932323] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1816; }","duration":"408.504296ms","start":"2026-04-03T02:19:40.870719Z","end":"2026-04-03T02:19:41.279224Z","steps":["trace[806932323] 'agreement among raft nodes before linearized reading' (duration: 408.402292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.279269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.870693Z","time spent":"408.56903ms","remote":"127.0.0.1:33032","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-04-03T02:19:41.279359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.167285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:19:41.279387Z","caller":"traceutil/trace.go:171","msg":"trace[2090512260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1816; }","duration":"323.22974ms","start":"2026-04-03T02:19:40.95615Z","end":"2026-04-03T02:19:41.27938Z","steps":["trace[2090512260] 'agreement among raft nodes before linearized reading' (duration: 323.190078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.279412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.956132Z","time spent":"323.275251ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:19:41.27945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.826677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-713ac670-407f-47da-b05f-a2699b38a914\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-03T02:19:41.279488Z","caller":"traceutil/trace.go:171","msg":"trace[127988882] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-713ac670-407f-47da-b05f-a2699b38a914; range_end:; response_count:1; response_revision:1816; }","duration":"801.862079ms","start":"2026-04-03T02:19:40.477614Z","end":"2026-04-03T02:19:41.279476Z","steps":["trace[127988882] 'agreement among raft nodes before linearized reading' (duration: 801.769835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.279542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.477569Z","time spent":"801.966933ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-713ac670-407f-47da-b05f-a2699b38a914\" "} {"level":"warn","ts":"2026-04-03T02:19:41.279711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.575735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:19:41.279747Z","caller":"traceutil/trace.go:171","msg":"trace[1122738094] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1816; }","duration":"835.66446ms","start":"2026-04-03T02:19:40.444074Z","end":"2026-04-03T02:19:41.279738Z","steps":["trace[1122738094] 'agreement among raft nodes before linearized reading' (duration: 835.582686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.278761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.975858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-03T02:19:41.279887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003331035s","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-04-03T02:19:41.279929Z","caller":"traceutil/trace.go:171","msg":"trace[1799937269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1816; }","duration":"1.003395568s","start":"2026-04-03T02:19:40.276523Z","end":"2026-04-03T02:19:41.279919Z","steps":["trace[1799937269] 'agreement among raft nodes before linearized reading' (duration: 1.003315585s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.279957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.276511Z","time spent":"1.003439831s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T02:19:41.279775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.444057Z","time spent":"835.709712ms","remote":"127.0.0.1:33228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-03T02:19:41.279925Z","caller":"traceutil/trace.go:171","msg":"trace[1536267266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"412.160021ms","start":"2026-04-03T02:19:40.867728Z","end":"2026-04-03T02:19:41.279888Z","steps":["trace[1536267266] 'agreement among raft nodes before linearized reading' (duration: 410.898745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.280082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.887794Z","time spent":"391.049571ms","remote":"127.0.0.1:33032","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-04-03T02:19:41.279794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002835323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-713ac670-407f-47da-b05f-a2699b38a914\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-03T02:19:41.280196Z","caller":"traceutil/trace.go:171","msg":"trace[153069187] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-713ac670-407f-47da-b05f-a2699b38a914; range_end:; response_count:1; response_revision:1816; }","duration":"1.003266953s","start":"2026-04-03T02:19:40.276918Z","end":"2026-04-03T02:19:41.280185Z","steps":["trace[153069187] 'agreement among raft nodes before linearized reading' (duration: 1.002806942s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.280227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.276903Z","time spent":"1.003315935s","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-713ac670-407f-47da-b05f-a2699b38a914\" "} {"level":"warn","ts":"2026-04-03T02:19:41.279795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.180157ms","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-04-03T02:19:41.280331Z","caller":"traceutil/trace.go:171","msg":"trace[1475200468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1816; }","duration":"409.759763ms","start":"2026-04-03T02:19:40.870559Z","end":"2026-04-03T02:19:41.280319Z","steps":["trace[1475200468] 'agreement among raft nodes before linearized reading' (duration: 409.039261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:41.280374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.870551Z","time spent":"409.810845ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T02:19:41.281334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:40.867689Z","time spent":"412.404412ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T02:19:42.077201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.030411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:19:42.077824Z","caller":"traceutil/trace.go:171","msg":"trace[1768461490] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"791.479271ms","start":"2026-04-03T02:19:41.286325Z","end":"2026-04-03T02:19:42.077804Z","steps":["trace[1768461490] 'process raft request' (duration: 791.408369ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:42.077812Z","caller":"traceutil/trace.go:171","msg":"trace[1966191687] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"792.406264ms","start":"2026-04-03T02:19:41.28537Z","end":"2026-04-03T02:19:42.077777Z","steps":["trace[1966191687] 'process raft request' (duration: 792.214695ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:42.077853Z","caller":"traceutil/trace.go:171","msg":"trace[1695160301] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"791.89819ms","start":"2026-04-03T02:19:41.285913Z","end":"2026-04-03T02:19:42.077811Z","steps":["trace[1695160301] 'process raft request' (duration: 791.786215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:42.077914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.286314Z","time spent":"791.557646ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:19:42.078008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.285356Z","time spent":"792.537049ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:19:42.078043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.285907Z","time spent":"792.038787ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:19:42.078052Z","caller":"traceutil/trace.go:171","msg":"trace[1222410762] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"793.19211ms","start":"2026-04-03T02:19:41.28484Z","end":"2026-04-03T02:19:42.078032Z","steps":["trace[1222410762] 'process raft request' (duration: 102.166273ms)","trace[1222410762] 'compare' (duration: 689.075557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:42.078125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.284823Z","time spent":"793.262973ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:19:42.078215Z","caller":"traceutil/trace.go:171","msg":"trace[1776321112] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"792.470707ms","start":"2026-04-03T02:19:41.285727Z","end":"2026-04-03T02:19:42.078197Z","steps":["trace[1776321112] 'process raft request' (duration: 791.931962ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:42.078283Z","caller":"traceutil/trace.go:171","msg":"trace[2029753685] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"791.756964ms","start":"2026-04-03T02:19:41.286512Z","end":"2026-04-03T02:19:42.078269Z","steps":["trace[2029753685] 'process raft request' (duration: 791.271201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:42.078311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.28572Z","time spent":"792.527738ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:19:42.078366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.286505Z","time spent":"791.807627ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:19:42.078368Z","caller":"traceutil/trace.go:171","msg":"trace[813323367] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1884; }","duration":"771.988745ms","start":"2026-04-03T02:19:41.30634Z","end":"2026-04-03T02:19:42.078329Z","steps":["trace[813323367] 'read index received' (duration: 80.635994ms)","trace[813323367] 'applied index is now lower than readState.Index' (duration: 691.350721ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:19:42.097015Z","caller":"traceutil/trace.go:171","msg":"trace[332992554] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"276.045895ms","start":"2026-04-03T02:19:41.82095Z","end":"2026-04-03T02:19:42.096996Z","steps":["trace[332992554] 'process raft request' (duration: 275.875887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:42.097095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.713475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:19:42.097236Z","caller":"traceutil/trace.go:171","msg":"trace[1577523296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1824; }","duration":"140.882762ms","start":"2026-04-03T02:19:41.956291Z","end":"2026-04-03T02:19:42.097173Z","steps":["trace[1577523296] 'agreement among raft nodes before linearized reading' (duration: 140.701273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:42.097035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.686394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-03T02:19:42.097408Z","caller":"traceutil/trace.go:171","msg":"trace[21264788] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1823; }","duration":"791.053301ms","start":"2026-04-03T02:19:41.306328Z","end":"2026-04-03T02:19:42.097381Z","steps":["trace[21264788] 'agreement among raft nodes before linearized reading' (duration: 772.133292ms)","trace[21264788] 'range keys from in-memory index tree' (duration: 18.422456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:42.097507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:41.306316Z","time spent":"791.158045ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-03T02:19:46.998877Z","caller":"traceutil/trace.go:171","msg":"trace[1190456892] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"225.59094ms","start":"2026-04-03T02:19:46.773261Z","end":"2026-04-03T02:19:46.998852Z","steps":["trace[1190456892] 'process raft request' (duration: 225.179441ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:54.280273Z","caller":"traceutil/trace.go:171","msg":"trace[647210592] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"133.617315ms","start":"2026-04-03T02:19:54.146627Z","end":"2026-04-03T02:19:54.280244Z","steps":["trace[647210592] 'process raft request' (duration: 133.32786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:54.28061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.12398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T02:19:54.280685Z","caller":"traceutil/trace.go:171","msg":"trace[582754024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1921; }","duration":"133.268187ms","start":"2026-04-03T02:19:54.1474Z","end":"2026-04-03T02:19:54.280668Z","steps":["trace[582754024] 'agreement among raft nodes before linearized reading' (duration: 132.930939ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:54.280203Z","caller":"traceutil/trace.go:171","msg":"trace[7997365] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"132.747341ms","start":"2026-04-03T02:19:54.147419Z","end":"2026-04-03T02:19:54.280166Z","steps":["trace[7997365] 'read index received' (duration: 132.590402ms)","trace[7997365] 'applied index is now lower than readState.Index' (duration: 155.138µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:19:54.538373Z","caller":"traceutil/trace.go:171","msg":"trace[780226932] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"390.855038ms","start":"2026-04-03T02:19:54.14749Z","end":"2026-04-03T02:19:54.538345Z","steps":["trace[780226932] 'process raft request' (duration: 390.645887ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:54.538658Z","caller":"traceutil/trace.go:171","msg":"trace[1163479319] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"388.798454ms","start":"2026-04-03T02:19:54.14981Z","end":"2026-04-03T02:19:54.538609Z","steps":["trace[1163479319] 'process raft request' (duration: 388.488848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:54.538884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.482984ms","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-04-03T02:19:54.53904Z","caller":"traceutil/trace.go:171","msg":"trace[647030823] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1923; }","duration":"367.654752ms","start":"2026-04-03T02:19:54.171366Z","end":"2026-04-03T02:19:54.539021Z","steps":["trace[647030823] 'agreement among raft nodes before linearized reading' (duration: 367.252942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:54.539059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:54.149802Z","time spent":"388.956232ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:19:54.538971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.043914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"warn","ts":"2026-04-03T02:19:54.53915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:54.171359Z","time spent":"367.738297ms","remote":"127.0.0.1:43122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-03T02:19:54.53931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.156762ms","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-04-03T02:19:54.5394Z","caller":"traceutil/trace.go:171","msg":"trace[1405873801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1923; }","duration":"391.275419ms","start":"2026-04-03T02:19:54.148106Z","end":"2026-04-03T02:19:54.539381Z","steps":["trace[1405873801] 'agreement among raft nodes before linearized reading' (duration: 390.983324ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:19:54.539355Z","caller":"traceutil/trace.go:171","msg":"trace[579936858] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1992; }","duration":"257.451204ms","start":"2026-04-03T02:19:54.281064Z","end":"2026-04-03T02:19:54.538515Z","steps":["trace[579936858] 'read index received' (duration: 257.055653ms)","trace[579936858] 'applied index is now lower than readState.Index' (duration: 393.501µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:19:54.539455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:54.148099Z","time spent":"391.347501ms","remote":"127.0.0.1:33032","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":"info","ts":"2026-04-03T02:19:54.539311Z","caller":"traceutil/trace.go:171","msg":"trace[1080127596] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1923; }","duration":"387.409134ms","start":"2026-04-03T02:19:54.151837Z","end":"2026-04-03T02:19:54.539246Z","steps":["trace[1080127596] 'agreement among raft nodes before linearized reading' (duration: 386.778232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:54.540095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:54.151828Z","time spent":"388.243325ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:19:54.539625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:19:54.147479Z","time spent":"391.045667ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:19:54.540523Z","caller":"traceutil/trace.go:171","msg":"trace[68467483] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"255.59667ms","start":"2026-04-03T02:19:54.284903Z","end":"2026-04-03T02:19:54.540499Z","steps":["trace[68467483] 'process raft request' (duration: 255.495795ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:30.755957Z","caller":"traceutil/trace.go:171","msg":"trace[1519914064] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"247.210674ms","start":"2026-04-03T02:20:30.508712Z","end":"2026-04-03T02:20:30.755923Z","steps":["trace[1519914064] 'process raft request' (duration: 246.965538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:36.2935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.068054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:20:36.293608Z","caller":"traceutil/trace.go:171","msg":"trace[1707855944] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"672.306609ms","start":"2026-04-03T02:20:35.621284Z","end":"2026-04-03T02:20:36.293591Z","steps":["trace[1707855944] 'read index received' (duration: 76.514µs)","trace[1707855944] 'applied index is now lower than readState.Index' (duration: 672.224995ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:36.293825Z","caller":"traceutil/trace.go:171","msg":"trace[982233246] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"793.648127ms","start":"2026-04-03T02:20:35.500165Z","end":"2026-04-03T02:20:36.293813Z","steps":["trace[982233246] 'process raft request' (duration: 32.193549ms)","trace[982233246] 'compare' (duration: 760.554803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:36.294026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.473926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T02:20:36.294094Z","caller":"traceutil/trace.go:171","msg":"trace[1389755970] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:2158; }","duration":"186.582484ms","start":"2026-04-03T02:20:36.1075Z","end":"2026-04-03T02:20:36.294082Z","steps":["trace[1389755970] 'agreement among raft nodes before linearized reading' (duration: 186.457746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:36.293893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:35.500116Z","time spent":"793.743645ms","remote":"127.0.0.1:33032","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-04-03T02:20:36.294088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.341292ms","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":"warn","ts":"2026-04-03T02:20:36.294027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.744326ms","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-04-03T02:20:36.294167Z","caller":"traceutil/trace.go:171","msg":"trace[445673539] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2158; }","duration":"489.458451ms","start":"2026-04-03T02:20:35.804687Z","end":"2026-04-03T02:20:36.294145Z","steps":["trace[445673539] 'agreement among raft nodes before linearized reading' (duration: 489.243408ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:36.294179Z","caller":"traceutil/trace.go:171","msg":"trace[1263811392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2158; }","duration":"672.937228ms","start":"2026-04-03T02:20:35.621229Z","end":"2026-04-03T02:20:36.294166Z","steps":["trace[1263811392] 'agreement among raft nodes before linearized reading' (duration: 672.697293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:36.294214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:35.621193Z","time spent":"673.012962ms","remote":"127.0.0.1:33032","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-04-03T02:20:36.294214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:35.804667Z","time spent":"489.538926ms","remote":"127.0.0.1:43122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-03T02:21:09.154287Z","caller":"traceutil/trace.go:171","msg":"trace[519280269] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2524; }","duration":"123.278889ms","start":"2026-04-03T02:21:09.03099Z","end":"2026-04-03T02:21:09.154269Z","steps":["trace[519280269] 'read index received' (duration: 123.194093ms)","trace[519280269] 'applied index is now lower than readState.Index' (duration: 83.796µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:21:09.154392Z","caller":"traceutil/trace.go:171","msg":"trace[1907604167] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"124.939188ms","start":"2026-04-03T02:21:09.029441Z","end":"2026-04-03T02:21:09.15438Z","steps":["trace[1907604167] 'process raft request' (duration: 124.698962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:21:09.154623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.608769ms","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-04-03T02:21:09.154732Z","caller":"traceutil/trace.go:171","msg":"trace[1824452549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2437; }","duration":"123.75783ms","start":"2026-04-03T02:21:09.030955Z","end":"2026-04-03T02:21:09.154713Z","steps":["trace[1824452549] 'agreement among raft nodes before linearized reading' (duration: 123.434779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:21:09.159248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.547128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-03T02:21:09.159275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.080545ms","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-04-03T02:21:09.159312Z","caller":"traceutil/trace.go:171","msg":"trace[1776732] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2438; }","duration":"123.137318ms","start":"2026-04-03T02:21:09.036163Z","end":"2026-04-03T02:21:09.1593Z","steps":["trace[1776732] 'agreement among raft nodes before linearized reading' (duration: 123.058024ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:09.159315Z","caller":"traceutil/trace.go:171","msg":"trace[538791656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2438; }","duration":"117.609791ms","start":"2026-04-03T02:21:09.041672Z","end":"2026-04-03T02:21:09.159282Z","steps":["trace[538791656] 'agreement among raft nodes before linearized reading' (duration: 117.494664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:21:09.159468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.317708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"warn","ts":"2026-04-03T02:21:09.159468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.904388ms","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-04-03T02:21:09.159558Z","caller":"traceutil/trace.go:171","msg":"trace[1976004154] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2438; }","duration":"124.414654ms","start":"2026-04-03T02:21:09.035115Z","end":"2026-04-03T02:21:09.159529Z","steps":["trace[1976004154] 'agreement among raft nodes before linearized reading' (duration: 124.037799ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:09.159599Z","caller":"traceutil/trace.go:171","msg":"trace[2003224816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2438; }","duration":"116.079201ms","start":"2026-04-03T02:21:09.043487Z","end":"2026-04-03T02:21:09.159567Z","steps":["trace[2003224816] 'agreement among raft nodes before linearized reading' (duration: 115.748749ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:42.369522Z","caller":"traceutil/trace.go:171","msg":"trace[222327224] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"127.155737ms","start":"2026-04-03T02:21:42.242339Z","end":"2026-04-03T02:21:42.369495Z","steps":["trace[222327224] 'process raft request' (duration: 126.925821ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:42.70346Z","caller":"traceutil/trace.go:171","msg":"trace[1427476828] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2716; }","duration":"101.617018ms","start":"2026-04-03T02:21:42.601811Z","end":"2026-04-03T02:21:42.703428Z","steps":["trace[1427476828] 'read index received' (duration: 101.425545ms)","trace[1427476828] 'applied index is now lower than readState.Index' (duration: 189.883µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:21:42.703511Z","caller":"traceutil/trace.go:171","msg":"trace[1898323362] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"115.798499ms","start":"2026-04-03T02:21:42.587679Z","end":"2026-04-03T02:21:42.703478Z","steps":["trace[1898323362] 'process raft request' (duration: 115.551082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:21:42.703698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.851443ms","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-04-03T02:21:42.703903Z","caller":"traceutil/trace.go:171","msg":"trace[272844396] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2622; }","duration":"102.044338ms","start":"2026-04-03T02:21:42.60174Z","end":"2026-04-03T02:21:42.703784Z","steps":["trace[272844396] 'agreement among raft nodes before linearized reading' (duration: 101.831583ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:42.934302Z","caller":"traceutil/trace.go:171","msg":"trace[184451830] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"227.668768ms","start":"2026-04-03T02:21:42.706566Z","end":"2026-04-03T02:21:42.934235Z","steps":["trace[184451830] 'process raft request' (duration: 226.508097ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:42.934325Z","caller":"traceutil/trace.go:171","msg":"trace[1726837600] linearizableReadLoop","detail":"{readStateIndex:2718; appliedIndex:2717; }","duration":"225.735973ms","start":"2026-04-03T02:21:42.708552Z","end":"2026-04-03T02:21:42.934288Z","steps":["trace[1726837600] 'read index received' (duration: 224.617126ms)","trace[1726837600] 'applied index is now lower than readState.Index' (duration: 1.116807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:21:42.934613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.932858ms","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-04-03T02:21:42.934712Z","caller":"traceutil/trace.go:171","msg":"trace[382929811] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2623; }","duration":"226.209907ms","start":"2026-04-03T02:21:42.70848Z","end":"2026-04-03T02:21:42.93469Z","steps":["trace[382929811] 'agreement among raft nodes before linearized reading' (duration: 225.924367ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:21:42.935192Z","caller":"traceutil/trace.go:171","msg":"trace[869891589] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"226.215096ms","start":"2026-04-03T02:21:42.708943Z","end":"2026-04-03T02:21:42.935158Z","steps":["trace[869891589] 'process raft request' (duration: 225.280722ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:11.809198Z","caller":"traceutil/trace.go:171","msg":"trace[1155384519] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"234.551541ms","start":"2026-04-03T02:22:11.574623Z","end":"2026-04-03T02:22:11.809175Z","steps":["trace[1155384519] 'process raft request' (duration: 147.342009ms)","trace[1155384519] 'compare' (duration: 86.961885ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:22:11.809207Z","caller":"traceutil/trace.go:171","msg":"trace[1583461317] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"233.736515ms","start":"2026-04-03T02:22:11.575447Z","end":"2026-04-03T02:22:11.809184Z","steps":["trace[1583461317] 'process raft request' (duration: 233.609056ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:11.809644Z","caller":"traceutil/trace.go:171","msg":"trace[2081097354] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"233.974332ms","start":"2026-04-03T02:22:11.575657Z","end":"2026-04-03T02:22:11.809632Z","steps":["trace[2081097354] 'process raft request' (duration: 233.482667ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:11.810334Z","caller":"traceutil/trace.go:171","msg":"trace[200900618] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"233.927368ms","start":"2026-04-03T02:22:11.576368Z","end":"2026-04-03T02:22:11.810295Z","steps":["trace[200900618] 'process raft request' (duration: 233.224478ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:11.827143Z","caller":"traceutil/trace.go:171","msg":"trace[812628203] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"207.097809ms","start":"2026-04-03T02:22:11.620029Z","end":"2026-04-03T02:22:11.827127Z","steps":["trace[812628203] 'process raft request' (duration: 207.004112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:17.036725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.874177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:25:17.037162Z","caller":"traceutil/trace.go:171","msg":"trace[107624126] linearizableReadLoop","detail":"{readStateIndex:4076; appliedIndex:4073; }","duration":"198.926176ms","start":"2026-04-03T02:25:16.838213Z","end":"2026-04-03T02:25:17.037139Z","steps":["trace[107624126] 'read index received' (duration: 22.474578ms)","trace[107624126] 'applied index is now lower than readState.Index' (duration: 176.450548ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:17.037136Z","caller":"traceutil/trace.go:171","msg":"trace[388073388] transaction","detail":"{read_only:false; response_revision:3916; number_of_response:1; }","duration":"269.916705ms","start":"2026-04-03T02:25:16.767145Z","end":"2026-04-03T02:25:17.037062Z","steps":["trace[388073388] 'process raft request' (duration: 93.609842ms)","trace[388073388] 'compare' (duration: 175.716512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:17.037284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.064371ms","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-04-03T02:25:17.037478Z","caller":"traceutil/trace.go:171","msg":"trace[2103707804] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3918; }","duration":"199.194555ms","start":"2026-04-03T02:25:16.838181Z","end":"2026-04-03T02:25:17.037375Z","steps":["trace[2103707804] 'agreement among raft nodes before linearized reading' (duration: 199.03353ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:17.037488Z","caller":"traceutil/trace.go:171","msg":"trace[1890397228] transaction","detail":"{read_only:false; response_revision:3918; number_of_response:1; }","duration":"266.927909ms","start":"2026-04-03T02:25:16.770281Z","end":"2026-04-03T02:25:17.037209Z","steps":["trace[1890397228] 'process raft request' (duration: 266.800393ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:17.037213Z","caller":"traceutil/trace.go:171","msg":"trace[804835489] transaction","detail":"{read_only:false; response_revision:3917; number_of_response:1; }","duration":"269.887623ms","start":"2026-04-03T02:25:16.767265Z","end":"2026-04-03T02:25:17.037153Z","steps":["trace[804835489] 'process raft request' (duration: 269.566612ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:17.086314Z","caller":"traceutil/trace.go:171","msg":"trace[1591977719] transaction","detail":"{read_only:false; response_revision:3919; number_of_response:1; }","duration":"186.69801ms","start":"2026-04-03T02:25:16.899591Z","end":"2026-04-03T02:25:17.086289Z","steps":["trace[1591977719] 'process raft request' (duration: 186.11875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:20.22539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.040917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:25:20.227235Z","caller":"traceutil/trace.go:171","msg":"trace[626053970] transaction","detail":"{read_only:false; response_revision:3957; number_of_response:1; }","duration":"202.594939ms","start":"2026-04-03T02:25:20.02459Z","end":"2026-04-03T02:25:20.227184Z","steps":["trace[626053970] 'process raft request' (duration: 11.241538ms)","trace[626053970] 'compare' (duration: 188.84705ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:20.227518Z","caller":"traceutil/trace.go:171","msg":"trace[1547320553] transaction","detail":"{read_only:false; response_revision:3958; number_of_response:1; }","duration":"143.694562ms","start":"2026-04-03T02:25:20.083596Z","end":"2026-04-03T02:25:20.227291Z","steps":["trace[1547320553] 'process raft request' (duration: 143.502985ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.144175Z","caller":"traceutil/trace.go:171","msg":"trace[341701579] transaction","detail":"{read_only:false; response_revision:3980; number_of_response:1; }","duration":"114.284613ms","start":"2026-04-03T02:25:24.029839Z","end":"2026-04-03T02:25:24.144123Z","steps":["trace[341701579] 'process raft request' (duration: 64.995638ms)","trace[341701579] 'compare' (duration: 49.183841ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:24.153647Z","caller":"traceutil/trace.go:171","msg":"trace[1114284434] transaction","detail":"{read_only:false; response_revision:3981; number_of_response:1; }","duration":"105.014848ms","start":"2026-04-03T02:25:24.048619Z","end":"2026-04-03T02:25:24.153634Z","steps":["trace[1114284434] 'process raft request' (duration: 104.925225ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.797303Z","caller":"traceutil/trace.go:171","msg":"trace[1583574031] transaction","detail":"{read_only:false; response_revision:4002; number_of_response:1; }","duration":"117.440258ms","start":"2026-04-03T02:25:24.67984Z","end":"2026-04-03T02:25:24.79728Z","steps":["trace[1583574031] 'process raft request' (duration: 117.292083ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.797349Z","caller":"traceutil/trace.go:171","msg":"trace[935843900] transaction","detail":"{read_only:false; response_revision:4001; number_of_response:1; }","duration":"117.689858ms","start":"2026-04-03T02:25:24.679614Z","end":"2026-04-03T02:25:24.797304Z","steps":["trace[935843900] 'process raft request' (duration: 116.732362ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.970968Z","caller":"traceutil/trace.go:171","msg":"trace[855980429] transaction","detail":"{read_only:false; response_revision:4004; number_of_response:1; }","duration":"164.927169ms","start":"2026-04-03T02:25:24.806008Z","end":"2026-04-03T02:25:24.970935Z","steps":["trace[855980429] 'process raft request' (duration: 130.68607ms)","trace[855980429] 'compare' (duration: 34.055472ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:24.971141Z","caller":"traceutil/trace.go:171","msg":"trace[1977278932] transaction","detail":"{read_only:false; response_revision:4007; number_of_response:1; }","duration":"163.38174ms","start":"2026-04-03T02:25:24.807753Z","end":"2026-04-03T02:25:24.971134Z","steps":["trace[1977278932] 'process raft request' (duration: 163.240015ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971189Z","caller":"traceutil/trace.go:171","msg":"trace[550517399] transaction","detail":"{read_only:false; response_revision:4005; number_of_response:1; }","duration":"165.123375ms","start":"2026-04-03T02:25:24.806061Z","end":"2026-04-03T02:25:24.971184Z","steps":["trace[550517399] 'process raft request' (duration: 164.823063ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971236Z","caller":"traceutil/trace.go:171","msg":"trace[942684570] transaction","detail":"{read_only:false; response_revision:4006; number_of_response:1; }","duration":"163.488705ms","start":"2026-04-03T02:25:24.807743Z","end":"2026-04-03T02:25:24.971232Z","steps":["trace[942684570] 'process raft request' (duration: 163.205955ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971281Z","caller":"traceutil/trace.go:171","msg":"trace[746444893] transaction","detail":"{read_only:false; response_revision:4010; number_of_response:1; }","duration":"163.226195ms","start":"2026-04-03T02:25:24.80805Z","end":"2026-04-03T02:25:24.971277Z","steps":["trace[746444893] 'process raft request' (duration: 163.042508ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971427Z","caller":"traceutil/trace.go:171","msg":"trace[1016230090] transaction","detail":"{read_only:false; response_revision:4008; number_of_response:1; }","duration":"163.610289ms","start":"2026-04-03T02:25:24.807809Z","end":"2026-04-03T02:25:24.971419Z","steps":["trace[1016230090] 'process raft request' (duration: 163.218964ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971558Z","caller":"traceutil/trace.go:171","msg":"trace[2075253247] transaction","detail":"{read_only:false; response_revision:4012; number_of_response:1; }","duration":"161.782111ms","start":"2026-04-03T02:25:24.809751Z","end":"2026-04-03T02:25:24.971533Z","steps":["trace[2075253247] 'process raft request' (duration: 161.411828ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971611Z","caller":"traceutil/trace.go:171","msg":"trace[709468999] transaction","detail":"{read_only:false; response_revision:4009; number_of_response:1; }","duration":"163.740714ms","start":"2026-04-03T02:25:24.807864Z","end":"2026-04-03T02:25:24.971605Z","steps":["trace[709468999] 'process raft request' (duration: 163.200384ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971609Z","caller":"traceutil/trace.go:171","msg":"trace[1381327951] transaction","detail":"{read_only:false; response_revision:4013; number_of_response:1; }","duration":"160.843247ms","start":"2026-04-03T02:25:24.810733Z","end":"2026-04-03T02:25:24.971577Z","steps":["trace[1381327951] 'process raft request' (duration: 160.467773ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971722Z","caller":"traceutil/trace.go:171","msg":"trace[828077131] transaction","detail":"{read_only:false; response_revision:4016; number_of_response:1; }","duration":"160.542556ms","start":"2026-04-03T02:25:24.811166Z","end":"2026-04-03T02:25:24.971708Z","steps":["trace[828077131] 'process raft request' (duration: 160.270166ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971763Z","caller":"traceutil/trace.go:171","msg":"trace[874026483] transaction","detail":"{read_only:false; response_revision:4011; number_of_response:1; }","duration":"163.610029ms","start":"2026-04-03T02:25:24.808141Z","end":"2026-04-03T02:25:24.971751Z","steps":["trace[874026483] 'process raft request' (duration: 162.986486ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971604Z","caller":"traceutil/trace.go:171","msg":"trace[927044835] transaction","detail":"{read_only:false; response_revision:4014; number_of_response:1; }","duration":"160.495174ms","start":"2026-04-03T02:25:24.811061Z","end":"2026-04-03T02:25:24.971556Z","steps":["trace[927044835] 'process raft request' (duration: 160.184592ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.971938Z","caller":"traceutil/trace.go:171","msg":"trace[1983012851] transaction","detail":"{read_only:false; response_revision:4019; number_of_response:1; }","duration":"160.351538ms","start":"2026-04-03T02:25:24.811578Z","end":"2026-04-03T02:25:24.971929Z","steps":["trace[1983012851] 'process raft request' (duration: 160.094978ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.972006Z","caller":"traceutil/trace.go:171","msg":"trace[492309845] transaction","detail":"{read_only:false; response_revision:4017; number_of_response:1; }","duration":"160.592267ms","start":"2026-04-03T02:25:24.811407Z","end":"2026-04-03T02:25:24.972Z","steps":["trace[492309845] 'process raft request' (duration: 160.153271ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.972039Z","caller":"traceutil/trace.go:171","msg":"trace[84300617] transaction","detail":"{read_only:false; response_revision:4015; number_of_response:1; }","duration":"160.903289ms","start":"2026-04-03T02:25:24.811121Z","end":"2026-04-03T02:25:24.972025Z","steps":["trace[84300617] 'process raft request' (duration: 160.219863ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.972274Z","caller":"traceutil/trace.go:171","msg":"trace[1808007393] transaction","detail":"{read_only:false; response_revision:4024; number_of_response:1; }","duration":"160.360248ms","start":"2026-04-03T02:25:24.811901Z","end":"2026-04-03T02:25:24.972261Z","steps":["trace[1808007393] 'process raft request' (duration: 160.089048ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.97256Z","caller":"traceutil/trace.go:171","msg":"trace[1232814768] linearizableReadLoop","detail":"{readStateIndex:4195; appliedIndex:4163; }","duration":"157.479452ms","start":"2026-04-03T02:25:24.815059Z","end":"2026-04-03T02:25:24.972538Z","steps":["trace[1232814768] 'read index received' (duration: 20.664095ms)","trace[1232814768] 'applied index is now lower than readState.Index' (duration: 136.814097ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:24.972696Z","caller":"traceutil/trace.go:171","msg":"trace[1579152926] transaction","detail":"{read_only:false; response_revision:4025; number_of_response:1; }","duration":"160.688541ms","start":"2026-04-03T02:25:24.811996Z","end":"2026-04-03T02:25:24.972685Z","steps":["trace[1579152926] 'process raft request' (duration: 160.052798ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973041Z","caller":"traceutil/trace.go:171","msg":"trace[839169885] transaction","detail":"{read_only:false; response_revision:4021; number_of_response:1; }","duration":"161.243611ms","start":"2026-04-03T02:25:24.811787Z","end":"2026-04-03T02:25:24.97303Z","steps":["trace[839169885] 'process raft request' (duration: 160.005745ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973183Z","caller":"traceutil/trace.go:171","msg":"trace[2116118807] transaction","detail":"{read_only:false; response_revision:4018; number_of_response:1; }","duration":"161.664637ms","start":"2026-04-03T02:25:24.811508Z","end":"2026-04-03T02:25:24.973173Z","steps":["trace[2116118807] 'process raft request' (duration: 160.095569ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973158Z","caller":"traceutil/trace.go:171","msg":"trace[595694737] transaction","detail":"{read_only:false; response_revision:4020; number_of_response:1; }","duration":"161.489491ms","start":"2026-04-03T02:25:24.811623Z","end":"2026-04-03T02:25:24.973113Z","steps":["trace[595694737] 'process raft request' (duration: 160.13002ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973266Z","caller":"traceutil/trace.go:171","msg":"trace[1168109077] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"160.025706ms","start":"2026-04-03T02:25:24.813235Z","end":"2026-04-03T02:25:24.973261Z","steps":["trace[1168109077] 'process raft request' (duration: 159.126723ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973312Z","caller":"traceutil/trace.go:171","msg":"trace[1073706361] transaction","detail":"{read_only:false; response_revision:4026; number_of_response:1; }","duration":"161.259622ms","start":"2026-04-03T02:25:24.812048Z","end":"2026-04-03T02:25:24.973308Z","steps":["trace[1073706361] 'process raft request' (duration: 160.035046ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973384Z","caller":"traceutil/trace.go:171","msg":"trace[2130503808] transaction","detail":"{read_only:false; response_revision:4027; number_of_response:1; }","duration":"161.266101ms","start":"2026-04-03T02:25:24.812084Z","end":"2026-04-03T02:25:24.97335Z","steps":["trace[2130503808] 'process raft request' (duration: 160.041437ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973464Z","caller":"traceutil/trace.go:171","msg":"trace[1577669778] transaction","detail":"{read_only:false; response_revision:4022; number_of_response:1; }","duration":"161.653826ms","start":"2026-04-03T02:25:24.811797Z","end":"2026-04-03T02:25:24.973451Z","steps":["trace[1577669778] 'process raft request' (duration: 160.091718ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973532Z","caller":"traceutil/trace.go:171","msg":"trace[1980210407] transaction","detail":"{read_only:false; response_revision:4028; number_of_response:1; }","duration":"161.313894ms","start":"2026-04-03T02:25:24.812211Z","end":"2026-04-03T02:25:24.973525Z","steps":["trace[1980210407] 'process raft request' (duration: 159.957404ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973564Z","caller":"traceutil/trace.go:171","msg":"trace[823904960] transaction","detail":"{read_only:false; response_revision:4030; number_of_response:1; }","duration":"160.824967ms","start":"2026-04-03T02:25:24.812721Z","end":"2026-04-03T02:25:24.973546Z","steps":["trace[823904960] 'process raft request' (duration: 159.522949ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973619Z","caller":"traceutil/trace.go:171","msg":"trace[1792146033] transaction","detail":"{read_only:false; response_revision:4033; number_of_response:1; }","duration":"160.114028ms","start":"2026-04-03T02:25:24.813487Z","end":"2026-04-03T02:25:24.973601Z","steps":["trace[1792146033] 'process raft request' (duration: 158.930495ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973648Z","caller":"traceutil/trace.go:171","msg":"trace[1374270633] transaction","detail":"{read_only:false; response_revision:4029; number_of_response:1; }","duration":"161.345796ms","start":"2026-04-03T02:25:24.812291Z","end":"2026-04-03T02:25:24.973636Z","steps":["trace[1374270633] 'process raft request' (duration: 159.914353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:24.973705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.626194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:25:24.973743Z","caller":"traceutil/trace.go:171","msg":"trace[387174635] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4035; }","duration":"158.707348ms","start":"2026-04-03T02:25:24.815024Z","end":"2026-04-03T02:25:24.973731Z","steps":["trace[387174635] 'agreement among raft nodes before linearized reading' (duration: 158.580273ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.97377Z","caller":"traceutil/trace.go:171","msg":"trace[1824502840] transaction","detail":"{read_only:false; response_revision:4023; number_of_response:1; }","duration":"161.885205ms","start":"2026-04-03T02:25:24.811875Z","end":"2026-04-03T02:25:24.97376Z","steps":["trace[1824502840] 'process raft request' (duration: 160.059847ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973845Z","caller":"traceutil/trace.go:171","msg":"trace[461682885] transaction","detail":"{read_only:false; response_revision:4035; number_of_response:1; }","duration":"160.278266ms","start":"2026-04-03T02:25:24.81356Z","end":"2026-04-03T02:25:24.973838Z","steps":["trace[461682885] 'process raft request' (duration: 158.936966ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973912Z","caller":"traceutil/trace.go:171","msg":"trace[330432819] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"160.41157ms","start":"2026-04-03T02:25:24.813494Z","end":"2026-04-03T02:25:24.973906Z","steps":["trace[330432819] 'process raft request' (duration: 158.960057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:24.973916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.970256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:25:24.973954Z","caller":"traceutil/trace.go:171","msg":"trace[1762344293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4035; }","duration":"129.06199ms","start":"2026-04-03T02:25:24.844881Z","end":"2026-04-03T02:25:24.973943Z","steps":["trace[1762344293] 'agreement among raft nodes before linearized reading' (duration: 128.848222ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:24.973993Z","caller":"traceutil/trace.go:171","msg":"trace[906576437] transaction","detail":"{read_only:false; response_revision:4031; number_of_response:1; }","duration":"160.355869ms","start":"2026-04-03T02:25:24.813229Z","end":"2026-04-03T02:25:24.973584Z","steps":["trace[906576437] 'process raft request' (duration: 159.057691ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:25.215715Z","caller":"traceutil/trace.go:171","msg":"trace[331246526] transaction","detail":"{read_only:false; response_revision:4038; number_of_response:1; }","duration":"224.043207ms","start":"2026-04-03T02:25:24.991642Z","end":"2026-04-03T02:25:25.215685Z","steps":["trace[331246526] 'process raft request' (duration: 223.875931ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:25.215724Z","caller":"traceutil/trace.go:171","msg":"trace[868040123] transaction","detail":"{read_only:false; response_revision:4037; number_of_response:1; }","duration":"224.174291ms","start":"2026-04-03T02:25:24.991513Z","end":"2026-04-03T02:25:25.215687Z","steps":["trace[868040123] 'process raft request' (duration: 151.61356ms)","trace[868040123] 'compare' (duration: 66.310759ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:25.215741Z","caller":"traceutil/trace.go:171","msg":"trace[2025831916] transaction","detail":"{read_only:false; response_revision:4039; number_of_response:1; }","duration":"223.485337ms","start":"2026-04-03T02:25:24.992226Z","end":"2026-04-03T02:25:25.215712Z","steps":["trace[2025831916] 'process raft request' (duration: 223.367792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:25.215976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.174463ms","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-04-03T02:25:25.216Z","caller":"traceutil/trace.go:171","msg":"trace[1673942081] transaction","detail":"{read_only:false; response_revision:4040; number_of_response:1; }","duration":"223.120544ms","start":"2026-04-03T02:25:24.99287Z","end":"2026-04-03T02:25:25.21599Z","steps":["trace[1673942081] 'process raft request' (duration: 222.767561ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:25.216037Z","caller":"traceutil/trace.go:171","msg":"trace[79942423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4040; }","duration":"142.310777ms","start":"2026-04-03T02:25:25.073718Z","end":"2026-04-03T02:25:25.216028Z","steps":["trace[79942423] 'agreement among raft nodes before linearized reading' (duration: 142.051137ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:25.215717Z","caller":"traceutil/trace.go:171","msg":"trace[1136114285] linearizableReadLoop","detail":"{readStateIndex:4200; appliedIndex:4196; }","duration":"141.902051ms","start":"2026-04-03T02:25:25.073791Z","end":"2026-04-03T02:25:25.215693Z","steps":["trace[1136114285] 'read index received' (duration: 69.385102ms)","trace[1136114285] 'applied index is now lower than readState.Index' (duration: 72.515649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:25.216231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.778253ms","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-04-03T02:25:25.216264Z","caller":"traceutil/trace.go:171","msg":"trace[982468439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4040; }","duration":"139.841665ms","start":"2026-04-03T02:25:25.076414Z","end":"2026-04-03T02:25:25.216256Z","steps":["trace[982468439] 'agreement among raft nodes before linearized reading' (duration: 139.71325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:25.216347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.849215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-03T02:25:25.216402Z","caller":"traceutil/trace.go:171","msg":"trace[518315459] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4040; }","duration":"139.955931ms","start":"2026-04-03T02:25:25.076431Z","end":"2026-04-03T02:25:25.216387Z","steps":["trace[518315459] 'agreement among raft nodes before linearized reading' (duration: 139.780824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:25.452887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.85908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:25:25.45303Z","caller":"traceutil/trace.go:171","msg":"trace[1788107174] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"161.659076ms","start":"2026-04-03T02:25:25.291347Z","end":"2026-04-03T02:25:25.453007Z","steps":["trace[1788107174] 'process raft request' (duration: 41.602888ms)","trace[1788107174] 'compare' (duration: 119.699534ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:26.012972Z","caller":"traceutil/trace.go:171","msg":"trace[463665473] transaction","detail":"{read_only:false; response_revision:4220; number_of_response:1; }","duration":"125.478901ms","start":"2026-04-03T02:25:25.887455Z","end":"2026-04-03T02:25:26.012934Z","steps":["trace[463665473] 'process raft request' (duration: 125.238893ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:27.313581Z","caller":"traceutil/trace.go:171","msg":"trace[243217135] transaction","detail":"{read_only:false; response_revision:4295; number_of_response:1; }","duration":"400.368223ms","start":"2026-04-03T02:25:26.913192Z","end":"2026-04-03T02:25:27.31356Z","steps":["trace[243217135] 'process raft request' (duration: 390.249803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.313715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:26.913169Z","time spent":"400.477597ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3171,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:25:27.319328Z","caller":"traceutil/trace.go:171","msg":"trace[1056681847] transaction","detail":"{read_only:false; response_revision:4296; number_of_response:1; }","duration":"366.277339ms","start":"2026-04-03T02:25:26.953013Z","end":"2026-04-03T02:25:27.319291Z","steps":["trace[1056681847] 'process raft request' (duration: 366.056811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.319499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:26.952992Z","time spent":"366.432825ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:25:27.319598Z","caller":"traceutil/trace.go:171","msg":"trace[1147968959] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"316.561719ms","start":"2026-04-03T02:25:27.002983Z","end":"2026-04-03T02:25:27.319545Z","steps":["trace[1147968959] 'process raft request' (duration: 316.194696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.319839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:27.002966Z","time spent":"316.753366ms","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4286,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:25:27.321125Z","caller":"traceutil/trace.go:171","msg":"trace[1699753646] linearizableReadLoop","detail":"{readStateIndex:4461; appliedIndex:4459; }","duration":"364.511433ms","start":"2026-04-03T02:25:26.956598Z","end":"2026-04-03T02:25:27.321109Z","steps":["trace[1699753646] 'read index received' (duration: 346.998844ms)","trace[1699753646] 'applied index is now lower than readState.Index' (duration: 17.511709ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:27.321356Z","caller":"traceutil/trace.go:171","msg":"trace[2047383314] transaction","detail":"{read_only:false; response_revision:4298; number_of_response:1; }","duration":"297.13663ms","start":"2026-04-03T02:25:27.024197Z","end":"2026-04-03T02:25:27.321333Z","steps":["trace[2047383314] 'process raft request' (duration: 295.105823ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:27.321542Z","caller":"traceutil/trace.go:171","msg":"trace[1629508931] transaction","detail":"{read_only:false; response_revision:4299; number_of_response:1; }","duration":"268.464342ms","start":"2026-04-03T02:25:27.053058Z","end":"2026-04-03T02:25:27.321523Z","steps":["trace[1629508931] 'process raft request' (duration: 267.962823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.321581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.93998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:25:27.321715Z","caller":"traceutil/trace.go:171","msg":"trace[2145219462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4300; }","duration":"365.156917ms","start":"2026-04-03T02:25:26.956542Z","end":"2026-04-03T02:25:27.321699Z","steps":["trace[2145219462] 'agreement among raft nodes before linearized reading' (duration: 364.918038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.321774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:26.95652Z","time spent":"365.231759ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T02:25:27.321929Z","caller":"traceutil/trace.go:171","msg":"trace[1447213736] transaction","detail":"{read_only:false; response_revision:4300; number_of_response:1; }","duration":"108.368283ms","start":"2026-04-03T02:25:27.213543Z","end":"2026-04-03T02:25:27.321911Z","steps":["trace[1447213736] 'process raft request' (duration: 107.524912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.322429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.908661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:25:27.322484Z","caller":"traceutil/trace.go:171","msg":"trace[2004817133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4300; }","duration":"309.004046ms","start":"2026-04-03T02:25:27.013468Z","end":"2026-04-03T02:25:27.322472Z","steps":["trace[2004817133] 'agreement among raft nodes before linearized reading' (duration: 308.738835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.322511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:27.013455Z","time spent":"309.051048ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T02:25:27.322511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.235967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls-8lctc\" ","response":"range_response_count:1 size:2456"} {"level":"info","ts":"2026-04-03T02:25:27.322552Z","caller":"traceutil/trace.go:171","msg":"trace[333897839] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls-8lctc; range_end:; response_count:1; response_revision:4300; }","duration":"171.30761ms","start":"2026-04-03T02:25:27.151232Z","end":"2026-04-03T02:25:27.32254Z","steps":["trace[333897839] 'agreement among raft nodes before linearized reading' (duration: 171.158014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.322643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.789183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:4316"} {"level":"info","ts":"2026-04-03T02:25:27.322668Z","caller":"traceutil/trace.go:171","msg":"trace[968696501] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4300; }","duration":"218.836576ms","start":"2026-04-03T02:25:27.103825Z","end":"2026-04-03T02:25:27.322662Z","steps":["trace[968696501] 'agreement among raft nodes before linearized reading' (duration: 218.755114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:27.322709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.63383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-03T02:25:27.323227Z","caller":"traceutil/trace.go:171","msg":"trace[932179335] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4300; }","duration":"123.164599ms","start":"2026-04-03T02:25:27.200047Z","end":"2026-04-03T02:25:27.323212Z","steps":["trace[932179335] 'agreement among raft nodes before linearized reading' (duration: 122.592637ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:27.823025Z","caller":"traceutil/trace.go:171","msg":"trace[709648515] transaction","detail":"{read_only:false; response_revision:4326; number_of_response:1; }","duration":"120.186088ms","start":"2026-04-03T02:25:27.702815Z","end":"2026-04-03T02:25:27.823001Z","steps":["trace[709648515] 'process raft request' (duration: 115.486802ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:27.824058Z","caller":"traceutil/trace.go:171","msg":"trace[684901261] transaction","detail":"{read_only:false; response_revision:4327; number_of_response:1; }","duration":"113.291419ms","start":"2026-04-03T02:25:27.710739Z","end":"2026-04-03T02:25:27.82403Z","steps":["trace[684901261] 'process raft request' (duration: 113.104562ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:30.27335Z","caller":"traceutil/trace.go:171","msg":"trace[1235795426] transaction","detail":"{read_only:false; response_revision:4426; number_of_response:1; }","duration":"217.739692ms","start":"2026-04-03T02:25:30.055583Z","end":"2026-04-03T02:25:30.273322Z","steps":["trace[1235795426] 'process raft request' (duration: 217.583176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:30.497516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.508657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ae0e031-7522-4c40-ad31-9544379bd568\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-03T02:25:30.497579Z","caller":"traceutil/trace.go:171","msg":"trace[12085090] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ae0e031-7522-4c40-ad31-9544379bd568; range_end:; response_count:1; response_revision:4426; }","duration":"189.617961ms","start":"2026-04-03T02:25:30.307946Z","end":"2026-04-03T02:25:30.497564Z","steps":["trace[12085090] 'range keys from in-memory index tree' (duration: 189.362581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:30.497909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.66479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T02:25:30.497951Z","caller":"traceutil/trace.go:171","msg":"trace[1476881808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4426; }","duration":"142.736793ms","start":"2026-04-03T02:25:30.355204Z","end":"2026-04-03T02:25:30.497941Z","steps":["trace[1476881808] 'range keys from in-memory index tree' (duration: 142.569627ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:30.500431Z","caller":"traceutil/trace.go:171","msg":"trace[1205259080] transaction","detail":"{read_only:false; response_revision:4427; number_of_response:1; }","duration":"134.156006ms","start":"2026-04-03T02:25:30.366248Z","end":"2026-04-03T02:25:30.500404Z","steps":["trace[1205259080] 'process raft request' (duration: 134.061372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:33.94143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.079844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-150fd8d7-7327-4631-ac38-9c289be4431d\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-04-03T02:25:33.941523Z","caller":"traceutil/trace.go:171","msg":"trace[880260472] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-150fd8d7-7327-4631-ac38-9c289be4431d; range_end:; response_count:1; response_revision:4493; }","duration":"201.223771ms","start":"2026-04-03T02:25:33.74028Z","end":"2026-04-03T02:25:33.941504Z","steps":["trace[880260472] 'range keys from in-memory index tree' (duration: 200.920949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:33.941559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.070734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:25:33.941631Z","caller":"traceutil/trace.go:171","msg":"trace[30517926] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4493; }","duration":"201.166748ms","start":"2026-04-03T02:25:33.740446Z","end":"2026-04-03T02:25:33.941613Z","steps":["trace[30517926] 'count revisions from in-memory index tree' (duration: 201.012743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:36.572412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.922329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-679hv\" ","response":"range_response_count:1 size:5137"} {"level":"info","ts":"2026-04-03T02:25:36.572497Z","caller":"traceutil/trace.go:171","msg":"trace[1024298019] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-679hv; range_end:; response_count:1; response_revision:4556; }","duration":"316.050943ms","start":"2026-04-03T02:25:36.256425Z","end":"2026-04-03T02:25:36.572476Z","steps":["trace[1024298019] 'range keys from in-memory index tree' (duration: 315.744081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:36.572538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:36.256406Z","time spent":"316.123806ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":5160,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-679hv\" "} {"level":"warn","ts":"2026-04-03T02:25:36.573976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.881435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348479"} {"level":"info","ts":"2026-04-03T02:25:36.574191Z","caller":"traceutil/trace.go:171","msg":"trace[914981513] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4556; }","duration":"208.136065ms","start":"2026-04-03T02:25:36.366036Z","end":"2026-04-03T02:25:36.574172Z","steps":["trace[914981513] 'range keys from in-memory index tree' (duration: 206.945639ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:36.700013Z","caller":"traceutil/trace.go:171","msg":"trace[452838647] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"119.861836ms","start":"2026-04-03T02:25:36.580135Z","end":"2026-04-03T02:25:36.699997Z","steps":["trace[452838647] 'process raft request' (duration: 112.214076ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:36.700273Z","caller":"traceutil/trace.go:171","msg":"trace[439441368] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"114.370731ms","start":"2026-04-03T02:25:36.585895Z","end":"2026-04-03T02:25:36.700265Z","steps":["trace[439441368] 'process raft request' (duration: 114.061129ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:36.937582Z","caller":"traceutil/trace.go:171","msg":"trace[1719521681] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"183.436987ms","start":"2026-04-03T02:25:36.754106Z","end":"2026-04-03T02:25:36.937543Z","steps":["trace[1719521681] 'process raft request' (duration: 156.129198ms)","trace[1719521681] 'compare' (duration: 26.890762ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:36.937697Z","caller":"traceutil/trace.go:171","msg":"trace[1297446524] linearizableReadLoop","detail":"{readStateIndex:4737; appliedIndex:4736; }","duration":"183.543091ms","start":"2026-04-03T02:25:36.754121Z","end":"2026-04-03T02:25:36.937664Z","steps":["trace[1297446524] 'read index received' (duration: 156.029704ms)","trace[1297446524] 'applied index is now lower than readState.Index' (duration: 27.510747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:36.937988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.839393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-03T02:25:36.938048Z","caller":"traceutil/trace.go:171","msg":"trace[1818020429] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4566; }","duration":"183.953887ms","start":"2026-04-03T02:25:36.754078Z","end":"2026-04-03T02:25:36.938032Z","steps":["trace[1818020429] 'agreement among raft nodes before linearized reading' (duration: 183.736499ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:37.698287Z","caller":"traceutil/trace.go:171","msg":"trace[461109291] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"104.036904ms","start":"2026-04-03T02:25:37.594221Z","end":"2026-04-03T02:25:37.698258Z","steps":["trace[461109291] 'process raft request' (duration: 103.837456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:37.905231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.141349ms","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-04-03T02:25:37.905366Z","caller":"traceutil/trace.go:171","msg":"trace[509746163] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4576; }","duration":"117.330838ms","start":"2026-04-03T02:25:37.788016Z","end":"2026-04-03T02:25:37.905347Z","steps":["trace[509746163] 'range keys from in-memory index tree' (duration: 117.058686ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:41.544287Z","caller":"traceutil/trace.go:171","msg":"trace[1334657275] linearizableReadLoop","detail":"{readStateIndex:4767; appliedIndex:4766; }","duration":"133.537853ms","start":"2026-04-03T02:25:41.410727Z","end":"2026-04-03T02:25:41.544265Z","steps":["trace[1334657275] 'read index received' (duration: 70.568384ms)","trace[1334657275] 'applied index is now lower than readState.Index' (duration: 62.967769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:41.544555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.808956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:25:41.544653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.008572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-03T02:25:41.544659Z","caller":"traceutil/trace.go:171","msg":"trace[968032535] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4594; }","duration":"133.965372ms","start":"2026-04-03T02:25:41.410675Z","end":"2026-04-03T02:25:41.544641Z","steps":["trace[968032535] 'agreement among raft nodes before linearized reading' (duration: 133.779144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:41.544684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.581785ms","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-04-03T02:25:41.544705Z","caller":"traceutil/trace.go:171","msg":"trace[1347703270] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4594; }","duration":"123.115187ms","start":"2026-04-03T02:25:41.421575Z","end":"2026-04-03T02:25:41.54469Z","steps":["trace[1347703270] 'agreement among raft nodes before linearized reading' (duration: 122.949561ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:41.544727Z","caller":"traceutil/trace.go:171","msg":"trace[182855924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4594; }","duration":"116.644078ms","start":"2026-04-03T02:25:41.428073Z","end":"2026-04-03T02:25:41.544717Z","steps":["trace[182855924] 'agreement among raft nodes before linearized reading' (duration: 116.501572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:41.544711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.024283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-03T02:25:41.544712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.046113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:25:41.544771Z","caller":"traceutil/trace.go:171","msg":"trace[678391739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4594; }","duration":"116.122387ms","start":"2026-04-03T02:25:41.428634Z","end":"2026-04-03T02:25:41.544756Z","steps":["trace[678391739] 'agreement among raft nodes before linearized reading' (duration: 115.917119ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:41.544784Z","caller":"traceutil/trace.go:171","msg":"trace[1579483895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4594; }","duration":"117.214271ms","start":"2026-04-03T02:25:41.427557Z","end":"2026-04-03T02:25:41.544772Z","steps":["trace[1579483895] 'agreement among raft nodes before linearized reading' (duration: 117.029503ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:41.732672Z","caller":"traceutil/trace.go:171","msg":"trace[286266974] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"125.324836ms","start":"2026-04-03T02:25:41.607313Z","end":"2026-04-03T02:25:41.732638Z","steps":["trace[286266974] 'process raft request' (duration: 102.980961ms)","trace[286266974] 'compare' (duration: 22.23041ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:41.954189Z","caller":"traceutil/trace.go:171","msg":"trace[30007965] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"111.867167ms","start":"2026-04-03T02:25:41.842299Z","end":"2026-04-03T02:25:41.954166Z","steps":["trace[30007965] 'process raft request' (duration: 62.361646ms)","trace[30007965] 'compare' (duration: 49.364395ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:42.120937Z","caller":"traceutil/trace.go:171","msg":"trace[188801489] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"117.19808ms","start":"2026-04-03T02:25:42.003693Z","end":"2026-04-03T02:25:42.120891Z","steps":["trace[188801489] 'process raft request' (duration: 72.052415ms)","trace[188801489] 'compare' (duration: 44.910735ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:42.120951Z","caller":"traceutil/trace.go:171","msg":"trace[1542530965] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"116.651927ms","start":"2026-04-03T02:25:42.004273Z","end":"2026-04-03T02:25:42.120925Z","steps":["trace[1542530965] 'process raft request' (duration: 116.588214ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:42.121189Z","caller":"traceutil/trace.go:171","msg":"trace[497819016] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"117.283143ms","start":"2026-04-03T02:25:42.003889Z","end":"2026-04-03T02:25:42.121172Z","steps":["trace[497819016] 'process raft request' (duration: 116.927919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:43.196615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.031999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-03T02:25:43.196714Z","caller":"traceutil/trace.go:171","msg":"trace[1615381169] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842; range_end:; response_count:1; response_revision:4668; }","duration":"109.190595ms","start":"2026-04-03T02:25:43.087502Z","end":"2026-04-03T02:25:43.196693Z","steps":["trace[1615381169] 'range keys from in-memory index tree' (duration: 108.823021ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:43.196862Z","caller":"traceutil/trace.go:171","msg":"trace[817027487] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"103.86714ms","start":"2026-04-03T02:25:43.092959Z","end":"2026-04-03T02:25:43.196826Z","steps":["trace[817027487] 'process raft request' (duration: 36.719141ms)","trace[817027487] 'compare' (duration: 66.947921ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:43.782038Z","caller":"traceutil/trace.go:171","msg":"trace[949907991] linearizableReadLoop","detail":"{readStateIndex:4843; appliedIndex:4842; }","duration":"485.02972ms","start":"2026-04-03T02:25:43.296966Z","end":"2026-04-03T02:25:43.781996Z","steps":["trace[949907991] 'read index received' (duration: 484.758338ms)","trace[949907991] 'applied index is now lower than readState.Index' (duration: 269.611µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:43.782129Z","caller":"traceutil/trace.go:171","msg":"trace[272947076] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"576.858405ms","start":"2026-04-03T02:25:43.205241Z","end":"2026-04-03T02:25:43.7821Z","steps":["trace[272947076] 'process raft request' (duration: 576.488759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:43.782305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.296389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-03T02:25:43.782364Z","caller":"traceutil/trace.go:171","msg":"trace[58347289] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842; range_end:; response_count:1; response_revision:4670; }","duration":"485.451566ms","start":"2026-04-03T02:25:43.296896Z","end":"2026-04-03T02:25:43.782347Z","steps":["trace[58347289] 'agreement among raft nodes before linearized reading' (duration: 485.307921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:43.782433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.296872Z","time spent":"485.535278ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842\" "} {"level":"warn","ts":"2026-04-03T02:25:43.782279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.205219Z","time spent":"576.974128ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:25:44.283255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958588802,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:25:44.535766Z","caller":"traceutil/trace.go:171","msg":"trace[191700226] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"919.009873ms","start":"2026-04-03T02:25:43.616729Z","end":"2026-04-03T02:25:44.535739Z","steps":["trace[191700226] 'process raft request' (duration: 875.141566ms)","trace[191700226] 'compare' (duration: 43.447721ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:44.535797Z","caller":"traceutil/trace.go:171","msg":"trace[1741389956] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"109.603719ms","start":"2026-04-03T02:25:44.426178Z","end":"2026-04-03T02:25:44.535781Z","steps":["trace[1741389956] 'process raft request' (duration: 109.569478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.535885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.616702Z","time spent":"919.115776ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:25:44.535968Z","caller":"traceutil/trace.go:171","msg":"trace[1234565531] linearizableReadLoop","detail":"{readStateIndex:4845; appliedIndex:4843; }","duration":"753.802488ms","start":"2026-04-03T02:25:43.782155Z","end":"2026-04-03T02:25:44.535958Z","steps":["trace[1234565531] 'read index received' (duration: 709.719512ms)","trace[1234565531] 'applied index is now lower than readState.Index' (duration: 44.079366ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:44.535991Z","caller":"traceutil/trace.go:171","msg":"trace[2109053603] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"797.278432ms","start":"2026-04-03T02:25:43.738613Z","end":"2026-04-03T02:25:44.535891Z","steps":["trace[2109053603] 'process raft request' (duration: 797.061353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043655852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:25:44.536172Z","caller":"traceutil/trace.go:171","msg":"trace[2062986573] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4673; }","duration":"1.043692253s","start":"2026-04-03T02:25:43.492473Z","end":"2026-04-03T02:25:44.536165Z","steps":["trace[2062986573] 'agreement among raft nodes before linearized reading' (duration: 1.043610051s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.815527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:25:44.536203Z","caller":"traceutil/trace.go:171","msg":"trace[1661232816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4673; }","duration":"952.89465ms","start":"2026-04-03T02:25:43.583298Z","end":"2026-04-03T02:25:44.536193Z","steps":["trace[1661232816] 'agreement among raft nodes before linearized reading' (duration: 952.760324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.492461Z","time spent":"1.043766187s","remote":"127.0.0.1:34448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-03T02:25:44.536237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.583274Z","time spent":"952.956133ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T02:25:44.536313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.73858Z","time spent":"797.531322ms","remote":"127.0.0.1:33032","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-04-03T02:25:44.536393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.224673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-03T02:25:44.536399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223537139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:25:44.536413Z","caller":"traceutil/trace.go:171","msg":"trace[1232366105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4673; }","duration":"953.265056ms","start":"2026-04-03T02:25:43.583144Z","end":"2026-04-03T02:25:44.536409Z","steps":["trace[1232366105] 'agreement among raft nodes before linearized reading' (duration: 953.177722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.583134Z","time spent":"953.289766ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T02:25:44.536427Z","caller":"traceutil/trace.go:171","msg":"trace[473798895] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4673; }","duration":"1.223587052s","start":"2026-04-03T02:25:43.312832Z","end":"2026-04-03T02:25:44.536419Z","steps":["trace[473798895] 'agreement among raft nodes before linearized reading' (duration: 1.223530489s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.312819Z","time spent":"1.223633813s","remote":"127.0.0.1:33240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-03T02:25:44.536614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.37209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:25:44.536635Z","caller":"traceutil/trace.go:171","msg":"trace[979411324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4673; }","duration":"580.409203ms","start":"2026-04-03T02:25:43.956221Z","end":"2026-04-03T02:25:44.53663Z","steps":["trace[979411324] 'agreement among raft nodes before linearized reading' (duration: 580.376421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.956208Z","time spent":"580.437393ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:25:44.536696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.020134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-03T02:25:44.536735Z","caller":"traceutil/trace.go:171","msg":"trace[455956421] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4673; }","duration":"401.097228ms","start":"2026-04-03T02:25:44.135627Z","end":"2026-04-03T02:25:44.536724Z","steps":["trace[455956421] 'agreement among raft nodes before linearized reading' (duration: 401.003234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.536768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.135606Z","time spent":"401.15564ms","remote":"127.0.0.1:32930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-03T02:25:44.536803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.265894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-03T02:25:44.536922Z","caller":"traceutil/trace.go:171","msg":"trace[679409551] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4673; }","duration":"980.448642ms","start":"2026-04-03T02:25:43.556443Z","end":"2026-04-03T02:25:44.536892Z","steps":["trace[679409551] 'agreement among raft nodes before linearized reading' (duration: 980.076907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.53697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.812552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-03T02:25:44.536981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.556421Z","time spent":"980.551406ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:25:44.536869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.716491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:25:44.537022Z","caller":"traceutil/trace.go:171","msg":"trace[1040075600] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4673; }","duration":"617.916535ms","start":"2026-04-03T02:25:43.919093Z","end":"2026-04-03T02:25:44.53701Z","steps":["trace[1040075600] 'agreement among raft nodes before linearized reading' (duration: 617.593642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.537069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.919068Z","time spent":"617.992319ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":471,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-03T02:25:44.536925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.015934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7b7cd4d994-wfd6g\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-04-03T02:25:44.537263Z","caller":"traceutil/trace.go:171","msg":"trace[1824308899] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7b7cd4d994-wfd6g; range_end:; response_count:1; response_revision:4673; }","duration":"750.435822ms","start":"2026-04-03T02:25:43.786808Z","end":"2026-04-03T02:25:44.537244Z","steps":["trace[1824308899] 'agreement among raft nodes before linearized reading' (duration: 749.767385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.537336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.786787Z","time spent":"750.536335ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11279,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7b7cd4d994-wfd6g\" "} {"level":"info","ts":"2026-04-03T02:25:44.537141Z","caller":"traceutil/trace.go:171","msg":"trace[974004002] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4673; }","duration":"716.94603ms","start":"2026-04-03T02:25:43.820115Z","end":"2026-04-03T02:25:44.537061Z","steps":["trace[974004002] 'agreement among raft nodes before linearized reading' (duration: 716.715991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.537521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.8201Z","time spent":"717.372029ms","remote":"127.0.0.1:33256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":6,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-03T02:25:44.538137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.561818ms","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-04-03T02:25:44.538244Z","caller":"traceutil/trace.go:171","msg":"trace[1315869254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4673; }","duration":"955.693334ms","start":"2026-04-03T02:25:43.582536Z","end":"2026-04-03T02:25:44.538229Z","steps":["trace[1315869254] 'agreement among raft nodes before linearized reading' (duration: 953.998205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:44.538302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:43.582525Z","time spent":"955.766597ms","remote":"127.0.0.1:33032","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":"info","ts":"2026-04-03T02:25:44.736748Z","caller":"traceutil/trace.go:171","msg":"trace[582811851] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"173.514769ms","start":"2026-04-03T02:25:44.563204Z","end":"2026-04-03T02:25:44.736719Z","steps":["trace[582811851] 'process raft request' (duration: 154.525789ms)","trace[582811851] 'compare' (duration: 18.854064ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:44.737011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.278057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-04-03T02:25:44.737065Z","caller":"traceutil/trace.go:171","msg":"trace[136228135] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4679; }","duration":"111.411383ms","start":"2026-04-03T02:25:44.625642Z","end":"2026-04-03T02:25:44.737054Z","steps":["trace[136228135] 'agreement among raft nodes before linearized reading' (duration: 111.147902ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:44.736742Z","caller":"traceutil/trace.go:171","msg":"trace[1260319466] linearizableReadLoop","detail":"{readStateIndex:4852; appliedIndex:4851; }","duration":"110.989695ms","start":"2026-04-03T02:25:44.625706Z","end":"2026-04-03T02:25:44.736696Z","steps":["trace[1260319466] 'read index received' (duration: 92.060069ms)","trace[1260319466] 'applied index is now lower than readState.Index' (duration: 18.928746ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:44.742534Z","caller":"traceutil/trace.go:171","msg":"trace[1771409550] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"115.125824ms","start":"2026-04-03T02:25:44.627381Z","end":"2026-04-03T02:25:44.742507Z","steps":["trace[1771409550] 'process raft request' (duration: 114.928367ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:44.742678Z","caller":"traceutil/trace.go:171","msg":"trace[2009044118] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"115.637225ms","start":"2026-04-03T02:25:44.627026Z","end":"2026-04-03T02:25:44.742663Z","steps":["trace[2009044118] 'process raft request' (duration: 115.192137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.270212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.153056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:25:45.27033Z","caller":"traceutil/trace.go:171","msg":"trace[407522658] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"512.430199ms","start":"2026-04-03T02:25:44.757877Z","end":"2026-04-03T02:25:45.270307Z","steps":["trace[407522658] 'process raft request' (duration: 85.125028ms)","trace[407522658] 'compare' (duration: 426.924307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:45.270441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.757845Z","time spent":"512.552444ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:25:45.270622Z","caller":"traceutil/trace.go:171","msg":"trace[1995811142] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"443.939204ms","start":"2026-04-03T02:25:44.826653Z","end":"2026-04-03T02:25:45.270592Z","steps":["trace[1995811142] 'process raft request' (duration: 443.623602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.27078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.826639Z","time spent":"444.05634ms","remote":"127.0.0.1:33044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:25:45.270835Z","caller":"traceutil/trace.go:171","msg":"trace[575842240] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"439.522036ms","start":"2026-04-03T02:25:44.831306Z","end":"2026-04-03T02:25:45.270828Z","steps":["trace[575842240] 'process raft request' (duration: 439.060377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.270877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.831299Z","time spent":"439.559967ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:25:45.271085Z","caller":"traceutil/trace.go:171","msg":"trace[2095567685] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4855; }","duration":"483.401143ms","start":"2026-04-03T02:25:44.787651Z","end":"2026-04-03T02:25:45.271052Z","steps":["trace[2095567685] 'read index received' (duration: 29.921141ms)","trace[2095567685] 'applied index is now lower than readState.Index' (duration: 453.476962ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:45.27117Z","caller":"traceutil/trace.go:171","msg":"trace[1672287289] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"443.799829ms","start":"2026-04-03T02:25:44.827351Z","end":"2026-04-03T02:25:45.271151Z","steps":["trace[1672287289] 'process raft request' (duration: 442.965415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.271275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.620052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842\" ","response":"range_response_count:1 size:3765"} {"level":"warn","ts":"2026-04-03T02:25:45.271275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.827344Z","time spent":"443.890014ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:25:45.271318Z","caller":"traceutil/trace.go:171","msg":"trace[1440181827] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842; range_end:; response_count:1; response_revision:4687; }","duration":"483.701195ms","start":"2026-04-03T02:25:44.787606Z","end":"2026-04-03T02:25:45.271307Z","steps":["trace[1440181827] 'agreement among raft nodes before linearized reading' (duration: 483.57985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.271352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.787591Z","time spent":"483.754048ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-377173d0-dbc5-4e11-bd5d-61c6b7bfe842\" "} {"level":"warn","ts":"2026-04-03T02:25:45.271384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.307792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T02:25:45.271429Z","caller":"traceutil/trace.go:171","msg":"trace[1852967690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4687; }","duration":"468.402516ms","start":"2026-04-03T02:25:44.803021Z","end":"2026-04-03T02:25:45.271424Z","steps":["trace[1852967690] 'agreement among raft nodes before linearized reading' (duration: 468.276801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.271471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.802966Z","time spent":"468.49936ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T02:25:45.27156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.732886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:25:45.271138Z","caller":"traceutil/trace.go:171","msg":"trace[1247366303] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"439.989716ms","start":"2026-04-03T02:25:44.831121Z","end":"2026-04-03T02:25:45.27111Z","steps":["trace[1247366303] 'process raft request' (duration: 439.225545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.271688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.831111Z","time spent":"440.557248ms","remote":"127.0.0.1:33044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:25:45.271687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.311995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-03T02:25:45.271696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.465661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T02:25:45.271721Z","caller":"traceutil/trace.go:171","msg":"trace[1331360283] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4687; }","duration":"122.345016ms","start":"2026-04-03T02:25:45.149368Z","end":"2026-04-03T02:25:45.271713Z","steps":["trace[1331360283] 'agreement among raft nodes before linearized reading' (duration: 122.254622ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:45.271773Z","caller":"traceutil/trace.go:171","msg":"trace[638483677] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4687; }","duration":"453.568925ms","start":"2026-04-03T02:25:44.818194Z","end":"2026-04-03T02:25:45.271763Z","steps":["trace[638483677] 'agreement among raft nodes before linearized reading' (duration: 453.4527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.27181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.818178Z","time spent":"453.622357ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-03T02:25:45.271604Z","caller":"traceutil/trace.go:171","msg":"trace[898266862] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4687; }","duration":"129.813959ms","start":"2026-04-03T02:25:45.141778Z","end":"2026-04-03T02:25:45.271592Z","steps":["trace[898266862] 'agreement among raft nodes before linearized reading' (duration: 129.749947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.271628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.420449ms","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-04-03T02:25:45.271928Z","caller":"traceutil/trace.go:171","msg":"trace[1447632665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4687; }","duration":"453.767953ms","start":"2026-04-03T02:25:44.818154Z","end":"2026-04-03T02:25:45.271922Z","steps":["trace[1447632665] 'agreement among raft nodes before linearized reading' (duration: 453.412719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:45.272009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:44.818131Z","time spent":"453.869147ms","remote":"127.0.0.1:33032","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-04-03T02:25:46.331233Z","caller":"traceutil/trace.go:171","msg":"trace[1376182245] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"114.026805ms","start":"2026-04-03T02:25:46.217178Z","end":"2026-04-03T02:25:46.331204Z","steps":["trace[1376182245] 'process raft request' (duration: 113.672641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:53.752958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.479515ms","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-04-03T02:25:53.752981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.02461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T02:25:53.753043Z","caller":"traceutil/trace.go:171","msg":"trace[371848987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4767; }","duration":"350.6277ms","start":"2026-04-03T02:25:53.402392Z","end":"2026-04-03T02:25:53.75302Z","steps":["trace[371848987] 'range keys from in-memory index tree' (duration: 350.268776ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:53.753042Z","caller":"traceutil/trace.go:171","msg":"trace[897144744] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4767; }","duration":"352.170926ms","start":"2026-04-03T02:25:53.400854Z","end":"2026-04-03T02:25:53.753025Z","steps":["trace[897144744] 'range keys from in-memory index tree' (duration: 351.785459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:53.753083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:53.402364Z","time spent":"350.709665ms","remote":"127.0.0.1:33032","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":"warn","ts":"2026-04-03T02:25:53.753083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:53.400797Z","time spent":"352.277391ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:25:53.753118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.299258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T02:25:53.753152Z","caller":"traceutil/trace.go:171","msg":"trace[460402294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4767; }","duration":"392.38872ms","start":"2026-04-03T02:25:53.360754Z","end":"2026-04-03T02:25:53.753143Z","steps":["trace[460402294] 'range keys from in-memory index tree' (duration: 392.13443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:53.753176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:53.360724Z","time spent":"392.445383ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T02:25:59.059305Z","caller":"traceutil/trace.go:171","msg":"trace[977153886] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5031; }","duration":"434.736383ms","start":"2026-04-03T02:25:58.62454Z","end":"2026-04-03T02:25:59.059276Z","steps":["trace[977153886] 'read index received' (duration: 434.554265ms)","trace[977153886] 'applied index is now lower than readState.Index' (duration: 180.518µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:59.059667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.953083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-03T02:25:59.059721Z","caller":"traceutil/trace.go:171","msg":"trace[1610973935] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4852; }","duration":"333.045668ms","start":"2026-04-03T02:25:58.726662Z","end":"2026-04-03T02:25:59.059708Z","steps":["trace[1610973935] 'agreement among raft nodes before linearized reading' (duration: 332.837259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:59.05965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.781456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-03T02:25:59.059772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:58.726654Z","time spent":"333.098479ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:25:59.059759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.205032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:25:59.059807Z","caller":"traceutil/trace.go:171","msg":"trace[870008075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4852; }","duration":"336.988606ms","start":"2026-04-03T02:25:58.722801Z","end":"2026-04-03T02:25:59.059789Z","steps":["trace[870008075] 'agreement among raft nodes before linearized reading' (duration: 336.690943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:59.059851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:58.72278Z","time spent":"337.061888ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T02:25:59.059849Z","caller":"traceutil/trace.go:171","msg":"trace[1203540791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4852; }","duration":"435.331727ms","start":"2026-04-03T02:25:58.624494Z","end":"2026-04-03T02:25:59.059825Z","steps":["trace[1203540791] 'agreement among raft nodes before linearized reading' (duration: 434.888949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:59.059823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.161219ms","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-04-03T02:25:59.059906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:58.624474Z","time spent":"435.418853ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T02:25:59.059935Z","caller":"traceutil/trace.go:171","msg":"trace[994163814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4852; }","duration":"336.335977ms","start":"2026-04-03T02:25:58.723574Z","end":"2026-04-03T02:25:59.05991Z","steps":["trace[994163814] 'agreement among raft nodes before linearized reading' (duration: 335.997663ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:59.059975Z","caller":"traceutil/trace.go:171","msg":"trace[693380703] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"470.318009ms","start":"2026-04-03T02:25:58.589624Z","end":"2026-04-03T02:25:59.059943Z","steps":["trace[693380703] 'process raft request' (duration: 469.449852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:59.059994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:58.723554Z","time spent":"336.430122ms","remote":"127.0.0.1:33032","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-04-03T02:25:59.060131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:25:58.589595Z","time spent":"470.433794ms","remote":"127.0.0.1:33032","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-04-03T02:26:03.620264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958589291,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:26:03.756104Z","caller":"traceutil/trace.go:171","msg":"trace[559030765] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"680.945711ms","start":"2026-04-03T02:26:03.075128Z","end":"2026-04-03T02:26:03.756074Z","steps":["trace[559030765] 'process raft request' (duration: 680.734782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.756428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.075105Z","time spent":"681.17295ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:26:03.884609Z","caller":"traceutil/trace.go:171","msg":"trace[959300951] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5056; }","duration":"765.413291ms","start":"2026-04-03T02:26:03.119156Z","end":"2026-04-03T02:26:03.884569Z","steps":["trace[959300951] 'read index received' (duration: 636.671892ms)","trace[959300951] 'applied index is now lower than readState.Index' (duration: 128.740219ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:03.884795Z","caller":"traceutil/trace.go:171","msg":"trace[798903627] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"543.305635ms","start":"2026-04-03T02:26:03.341475Z","end":"2026-04-03T02:26:03.884781Z","steps":["trace[798903627] 'process raft request' (duration: 503.695978ms)","trace[798903627] 'compare' (duration: 39.232791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:03.884955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.341457Z","time spent":"543.38091ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:26:03.885041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.983038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-03T02:26:03.885127Z","caller":"traceutil/trace.go:171","msg":"trace[1056233813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4877; }","duration":"422.125963ms","start":"2026-04-03T02:26:03.462981Z","end":"2026-04-03T02:26:03.885107Z","steps":["trace[1056233813] 'agreement among raft nodes before linearized reading' (duration: 421.917024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.885176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.462956Z","time spent":"422.211538ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-03T02:26:03.885253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.151609ms","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":"warn","ts":"2026-04-03T02:26:03.885319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.92875ms","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-04-03T02:26:03.885367Z","caller":"traceutil/trace.go:171","msg":"trace[632812986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4877; }","duration":"765.033755ms","start":"2026-04-03T02:26:03.120321Z","end":"2026-04-03T02:26:03.885355Z","steps":["trace[632812986] 'agreement among raft nodes before linearized reading' (duration: 764.942011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.885412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.120287Z","time spent":"765.117838ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T02:26:03.885415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.057906ms","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-04-03T02:26:03.885447Z","caller":"traceutil/trace.go:171","msg":"trace[555430221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4877; }","duration":"765.104737ms","start":"2026-04-03T02:26:03.120334Z","end":"2026-04-03T02:26:03.885439Z","steps":["trace[555430221] 'agreement among raft nodes before linearized reading' (duration: 765.018764ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:03.885326Z","caller":"traceutil/trace.go:171","msg":"trace[1613765674] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4877; }","duration":"573.248723ms","start":"2026-04-03T02:26:03.312047Z","end":"2026-04-03T02:26:03.885295Z","steps":["trace[1613765674] 'agreement among raft nodes before linearized reading' (duration: 573.109158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.885494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.120327Z","time spent":"765.159531ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T02:26:03.885541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.886745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-04-03T02:26:03.885545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.312026Z","time spent":"573.504574ms","remote":"127.0.0.1:32930","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-04-03T02:26:03.885568Z","caller":"traceutil/trace.go:171","msg":"trace[2023814267] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4877; }","duration":"613.956957ms","start":"2026-04-03T02:26:03.271602Z","end":"2026-04-03T02:26:03.885559Z","steps":["trace[2023814267] 'agreement among raft nodes before linearized reading' (duration: 613.876054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.885603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.271575Z","time spent":"614.022111ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-03T02:26:03.885616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.265689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:26:03.88568Z","caller":"traceutil/trace.go:171","msg":"trace[1655457432] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4877; }","duration":"233.367314ms","start":"2026-04-03T02:26:03.652284Z","end":"2026-04-03T02:26:03.885651Z","steps":["trace[1655457432] 'agreement among raft nodes before linearized reading' (duration: 233.294561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.885049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.899612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"warn","ts":"2026-04-03T02:26:03.885108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.753283ms","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-04-03T02:26:03.885831Z","caller":"traceutil/trace.go:171","msg":"trace[1826872198] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4877; }","duration":"766.734019ms","start":"2026-04-03T02:26:03.11908Z","end":"2026-04-03T02:26:03.885814Z","steps":["trace[1826872198] 'agreement among raft nodes before linearized reading' (duration: 765.784608ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:03.885846Z","caller":"traceutil/trace.go:171","msg":"trace[1457346347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4877; }","duration":"765.543727ms","start":"2026-04-03T02:26:03.120288Z","end":"2026-04-03T02:26:03.885831Z","steps":["trace[1457346347] 'agreement among raft nodes before linearized reading' (duration: 764.587305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:03.885884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.119049Z","time spent":"766.822182ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:26:03.885907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:03.120274Z","time spent":"765.602988ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T02:26:04.132168Z","caller":"traceutil/trace.go:171","msg":"trace[1115332238] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"240.051451ms","start":"2026-04-03T02:26:03.892085Z","end":"2026-04-03T02:26:04.132137Z","steps":["trace[1115332238] 'process raft request' (duration: 218.805407ms)","trace[1115332238] 'compare' (duration: 21.116278ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:04.171882Z","caller":"traceutil/trace.go:171","msg":"trace[768423670] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"279.690255ms","start":"2026-04-03T02:26:03.892164Z","end":"2026-04-03T02:26:04.171855Z","steps":["trace[768423670] 'process raft request' (duration: 279.496686ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.172352Z","caller":"traceutil/trace.go:171","msg":"trace[777709335] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"280.0459ms","start":"2026-04-03T02:26:03.892279Z","end":"2026-04-03T02:26:04.172325Z","steps":["trace[777709335] 'process raft request' (duration: 279.710855ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.17245Z","caller":"traceutil/trace.go:171","msg":"trace[1775296829] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5058; }","duration":"280.07373ms","start":"2026-04-03T02:26:03.892346Z","end":"2026-04-03T02:26:04.172419Z","steps":["trace[1775296829] 'read index received' (duration: 218.559516ms)","trace[1775296829] 'applied index is now lower than readState.Index' (duration: 61.512674ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:04.172495Z","caller":"traceutil/trace.go:171","msg":"trace[1066930237] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"279.967377ms","start":"2026-04-03T02:26:03.892179Z","end":"2026-04-03T02:26:04.172146Z","steps":["trace[1066930237] 'process raft request' (duration: 279.60852ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.172719Z","caller":"traceutil/trace.go:171","msg":"trace[961952012] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"279.693015ms","start":"2026-04-03T02:26:03.893012Z","end":"2026-04-03T02:26:04.172705Z","steps":["trace[961952012] 'process raft request' (duration: 279.126239ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.172764Z","caller":"traceutil/trace.go:171","msg":"trace[1294735149] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"250.75284ms","start":"2026-04-03T02:26:03.921882Z","end":"2026-04-03T02:26:04.172635Z","steps":["trace[1294735149] 'process raft request' (duration: 250.472679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:04.17278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.403736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-03T02:26:04.172808Z","caller":"traceutil/trace.go:171","msg":"trace[2102045678] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"278.759245ms","start":"2026-04-03T02:26:03.89404Z","end":"2026-04-03T02:26:04.172799Z","steps":["trace[2102045678] 'process raft request' (duration: 278.145988ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.172825Z","caller":"traceutil/trace.go:171","msg":"trace[1573189314] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4886; }","duration":"280.497889ms","start":"2026-04-03T02:26:03.892314Z","end":"2026-04-03T02:26:04.172812Z","steps":["trace[1573189314] 'agreement among raft nodes before linearized reading' (duration: 280.314111ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.172875Z","caller":"traceutil/trace.go:171","msg":"trace[141537892] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"278.758754ms","start":"2026-04-03T02:26:03.894104Z","end":"2026-04-03T02:26:04.172863Z","steps":["trace[141537892] 'process raft request' (duration: 278.130967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:04.172992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.458904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:26:04.173027Z","caller":"traceutil/trace.go:171","msg":"trace[788731845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4886; }","duration":"216.537698ms","start":"2026-04-03T02:26:03.956481Z","end":"2026-04-03T02:26:04.173018Z","steps":["trace[788731845] 'agreement among raft nodes before linearized reading' (duration: 216.485206ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.173327Z","caller":"traceutil/trace.go:171","msg":"trace[274202256] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"272.801196ms","start":"2026-04-03T02:26:03.900498Z","end":"2026-04-03T02:26:04.173299Z","steps":["trace[274202256] 'process raft request' (duration: 271.781402ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.337672Z","caller":"traceutil/trace.go:171","msg":"trace[1084858237] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"156.826718ms","start":"2026-04-03T02:26:04.180814Z","end":"2026-04-03T02:26:04.33764Z","steps":["trace[1084858237] 'process raft request' (duration: 84.356847ms)","trace[1084858237] 'compare' (duration: 72.325605ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:04.379511Z","caller":"traceutil/trace.go:171","msg":"trace[1175163762] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"198.389425ms","start":"2026-04-03T02:26:04.181087Z","end":"2026-04-03T02:26:04.379477Z","steps":["trace[1175163762] 'process raft request' (duration: 198.115553ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.37984Z","caller":"traceutil/trace.go:171","msg":"trace[1648254294] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"198.52493ms","start":"2026-04-03T02:26:04.181238Z","end":"2026-04-03T02:26:04.379762Z","steps":["trace[1648254294] 'process raft request' (duration: 198.183825ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.379904Z","caller":"traceutil/trace.go:171","msg":"trace[348286255] linearizableReadLoop","detail":"{readStateIndex:5071; appliedIndex:5067; }","duration":"137.680596ms","start":"2026-04-03T02:26:04.242211Z","end":"2026-04-03T02:26:04.379891Z","steps":["trace[348286255] 'read index received' (duration: 22.977299ms)","trace[348286255] 'applied index is now lower than readState.Index' (duration: 114.702157ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:04.380042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.816681ms","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-04-03T02:26:04.38009Z","caller":"traceutil/trace.go:171","msg":"trace[875669845] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4890; }","duration":"137.920325ms","start":"2026-04-03T02:26:04.242158Z","end":"2026-04-03T02:26:04.380078Z","steps":["trace[875669845] 'agreement among raft nodes before linearized reading' (duration: 137.821181ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:04.380082Z","caller":"traceutil/trace.go:171","msg":"trace[1753569242] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"197.918625ms","start":"2026-04-03T02:26:04.182133Z","end":"2026-04-03T02:26:04.380052Z","steps":["trace[1753569242] 'process raft request' (duration: 197.58983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.458159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958589331,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:26:06.580888Z","caller":"traceutil/trace.go:171","msg":"trace[920736189] linearizableReadLoop","detail":"{readStateIndex:5078; appliedIndex:5077; }","duration":"623.609195ms","start":"2026-04-03T02:26:05.957256Z","end":"2026-04-03T02:26:06.580865Z","steps":["trace[920736189] 'read index received' (duration: 621.008471ms)","trace[920736189] 'applied index is now lower than readState.Index' (duration: 2.599724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:06.581312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.050034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:26:06.581302Z","caller":"traceutil/trace.go:171","msg":"trace[148622522] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"689.426187ms","start":"2026-04-03T02:26:05.891836Z","end":"2026-04-03T02:26:06.581262Z","steps":["trace[148622522] 'process raft request' (duration: 686.880995ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:06.58139Z","caller":"traceutil/trace.go:171","msg":"trace[2006882388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4897; }","duration":"624.198611ms","start":"2026-04-03T02:26:05.957174Z","end":"2026-04-03T02:26:06.581372Z","steps":["trace[2006882388] 'agreement among raft nodes before linearized reading' (duration: 623.995842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.581411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.732713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-03T02:26:06.581488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:05.891793Z","time spent":"689.604863ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:26:06.58158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.744956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-04-03T02:26:06.581593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.43746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-03T02:26:06.58162Z","caller":"traceutil/trace.go:171","msg":"trace[1450877932] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4897; }","duration":"273.825658ms","start":"2026-04-03T02:26:06.307782Z","end":"2026-04-03T02:26:06.581608Z","steps":["trace[1450877932] 'agreement among raft nodes before linearized reading' (duration: 273.687593ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:06.581629Z","caller":"traceutil/trace.go:171","msg":"trace[884322729] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4897; }","duration":"403.522353ms","start":"2026-04-03T02:26:06.178093Z","end":"2026-04-03T02:26:06.581616Z","steps":["trace[884322729] 'agreement among raft nodes before linearized reading' (duration: 403.429269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.581671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.178062Z","time spent":"403.600977ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-03T02:26:06.581495Z","caller":"traceutil/trace.go:171","msg":"trace[199620374] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:4897; }","duration":"454.88336ms","start":"2026-04-03T02:26:06.126593Z","end":"2026-04-03T02:26:06.581477Z","steps":["trace[199620374] 'agreement among raft nodes before linearized reading' (duration: 454.65123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.581642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.993567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-03T02:26:06.581744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.126557Z","time spent":"455.171923ms","remote":"127.0.0.1:32808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-04-03T02:26:06.58177Z","caller":"traceutil/trace.go:171","msg":"trace[1287969986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4897; }","duration":"402.168174ms","start":"2026-04-03T02:26:06.179573Z","end":"2026-04-03T02:26:06.581741Z","steps":["trace[1287969986] 'agreement among raft nodes before linearized reading' (duration: 401.764447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.581831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.093792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:26:06.581868Z","caller":"traceutil/trace.go:171","msg":"trace[785466745] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:4897; }","duration":"157.191107ms","start":"2026-04-03T02:26:06.424664Z","end":"2026-04-03T02:26:06.581855Z","steps":["trace[785466745] 'agreement among raft nodes before linearized reading' (duration: 157.137844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.581856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.179567Z","time spent":"402.274119ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T02:26:06.58191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.077251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-03T02:26:06.582002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.421725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T02:26:06.582024Z","caller":"traceutil/trace.go:171","msg":"trace[1362503779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4897; }","duration":"445.317891ms","start":"2026-04-03T02:26:06.136691Z","end":"2026-04-03T02:26:06.582009Z","steps":["trace[1362503779] 'agreement among raft nodes before linearized reading' (duration: 444.904853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.582119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.997007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-03T02:26:06.582138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.136648Z","time spent":"445.439957ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-03T02:26:06.582173Z","caller":"traceutil/trace.go:171","msg":"trace[1802540009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4897; }","duration":"402.065519ms","start":"2026-04-03T02:26:06.180093Z","end":"2026-04-03T02:26:06.582159Z","steps":["trace[1802540009] 'agreement among raft nodes before linearized reading' (duration: 401.935064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.582218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.180086Z","time spent":"402.122822ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T02:26:06.582309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.574391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-03T02:26:06.581437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:05.95714Z","time spent":"624.288685ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T02:26:06.582358Z","caller":"traceutil/trace.go:171","msg":"trace[328892031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4897; }","duration":"402.635374ms","start":"2026-04-03T02:26:06.17971Z","end":"2026-04-03T02:26:06.582346Z","steps":["trace[328892031] 'agreement among raft nodes before linearized reading' (duration: 402.473047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.582396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.179701Z","time spent":"402.687537ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T02:26:06.582444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.203819ms","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-04-03T02:26:06.582572Z","caller":"traceutil/trace.go:171","msg":"trace[1999279173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4897; }","duration":"403.471701ms","start":"2026-04-03T02:26:06.179077Z","end":"2026-04-03T02:26:06.582549Z","steps":["trace[1999279173] 'agreement among raft nodes before linearized reading' (duration: 402.279909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.582651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.179064Z","time spent":"403.575776ms","remote":"127.0.0.1:33032","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-04-03T02:26:06.582218Z","caller":"traceutil/trace.go:171","msg":"trace[230609687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4897; }","duration":"402.600962ms","start":"2026-04-03T02:26:06.179441Z","end":"2026-04-03T02:26:06.582042Z","steps":["trace[230609687] 'agreement among raft nodes before linearized reading' (duration: 401.928523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:06.584622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:06.179429Z","time spent":"405.175065ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T02:26:31.669292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.040029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:26:31.669573Z","caller":"traceutil/trace.go:171","msg":"trace[1130751727] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"179.940363ms","start":"2026-04-03T02:26:31.489576Z","end":"2026-04-03T02:26:31.669516Z","steps":["trace[1130751727] 'process raft request' (duration: 46.554569ms)","trace[1130751727] 'compare' (duration: 132.714013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:41.595717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.944444ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-03T02:26:56.434884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.434734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:26:56.435247Z","caller":"traceutil/trace.go:171","msg":"trace[1160482524] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"240.40235ms","start":"2026-04-03T02:26:56.194781Z","end":"2026-04-03T02:26:56.435183Z","steps":["trace[1160482524] 'process raft request' (duration: 122.459461ms)","trace[1160482524] 'compare' (duration: 117.271745ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:56.435538Z","caller":"traceutil/trace.go:171","msg":"trace[1167073495] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"218.033316ms","start":"2026-04-03T02:26:56.217386Z","end":"2026-04-03T02:26:56.435419Z","steps":["trace[1167073495] 'process raft request' (duration: 217.69309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:27:05.093055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.285739ms","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-04-03T02:27:05.093166Z","caller":"traceutil/trace.go:171","msg":"trace[150648115] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5384; }","duration":"183.436156ms","start":"2026-04-03T02:27:04.909713Z","end":"2026-04-03T02:27:05.093149Z","steps":["trace[150648115] 'range keys from in-memory index tree' (duration: 183.189274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:27:05.093282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.17002ms","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-04-03T02:27:05.093411Z","caller":"traceutil/trace.go:171","msg":"trace[432374972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5384; }","duration":"103.340919ms","start":"2026-04-03T02:27:04.990057Z","end":"2026-04-03T02:27:05.093398Z","steps":["trace[432374972] 'range keys from in-memory index tree' (duration: 102.36736ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:27:05.258645Z","caller":"traceutil/trace.go:171","msg":"trace[1078708572] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"101.310978ms","start":"2026-04-03T02:27:05.157313Z","end":"2026-04-03T02:27:05.258624Z","steps":["trace[1078708572] 'process raft request' (duration: 91.023092ms)","trace[1078708572] 'compare' (duration: 10.023942ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:27:05.442062Z","caller":"traceutil/trace.go:171","msg":"trace[1080879015] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"109.052727ms","start":"2026-04-03T02:27:05.332978Z","end":"2026-04-03T02:27:05.44203Z","steps":["trace[1080879015] 'process raft request' (duration: 39.502471ms)","trace[1080879015] 'compare' (duration: 69.260501ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:27:05.950583Z","caller":"traceutil/trace.go:171","msg":"trace[1333597561] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"195.866037ms","start":"2026-04-03T02:27:05.75432Z","end":"2026-04-03T02:27:05.950186Z","steps":["trace[1333597561] 'process raft request' (duration: 195.629005ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:27:33.094635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"info","ts":"2026-04-03T02:27:33.176189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"79.579638ms","hash":1698723521,"current-db-size-bytes":53645312,"current-db-size":"54 MB","current-db-size-in-use-bytes":29974528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-03T02:27:33.176272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1698723521,"revision":2888,"compact-revision":-1} {"level":"info","ts":"2026-04-03T02:28:39.492696Z","caller":"traceutil/trace.go:171","msg":"trace[992511293] transaction","detail":"{read_only:false; response_revision:5951; number_of_response:1; }","duration":"106.590764ms","start":"2026-04-03T02:28:39.386078Z","end":"2026-04-03T02:28:39.492668Z","steps":["trace[992511293] 'process raft request' (duration: 66.514599ms)","trace[992511293] 'compare' (duration: 39.97254ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:29:22.145535Z","caller":"traceutil/trace.go:171","msg":"trace[697024602] transaction","detail":"{read_only:false; response_revision:6322; number_of_response:1; }","duration":"109.536941ms","start":"2026-04-03T02:29:22.035759Z","end":"2026-04-03T02:29:22.145296Z","steps":["trace[697024602] 'process raft request' (duration: 109.314069ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:22.146485Z","caller":"traceutil/trace.go:171","msg":"trace[1762381065] transaction","detail":"{read_only:false; response_revision:6323; number_of_response:1; }","duration":"106.222062ms","start":"2026-04-03T02:29:22.040234Z","end":"2026-04-03T02:29:22.146456Z","steps":["trace[1762381065] 'process raft request' (duration: 105.893853ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:23.006657Z","caller":"traceutil/trace.go:171","msg":"trace[336716524] transaction","detail":"{read_only:false; response_revision:6330; number_of_response:1; }","duration":"151.43554ms","start":"2026-04-03T02:29:22.855186Z","end":"2026-04-03T02:29:23.006622Z","steps":["trace[336716524] 'process raft request' (duration: 104.934578ms)","trace[336716524] 'compare' (duration: 46.28328ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:29:23.083471Z","caller":"traceutil/trace.go:171","msg":"trace[463805737] transaction","detail":"{read_only:false; response_revision:6331; number_of_response:1; }","duration":"226.84672ms","start":"2026-04-03T02:29:22.856589Z","end":"2026-04-03T02:29:23.083436Z","steps":["trace[463805737] 'process raft request' (duration: 226.641008ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:23.083647Z","caller":"traceutil/trace.go:171","msg":"trace[1459628488] linearizableReadLoop","detail":"{readStateIndex:6561; appliedIndex:6558; }","duration":"127.039107ms","start":"2026-04-03T02:29:22.956569Z","end":"2026-04-03T02:29:23.083608Z","steps":["trace[1459628488] 'read index received' (duration: 3.566334ms)","trace[1459628488] 'applied index is now lower than readState.Index' (duration: 123.469432ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:29:23.083802Z","caller":"traceutil/trace.go:171","msg":"trace[272966873] transaction","detail":"{read_only:false; response_revision:6332; number_of_response:1; }","duration":"222.98568ms","start":"2026-04-03T02:29:22.860707Z","end":"2026-04-03T02:29:23.083693Z","steps":["trace[272966873] 'process raft request' (duration: 222.664321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:23.083863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.261809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:29:23.084104Z","caller":"traceutil/trace.go:171","msg":"trace[1543820445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6332; }","duration":"127.525344ms","start":"2026-04-03T02:29:22.956549Z","end":"2026-04-03T02:29:23.084074Z","steps":["trace[1543820445] 'agreement among raft nodes before linearized reading' (duration: 127.208736ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:23.275962Z","caller":"traceutil/trace.go:171","msg":"trace[1760222142] transaction","detail":"{read_only:false; response_revision:6333; number_of_response:1; }","duration":"175.209407ms","start":"2026-04-03T02:29:23.100718Z","end":"2026-04-03T02:29:23.275927Z","steps":["trace[1760222142] 'process raft request' (duration: 174.899859ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:23.397802Z","caller":"traceutil/trace.go:171","msg":"trace[820180036] transaction","detail":"{read_only:false; response_revision:6335; number_of_response:1; }","duration":"114.051813ms","start":"2026-04-03T02:29:23.283707Z","end":"2026-04-03T02:29:23.397759Z","steps":["trace[820180036] 'process raft request' (duration: 112.910958ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:23.397867Z","caller":"traceutil/trace.go:171","msg":"trace[1274867953] transaction","detail":"{read_only:false; response_revision:6336; number_of_response:1; }","duration":"106.987448ms","start":"2026-04-03T02:29:23.290848Z","end":"2026-04-03T02:29:23.397835Z","steps":["trace[1274867953] 'process raft request' (duration: 106.696902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:33.153083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.34431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:29:33.1532Z","caller":"traceutil/trace.go:171","msg":"trace[1629726671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6415; }","duration":"196.482158ms","start":"2026-04-03T02:29:32.956677Z","end":"2026-04-03T02:29:33.153159Z","steps":["trace[1629726671] 'range keys from in-memory index tree' (duration: 196.275317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:40.165245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.601378ms","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-04-03T02:29:40.165341Z","caller":"traceutil/trace.go:171","msg":"trace[1823096798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6452; }","duration":"114.925086ms","start":"2026-04-03T02:29:40.050397Z","end":"2026-04-03T02:29:40.165322Z","steps":["trace[1823096798] 'range keys from in-memory index tree' (duration: 114.190654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:40.165514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.312248ms","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-04-03T02:29:40.165585Z","caller":"traceutil/trace.go:171","msg":"trace[434107545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6452; }","duration":"111.409243ms","start":"2026-04-03T02:29:40.05416Z","end":"2026-04-03T02:29:40.16557Z","steps":["trace[434107545] 'range keys from in-memory index tree' (duration: 110.811588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:40.165755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.895177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:29:40.165816Z","caller":"traceutil/trace.go:171","msg":"trace[773853275] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:6452; }","duration":"167.000382ms","start":"2026-04-03T02:29:39.998802Z","end":"2026-04-03T02:29:40.165802Z","steps":["trace[773853275] 'count revisions from in-memory index tree' (duration: 166.754368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:40.166109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.475726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"info","ts":"2026-04-03T02:29:40.166157Z","caller":"traceutil/trace.go:171","msg":"trace[1088788303] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6452; }","duration":"160.549491ms","start":"2026-04-03T02:29:40.005594Z","end":"2026-04-03T02:29:40.166143Z","steps":["trace[1088788303] 'range keys from in-memory index tree' (duration: 160.340019ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:40.374788Z","caller":"traceutil/trace.go:171","msg":"trace[1487727729] linearizableReadLoop","detail":"{readStateIndex:6689; appliedIndex:6685; }","duration":"147.461154ms","start":"2026-04-03T02:29:40.227299Z","end":"2026-04-03T02:29:40.374761Z","steps":["trace[1487727729] 'read index received' (duration: 16.848702ms)","trace[1487727729] 'applied index is now lower than readState.Index' (duration: 130.611142ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:29:40.374977Z","caller":"traceutil/trace.go:171","msg":"trace[18159913] transaction","detail":"{read_only:false; response_revision:6456; number_of_response:1; }","duration":"202.667783ms","start":"2026-04-03T02:29:40.172295Z","end":"2026-04-03T02:29:40.374963Z","steps":["trace[18159913] 'process raft request' (duration: 202.419239ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:40.37502Z","caller":"traceutil/trace.go:171","msg":"trace[2017066139] transaction","detail":"{read_only:false; response_revision:6455; number_of_response:1; }","duration":"202.985321ms","start":"2026-04-03T02:29:40.171869Z","end":"2026-04-03T02:29:40.374854Z","steps":["trace[2017066139] 'process raft request' (duration: 202.7956ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:40.375148Z","caller":"traceutil/trace.go:171","msg":"trace[684067676] transaction","detail":"{read_only:false; response_revision:6454; number_of_response:1; }","duration":"203.339541ms","start":"2026-04-03T02:29:40.171799Z","end":"2026-04-03T02:29:40.375138Z","steps":["trace[684067676] 'process raft request' (duration: 202.683254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:40.375317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.014557ms","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-04-03T02:29:40.376117Z","caller":"traceutil/trace.go:171","msg":"trace[1407599924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6456; }","duration":"148.866046ms","start":"2026-04-03T02:29:40.227227Z","end":"2026-04-03T02:29:40.376094Z","steps":["trace[1407599924] 'agreement among raft nodes before linearized reading' (duration: 147.955444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:40.375449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.404431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-03T02:29:40.376274Z","caller":"traceutil/trace.go:171","msg":"trace[177006767] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6456; }","duration":"144.283172ms","start":"2026-04-03T02:29:40.231977Z","end":"2026-04-03T02:29:40.37626Z","steps":["trace[177006767] 'agreement among raft nodes before linearized reading' (duration: 143.153316ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:41.185139Z","caller":"traceutil/trace.go:171","msg":"trace[2099620716] transaction","detail":"{read_only:false; response_revision:6460; number_of_response:1; }","duration":"227.854941ms","start":"2026-04-03T02:29:40.957233Z","end":"2026-04-03T02:29:41.185088Z","steps":["trace[2099620716] 'process raft request' (duration: 227.505722ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:41.185238Z","caller":"traceutil/trace.go:171","msg":"trace[821841951] linearizableReadLoop","detail":"{readStateIndex:6693; appliedIndex:6692; }","duration":"227.66152ms","start":"2026-04-03T02:29:40.957546Z","end":"2026-04-03T02:29:41.185208Z","steps":["trace[821841951] 'read index received' (duration: 227.124079ms)","trace[821841951] 'applied index is now lower than readState.Index' (duration: 533.031µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:29:41.185486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.900004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:29:41.185581Z","caller":"traceutil/trace.go:171","msg":"trace[660488822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6460; }","duration":"228.048332ms","start":"2026-04-03T02:29:40.957515Z","end":"2026-04-03T02:29:41.185563Z","steps":["trace[660488822] 'agreement among raft nodes before linearized reading' (duration: 227.822549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:41.185468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.89398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:29:41.185709Z","caller":"traceutil/trace.go:171","msg":"trace[703031532] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:6460; }","duration":"127.185377ms","start":"2026-04-03T02:29:41.058497Z","end":"2026-04-03T02:29:41.185682Z","steps":["trace[703031532] 'agreement among raft nodes before linearized reading' (duration: 126.872629ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:42.5192Z","caller":"traceutil/trace.go:171","msg":"trace[154093575] linearizableReadLoop","detail":"{readStateIndex:6700; appliedIndex:6697; }","duration":"105.617213ms","start":"2026-04-03T02:29:42.413563Z","end":"2026-04-03T02:29:42.51918Z","steps":["trace[154093575] 'read index received' (duration: 34.126792ms)","trace[154093575] 'applied index is now lower than readState.Index' (duration: 71.486991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:29:42.519545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.960193ms","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-04-03T02:29:42.519792Z","caller":"traceutil/trace.go:171","msg":"trace[690274713] transaction","detail":"{read_only:false; response_revision:6466; number_of_response:1; }","duration":"131.532982ms","start":"2026-04-03T02:29:42.388223Z","end":"2026-04-03T02:29:42.519756Z","steps":["trace[690274713] 'process raft request' (duration: 130.921236ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:42.519596Z","caller":"traceutil/trace.go:171","msg":"trace[515849330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6466; }","duration":"106.06227ms","start":"2026-04-03T02:29:42.413523Z","end":"2026-04-03T02:29:42.519585Z","steps":["trace[515849330] 'agreement among raft nodes before linearized reading' (duration: 105.89068ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:42.519838Z","caller":"traceutil/trace.go:171","msg":"trace[2088317803] transaction","detail":"{read_only:false; response_revision:6465; number_of_response:1; }","duration":"133.257751ms","start":"2026-04-03T02:29:42.386475Z","end":"2026-04-03T02:29:42.519733Z","steps":["trace[2088317803] 'process raft request' (duration: 132.54132ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:43.320986Z","caller":"traceutil/trace.go:171","msg":"trace[1659032346] transaction","detail":"{read_only:false; response_revision:6471; number_of_response:1; }","duration":"122.816051ms","start":"2026-04-03T02:29:43.198138Z","end":"2026-04-03T02:29:43.320954Z","steps":["trace[1659032346] 'process raft request' (duration: 122.63358ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:43.695097Z","caller":"traceutil/trace.go:171","msg":"trace[11697252] transaction","detail":"{read_only:false; response_revision:6472; number_of_response:1; }","duration":"165.349631ms","start":"2026-04-03T02:29:43.529719Z","end":"2026-04-03T02:29:43.695069Z","steps":["trace[11697252] 'process raft request' (duration: 165.118697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:43.911724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.498889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-03T02:29:43.911847Z","caller":"traceutil/trace.go:171","msg":"trace[1672901291] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6472; }","duration":"128.662848ms","start":"2026-04-03T02:29:43.783161Z","end":"2026-04-03T02:29:43.911824Z","steps":["trace[1672901291] 'range keys from in-memory index tree' (duration: 128.282397ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:55.545051Z","caller":"traceutil/trace.go:171","msg":"trace[1889812084] transaction","detail":"{read_only:false; response_revision:6585; number_of_response:1; }","duration":"224.437089ms","start":"2026-04-03T02:29:55.320589Z","end":"2026-04-03T02:29:55.545026Z","steps":["trace[1889812084] 'process raft request' (duration: 224.250428ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:56.181749Z","caller":"traceutil/trace.go:171","msg":"trace[1017810592] linearizableReadLoop","detail":"{readStateIndex:6824; appliedIndex:6823; }","duration":"224.330047ms","start":"2026-04-03T02:29:55.95698Z","end":"2026-04-03T02:29:56.18131Z","steps":["trace[1017810592] 'read index received' (duration: 223.292478ms)","trace[1017810592] 'applied index is now lower than readState.Index' (duration: 1.036769ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:29:56.182157Z","caller":"traceutil/trace.go:171","msg":"trace[63456957] transaction","detail":"{read_only:false; response_revision:6586; number_of_response:1; }","duration":"431.238158ms","start":"2026-04-03T02:29:55.750802Z","end":"2026-04-03T02:29:56.182041Z","steps":["trace[63456957] 'process raft request' (duration: 429.535159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:29:56.182384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:29:55.750782Z","time spent":"431.496893ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:29:56.518527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.529276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:29:56.518615Z","caller":"traceutil/trace.go:171","msg":"trace[1279050551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6586; }","duration":"561.675894ms","start":"2026-04-03T02:29:55.956915Z","end":"2026-04-03T02:29:56.518591Z","steps":["trace[1279050551] 'agreement among raft nodes before linearized reading' (duration: 225.274801ms)","trace[1279050551] 'range keys from in-memory index tree' (duration: 336.285936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:29:56.518657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:29:55.956892Z","time spent":"561.755909ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:29:56.519053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.761422ms","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-04-03T02:29:56.519093Z","caller":"traceutil/trace.go:171","msg":"trace[1217195163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6586; }","duration":"395.800614ms","start":"2026-04-03T02:29:56.12328Z","end":"2026-04-03T02:29:56.519081Z","steps":["trace[1217195163] 'agreement among raft nodes before linearized reading' (duration: 58.934276ms)","trace[1217195163] 'range keys from in-memory index tree' (duration: 336.480346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:29:56.519123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:29:56.123202Z","time spent":"395.913801ms","remote":"127.0.0.1:33032","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-04-03T02:29:56.519118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.488773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T02:29:56.519226Z","caller":"traceutil/trace.go:171","msg":"trace[1653402454] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:6586; }","duration":"381.60972ms","start":"2026-04-03T02:29:56.137593Z","end":"2026-04-03T02:29:56.519203Z","steps":["trace[1653402454] 'agreement among raft nodes before linearized reading' (duration: 44.634824ms)","trace[1653402454] 'range keys from in-memory index tree' (duration: 336.583592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:29:56.519267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:29:56.137579Z","time spent":"381.677964ms","remote":"127.0.0.1:32808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-04-03T02:29:56.519316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.285835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:4 size:218834"} {"level":"info","ts":"2026-04-03T02:29:56.519497Z","caller":"traceutil/trace.go:171","msg":"trace[772054584] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:4; response_revision:6586; }","duration":"395.581392ms","start":"2026-04-03T02:29:56.123859Z","end":"2026-04-03T02:29:56.519441Z","steps":["trace[772054584] 'agreement among raft nodes before linearized reading' (duration: 58.378543ms)","trace[772054584] 'range keys from in-memory index tree' (duration: 336.622145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:29:56.518738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.259402ms","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-04-03T02:29:56.519624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:29:56.123817Z","time spent":"395.788114ms","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":4,"response size":218857,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-04-03T02:29:56.51967Z","caller":"traceutil/trace.go:171","msg":"trace[1418370517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6586; }","duration":"184.252739ms","start":"2026-04-03T02:29:56.335404Z","end":"2026-04-03T02:29:56.519656Z","steps":["trace[1418370517] 'range keys from in-memory index tree' (duration: 183.122983ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:56.649893Z","caller":"traceutil/trace.go:171","msg":"trace[1057568594] transaction","detail":"{read_only:false; response_revision:6587; number_of_response:1; }","duration":"124.804814ms","start":"2026-04-03T02:29:56.525066Z","end":"2026-04-03T02:29:56.649871Z","steps":["trace[1057568594] 'process raft request' (duration: 94.027585ms)","trace[1057568594] 'compare' (duration: 30.238008ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:29:56.650091Z","caller":"traceutil/trace.go:171","msg":"trace[805739633] transaction","detail":"{read_only:false; response_revision:6588; number_of_response:1; }","duration":"124.618082ms","start":"2026-04-03T02:29:56.525466Z","end":"2026-04-03T02:29:56.650084Z","steps":["trace[805739633] 'process raft request' (duration: 124.033898ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:29:56.650174Z","caller":"traceutil/trace.go:171","msg":"trace[1904337385] transaction","detail":"{read_only:false; response_revision:6589; number_of_response:1; }","duration":"120.169325ms","start":"2026-04-03T02:29:56.529999Z","end":"2026-04-03T02:29:56.650168Z","steps":["trace[1904337385] 'process raft request' (duration: 119.552438ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:30:02.864004Z","caller":"traceutil/trace.go:171","msg":"trace[285637826] linearizableReadLoop","detail":"{readStateIndex:6860; appliedIndex:6857; }","duration":"167.364289ms","start":"2026-04-03T02:30:02.696619Z","end":"2026-04-03T02:30:02.863983Z","steps":["trace[285637826] 'read index received' (duration: 3.93923ms)","trace[285637826] 'applied index is now lower than readState.Index' (duration: 163.424169ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:30:02.864114Z","caller":"traceutil/trace.go:171","msg":"trace[1208154764] transaction","detail":"{read_only:false; response_revision:6620; number_of_response:1; }","duration":"172.88236ms","start":"2026-04-03T02:30:02.691223Z","end":"2026-04-03T02:30:02.864105Z","steps":["trace[1208154764] 'process raft request' (duration: 172.684038ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:30:02.864116Z","caller":"traceutil/trace.go:171","msg":"trace[820781628] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"174.145214ms","start":"2026-04-03T02:30:02.689927Z","end":"2026-04-03T02:30:02.864072Z","steps":["trace[820781628] 'process raft request' (duration: 172.51771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:02.864312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.179613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-03T02:30:02.864392Z","caller":"traceutil/trace.go:171","msg":"trace[1886297595] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6620; }","duration":"159.30588ms","start":"2026-04-03T02:30:02.705063Z","end":"2026-04-03T02:30:02.864369Z","steps":["trace[1886297595] 'agreement among raft nodes before linearized reading' (duration: 159.125449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:02.864414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.27062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:30:02.864441Z","caller":"traceutil/trace.go:171","msg":"trace[1951977835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6620; }","duration":"158.328073ms","start":"2026-04-03T02:30:02.706105Z","end":"2026-04-03T02:30:02.864433Z","steps":["trace[1951977835] 'agreement among raft nodes before linearized reading' (duration: 158.207926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:02.864551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.931002ms","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-04-03T02:30:02.864566Z","caller":"traceutil/trace.go:171","msg":"trace[751696849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6620; }","duration":"167.972595ms","start":"2026-04-03T02:30:02.696589Z","end":"2026-04-03T02:30:02.864562Z","steps":["trace[751696849] 'agreement among raft nodes before linearized reading' (duration: 167.917062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:02.864697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.521143ms","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-04-03T02:30:02.864744Z","caller":"traceutil/trace.go:171","msg":"trace[1788013614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6620; }","duration":"159.638729ms","start":"2026-04-03T02:30:02.705093Z","end":"2026-04-03T02:30:02.864732Z","steps":["trace[1788013614] 'agreement among raft nodes before linearized reading' (duration: 159.418696ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:30:04.774836Z","caller":"traceutil/trace.go:171","msg":"trace[1662772133] linearizableReadLoop","detail":"{readStateIndex:6868; appliedIndex:6867; }","duration":"138.43054ms","start":"2026-04-03T02:30:04.636386Z","end":"2026-04-03T02:30:04.774817Z","steps":["trace[1662772133] 'read index received' (duration: 138.27929ms)","trace[1662772133] 'applied index is now lower than readState.Index' (duration: 150.699µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:30:04.77508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.670844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com\" ","response":"range_response_count:1 size:5872"} {"level":"info","ts":"2026-04-03T02:30:04.775116Z","caller":"traceutil/trace.go:171","msg":"trace[790848662] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com; range_end:; response_count:1; response_revision:6628; }","duration":"138.741278ms","start":"2026-04-03T02:30:04.636364Z","end":"2026-04-03T02:30:04.775105Z","steps":["trace[790848662] 'agreement among raft nodes before linearized reading' (duration: 138.631041ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:30:04.77524Z","caller":"traceutil/trace.go:171","msg":"trace[274541693] transaction","detail":"{read_only:false; response_revision:6628; number_of_response:1; }","duration":"139.267259ms","start":"2026-04-03T02:30:04.635965Z","end":"2026-04-03T02:30:04.775233Z","steps":["trace[274541693] 'process raft request' (duration: 138.743858ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:33.101617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5608} {"level":"info","ts":"2026-04-03T02:32:33.184429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5608,"took":"81.194897ms","hash":3504621910,"current-db-size-bytes":53645312,"current-db-size":"54 MB","current-db-size-in-use-bytes":18440192,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-03T02:32:33.184486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3504621910,"revision":5608,"compact-revision":2888} {"level":"info","ts":"2026-04-03T02:32:51.964625Z","caller":"traceutil/trace.go:171","msg":"trace[820958448] transaction","detail":"{read_only:false; response_revision:7502; number_of_response:1; }","duration":"160.503449ms","start":"2026-04-03T02:32:51.804102Z","end":"2026-04-03T02:32:51.964605Z","steps":["trace[820958448] 'process raft request' (duration: 160.318578ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:53.063441Z","caller":"traceutil/trace.go:171","msg":"trace[262824721] transaction","detail":"{read_only:false; response_revision:7509; number_of_response:1; }","duration":"187.002481ms","start":"2026-04-03T02:32:52.876408Z","end":"2026-04-03T02:32:53.06341Z","steps":["trace[262824721] 'process raft request' (duration: 186.846509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.039478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958598327,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T02:32:55.539654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421591958598327,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T02:32:55.819067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.281721898s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T02:32:55.819544Z","caller":"traceutil/trace.go:171","msg":"trace[1726257039] transaction","detail":"{read_only:false; response_revision:7510; number_of_response:1; }","duration":"1.282255683s","start":"2026-04-03T02:32:54.537261Z","end":"2026-04-03T02:32:55.819516Z","steps":["trace[1726257039] 'process raft request' (duration: 1.282085921s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.819776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.537252Z","time spent":"1.282374494s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:32:55.82226Z","caller":"traceutil/trace.go:171","msg":"trace[1919424670] transaction","detail":"{read_only:false; response_revision:7512; number_of_response:1; }","duration":"1.281069502s","start":"2026-04-03T02:32:54.541172Z","end":"2026-04-03T02:32:55.822242Z","steps":["trace[1919424670] 'process raft request' (duration: 1.281018612s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:55.822277Z","caller":"traceutil/trace.go:171","msg":"trace[980317071] transaction","detail":"{read_only:false; response_revision:7511; number_of_response:1; }","duration":"1.281147694s","start":"2026-04-03T02:32:54.541111Z","end":"2026-04-03T02:32:55.822258Z","steps":["trace[980317071] 'process raft request' (duration: 1.280992782s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.822404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.541098Z","time spent":"1.281222474s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:32:55.822448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.541169Z","time spent":"1.281134683s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:32:55.822586Z","caller":"traceutil/trace.go:171","msg":"trace[663194485] transaction","detail":"{read_only:false; response_revision:7513; number_of_response:1; }","duration":"1.280856171s","start":"2026-04-03T02:32:54.541715Z","end":"2026-04-03T02:32:55.822571Z","steps":["trace[663194485] 'process raft request' (duration: 1.280499958s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:55.822683Z","caller":"traceutil/trace.go:171","msg":"trace[549460204] linearizableReadLoop","detail":"{readStateIndex:7792; appliedIndex:7784; }","duration":"1.283868416s","start":"2026-04-03T02:32:54.538796Z","end":"2026-04-03T02:32:55.822665Z","steps":["trace[549460204] 'read index received' (duration: 1.280563919s)","trace[549460204] 'applied index is now lower than readState.Index' (duration: 3.303597ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:32:55.822721Z","caller":"traceutil/trace.go:171","msg":"trace[979343550] transaction","detail":"{read_only:false; response_revision:7516; number_of_response:1; }","duration":"1.153550807s","start":"2026-04-03T02:32:54.669118Z","end":"2026-04-03T02:32:55.822669Z","steps":["trace[979343550] 'process raft request' (duration: 1.153438506s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:55.82269Z","caller":"traceutil/trace.go:171","msg":"trace[1474387319] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"1.2670603s","start":"2026-04-03T02:32:54.555599Z","end":"2026-04-03T02:32:55.822659Z","steps":["trace[1474387319] 'process raft request' (duration: 1.266904259s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:55.8226Z","caller":"traceutil/trace.go:171","msg":"trace[1470484959] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"1.277463864s","start":"2026-04-03T02:32:54.545111Z","end":"2026-04-03T02:32:55.822575Z","steps":["trace[1470484959] 'process raft request' (duration: 1.277312513s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:55.822741Z","caller":"traceutil/trace.go:171","msg":"trace[1164635352] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"752.112995ms","start":"2026-04-03T02:32:55.070596Z","end":"2026-04-03T02:32:55.822709Z","steps":["trace[1164635352] 'process raft request' (duration: 752.013264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.822967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284111727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-03T02:32:55.82302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21735933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-scheduler\" ","response":"range_response_count:1 size:16130"} {"level":"warn","ts":"2026-04-03T02:32:55.823023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18144994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-workload-total\" ","response":"range_response_count:1 size:21668"} {"level":"info","ts":"2026-04-03T02:32:55.823052Z","caller":"traceutil/trace.go:171","msg":"trace[1959060123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7517; }","duration":"1.28426731s","start":"2026-04-03T02:32:54.538766Z","end":"2026-04-03T02:32:55.823033Z","steps":["trace[1959060123] 'agreement among raft nodes before linearized reading' (duration: 1.284016227s)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:55.823067Z","caller":"traceutil/trace.go:171","msg":"trace[383491005] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-workload-total; range_end:; response_count:1; response_revision:7517; }","duration":"1.181532391s","start":"2026-04-03T02:32:54.641521Z","end":"2026-04-03T02:32:55.823054Z","steps":["trace[383491005] 'agreement among raft nodes before linearized reading' (duration: 1.181323739s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.823101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.641495Z","time spent":"1.181599222s","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":21691,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-workload-total\" "} {"level":"warn","ts":"2026-04-03T02:32:55.823101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.53875Z","time spent":"1.28433857s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T02:32:55.823131Z","caller":"traceutil/trace.go:171","msg":"trace[737203338] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-scheduler; range_end:; response_count:1; response_revision:7517; }","duration":"1.217464111s","start":"2026-04-03T02:32:54.605577Z","end":"2026-04-03T02:32:55.823041Z","steps":["trace[737203338] 'agreement among raft nodes before linearized reading' (duration: 1.217290809s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.823218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.605561Z","time spent":"1.217591842s","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":16153,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-scheduler\" "} {"level":"warn","ts":"2026-04-03T02:32:55.822894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281887249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:32:55.823288Z","caller":"traceutil/trace.go:171","msg":"trace[1385798401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7517; }","duration":"1.282278673s","start":"2026-04-03T02:32:54.540972Z","end":"2026-04-03T02:32:55.823251Z","steps":["trace[1385798401] 'agreement among raft nodes before linearized reading' (duration: 1.281868919s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:55.823338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.540967Z","time spent":"1.282353263s","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:32:55.823413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.54171Z","time spent":"1.280933342s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:32:55.823593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.555582Z","time spent":"1.26717732s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:32:55.82366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.669097Z","time spent":"1.153646387s","remote":"127.0.0.1:33032","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-04-03T02:32:55.823578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:55.070567Z","time spent":"752.652628ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:32:55.82376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:32:54.545089Z","time spent":"1.277791236s","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}