{"level":"warn","ts":"2026-04-29T15:17:44.556826Z","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-29T15:17:44.556948Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-29T15:17:44.557045Z","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-29T15:17:44.557058Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-29T15:17:44.557076Z","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-29T15:17:44.557751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-04-29T15:17:44.557824Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-29T15:17:44.561967Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.889638ms"} {"level":"info","ts":"2026-04-29T15:17:44.567352Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-04-29T15:17:44.567435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-04-29T15:17:44.567463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-04-29T15:17:44.567478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-29T15:17:44.567487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-04-29T15:17:44.567536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-04-29T15:17:44.574133Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-29T15:17:44.575775Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-29T15:17:44.577016Z","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-29T15:17:44.579386Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-29T15:17:44.579636Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-29T15:17:44.581037Z","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-29T15:17:44.58114Z","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-29T15:17:44.581162Z","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-29T15:17:44.583544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-04-29T15:17:44.583832Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-29T15:17:44.586987Z","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-29T15:17:44.587174Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-29T15:17:44.58722Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-29T15:17:44.587381Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-29T15:17:44.587445Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-29T15:17:45.467725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-04-29T15:17:45.467796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-29T15:17:45.467848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-04-29T15:17:45.467872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-04-29T15:17:45.46789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-29T15:17:45.467908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-04-29T15:17:45.467927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-29T15:17:45.469368Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T15:17:45.469556Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-04-29T15:17:45.469579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-29T15:17:45.46968Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-29T15:17:45.470064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-29T15:17:45.470162Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-29T15:17:45.471092Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T15:17:45.47121Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T15:17:45.471354Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T15:17:45.473467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-29T15:17:45.47355Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-04-29T15:18:25.754479Z","caller":"traceutil/trace.go:171","msg":"trace[2032569424] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"118.493514ms","start":"2026-04-29T15:18:25.63595Z","end":"2026-04-29T15:18:25.754444Z","steps":["trace[2032569424] 'read index received' (duration: 118.219067ms)","trace[2032569424] 'applied index is now lower than readState.Index' (duration: 272.607µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:18:25.754614Z","caller":"traceutil/trace.go:171","msg":"trace[63739606] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"159.892308ms","start":"2026-04-29T15:18:25.594672Z","end":"2026-04-29T15:18:25.754565Z","steps":["trace[63739606] 'process raft request' (duration: 159.454167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:18:25.754736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.775122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:18:25.754862Z","caller":"traceutil/trace.go:171","msg":"trace[840224752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"118.979067ms","start":"2026-04-29T15:18:25.635866Z","end":"2026-04-29T15:18:25.754845Z","steps":["trace[840224752] 'agreement among raft nodes before linearized reading' (duration: 118.810562ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:18:26.063755Z","caller":"traceutil/trace.go:171","msg":"trace[1179598419] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"309.141623ms","start":"2026-04-29T15:18:25.754576Z","end":"2026-04-29T15:18:26.063717Z","steps":["trace[1179598419] 'read index received' (duration: 308.269369ms)","trace[1179598419] 'applied index is now lower than readState.Index' (duration: 871.284µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:18:26.063828Z","caller":"traceutil/trace.go:171","msg":"trace[375447618] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"335.410828ms","start":"2026-04-29T15:18:25.728387Z","end":"2026-04-29T15:18:26.063798Z","steps":["trace[375447618] 'process raft request' (duration: 334.72726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:18:26.064056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.338842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-29T15:18:26.064062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.016512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:18:26.064105Z","caller":"traceutil/trace.go:171","msg":"trace[1988944438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"312.460925ms","start":"2026-04-29T15:18:25.75163Z","end":"2026-04-29T15:18:26.064091Z","steps":["trace[1988944438] 'agreement among raft nodes before linearized reading' (duration: 312.255549ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:18:26.064161Z","caller":"traceutil/trace.go:171","msg":"trace[1951611219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"308.159376ms","start":"2026-04-29T15:18:25.755974Z","end":"2026-04-29T15:18:26.064133Z","steps":["trace[1951611219] 'agreement among raft nodes before linearized reading' (duration: 307.97368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:18:26.064159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:18:25.751603Z","time spent":"312.545918ms","remote":"127.0.0.1:34750","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-29T15:18:26.064228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:18:25.755959Z","time spent":"308.25951ms","remote":"127.0.0.1:34462","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-29T15:18:26.064568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:18:25.728347Z","time spent":"335.552223ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T15:18:42.327965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.339524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-29T15:18:42.328094Z","caller":"traceutil/trace.go:171","msg":"trace[1241789403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"112.53811ms","start":"2026-04-29T15:18:42.215535Z","end":"2026-04-29T15:18:42.328073Z","steps":["trace[1241789403] 'range keys from in-memory index tree' (duration: 112.135819ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:19:09.150894Z","caller":"traceutil/trace.go:171","msg":"trace[1927748036] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"126.047372ms","start":"2026-04-29T15:19:09.024815Z","end":"2026-04-29T15:19:09.150863Z","steps":["trace[1927748036] 'process raft request' (duration: 125.847697ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:20:05.282079Z","caller":"traceutil/trace.go:171","msg":"trace[113295827] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"104.826405ms","start":"2026-04-29T15:20:05.177221Z","end":"2026-04-29T15:20:05.282048Z","steps":["trace[113295827] 'process raft request' (duration: 103.851878ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:20:10.921046Z","caller":"traceutil/trace.go:171","msg":"trace[524127191] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"158.647737ms","start":"2026-04-29T15:20:10.762358Z","end":"2026-04-29T15:20:10.921006Z","steps":["trace[524127191] 'read index received' (duration: 156.97706ms)","trace[524127191] 'applied index is now lower than readState.Index' (duration: 1.669297ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:20:10.921174Z","caller":"traceutil/trace.go:171","msg":"trace[1060889300] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"234.067562ms","start":"2026-04-29T15:20:10.687093Z","end":"2026-04-29T15:20:10.921161Z","steps":["trace[1060889300] 'process raft request' (duration: 232.21967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:20:10.921451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.063779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-29T15:20:10.92178Z","caller":"traceutil/trace.go:171","msg":"trace[1877474523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1934; }","duration":"159.444689ms","start":"2026-04-29T15:20:10.762307Z","end":"2026-04-29T15:20:10.921751Z","steps":["trace[1877474523] 'agreement among raft nodes before linearized reading' (duration: 158.927135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:20:10.927505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.529301ms","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-29T15:20:10.927643Z","caller":"traceutil/trace.go:171","msg":"trace[2040634758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1935; }","duration":"140.738427ms","start":"2026-04-29T15:20:10.786879Z","end":"2026-04-29T15:20:10.927618Z","steps":["trace[2040634758] 'agreement among raft nodes before linearized reading' (duration: 140.49112ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:20:10.927696Z","caller":"traceutil/trace.go:171","msg":"trace[1782277445] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"112.669794ms","start":"2026-04-29T15:20:10.815007Z","end":"2026-04-29T15:20:10.927677Z","steps":["trace[1782277445] 'process raft request' (duration: 112.200961ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:20:41.589038Z","caller":"traceutil/trace.go:171","msg":"trace[1099471361] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"108.22998ms","start":"2026-04-29T15:20:41.480771Z","end":"2026-04-29T15:20:41.589001Z","steps":["trace[1099471361] 'read index received' (duration: 108.004654ms)","trace[1099471361] 'applied index is now lower than readState.Index' (duration: 223.656µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:20:41.589173Z","caller":"traceutil/trace.go:171","msg":"trace[1267340737] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"115.824819ms","start":"2026-04-29T15:20:41.473299Z","end":"2026-04-29T15:20:41.589124Z","steps":["trace[1267340737] 'process raft request' (duration: 115.49014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:20:41.589381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.571009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-29T15:20:41.589437Z","caller":"traceutil/trace.go:171","msg":"trace[741576329] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2117; }","duration":"108.688582ms","start":"2026-04-29T15:20:41.48073Z","end":"2026-04-29T15:20:41.589419Z","steps":["trace[741576329] 'agreement among raft nodes before linearized reading' (duration: 108.414765ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:20:47.339484Z","caller":"traceutil/trace.go:171","msg":"trace[2029854633] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"107.754898ms","start":"2026-04-29T15:20:47.231681Z","end":"2026-04-29T15:20:47.339436Z","steps":["trace[2029854633] 'process raft request' (duration: 103.404797ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:20:47.87928Z","caller":"traceutil/trace.go:171","msg":"trace[1404419303] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"243.118627ms","start":"2026-04-29T15:20:47.636132Z","end":"2026-04-29T15:20:47.87925Z","steps":["trace[1404419303] 'read index received' (duration: 242.87396ms)","trace[1404419303] 'applied index is now lower than readState.Index' (duration: 243.517µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:20:47.879429Z","caller":"traceutil/trace.go:171","msg":"trace[950445604] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"256.241428ms","start":"2026-04-29T15:20:47.623137Z","end":"2026-04-29T15:20:47.879379Z","steps":["trace[950445604] 'process raft request' (duration: 255.94462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:20:47.879516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.658451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:20:47.879574Z","caller":"traceutil/trace.go:171","msg":"trace[136595562] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2149; }","duration":"219.755083ms","start":"2026-04-29T15:20:47.659803Z","end":"2026-04-29T15:20:47.879558Z","steps":["trace[136595562] 'agreement among raft nodes before linearized reading' (duration: 219.62869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:20:47.879539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.384644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:20:47.879651Z","caller":"traceutil/trace.go:171","msg":"trace[946221777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"243.551038ms","start":"2026-04-29T15:20:47.636074Z","end":"2026-04-29T15:20:47.879625Z","steps":["trace[946221777] 'agreement among raft nodes before linearized reading' (duration: 243.346863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:22:11.233025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.491066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-29T15:22:11.23343Z","caller":"traceutil/trace.go:171","msg":"trace[216470917] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"137.243085ms","start":"2026-04-29T15:22:11.09616Z","end":"2026-04-29T15:22:11.233403Z","steps":["trace[216470917] 'process raft request' (duration: 15.217315ms)","trace[216470917] 'compare' (duration: 121.116835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:22:11.497708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.143648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-29T15:22:11.497825Z","caller":"traceutil/trace.go:171","msg":"trace[258659649] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"260.292904ms","start":"2026-04-29T15:22:11.237512Z","end":"2026-04-29T15:22:11.497804Z","steps":["trace[258659649] 'process raft request' (duration: 124.957882ms)","trace[258659649] 'compare' (duration: 134.946852ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:24:48.233688Z","caller":"traceutil/trace.go:171","msg":"trace[1634813699] linearizableReadLoop","detail":"{readStateIndex:3965; appliedIndex:3964; }","duration":"105.574662ms","start":"2026-04-29T15:24:48.128087Z","end":"2026-04-29T15:24:48.233661Z","steps":["trace[1634813699] 'read index received' (duration: 105.438318ms)","trace[1634813699] 'applied index is now lower than readState.Index' (duration: 135.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:24:48.233922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.817658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-29T15:24:48.233963Z","caller":"traceutil/trace.go:171","msg":"trace[1084317714] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3821; }","duration":"105.932812ms","start":"2026-04-29T15:24:48.128017Z","end":"2026-04-29T15:24:48.23395Z","steps":["trace[1084317714] 'agreement among raft nodes before linearized reading' (duration: 105.752927ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:24:48.233968Z","caller":"traceutil/trace.go:171","msg":"trace[1405777070] transaction","detail":"{read_only:false; response_revision:3821; number_of_response:1; }","duration":"122.358792ms","start":"2026-04-29T15:24:48.111583Z","end":"2026-04-29T15:24:48.233941Z","steps":["trace[1405777070] 'process raft request' (duration: 121.907919ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:26:12.658702Z","caller":"traceutil/trace.go:171","msg":"trace[877486883] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"102.636431ms","start":"2026-04-29T15:26:12.556016Z","end":"2026-04-29T15:26:12.658652Z","steps":["trace[877486883] 'process raft request' (duration: 102.318132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:26:17.280283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.757258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5tztx\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-29T15:26:17.28039Z","caller":"traceutil/trace.go:171","msg":"trace[525857400] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5tztx; range_end:; response_count:1; response_revision:5039; }","duration":"130.929392ms","start":"2026-04-29T15:26:17.149436Z","end":"2026-04-29T15:26:17.280365Z","steps":["trace[525857400] 'range keys from in-memory index tree' (duration: 130.526152ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:26:29.860769Z","caller":"traceutil/trace.go:171","msg":"trace[1099851857] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5478; }","duration":"119.92763ms","start":"2026-04-29T15:26:29.740815Z","end":"2026-04-29T15:26:29.860743Z","steps":["trace[1099851857] 'read index received' (duration: 119.666202ms)","trace[1099851857] 'applied index is now lower than readState.Index' (duration: 260.438µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:26:29.860838Z","caller":"traceutil/trace.go:171","msg":"trace[1876125575] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"120.293481ms","start":"2026-04-29T15:26:29.740512Z","end":"2026-04-29T15:26:29.860806Z","steps":["trace[1876125575] 'process raft request' (duration: 120.051993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:26:29.861037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.192158ms","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-29T15:26:29.861088Z","caller":"traceutil/trace.go:171","msg":"trace[296923674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5278; }","duration":"120.28265ms","start":"2026-04-29T15:26:29.740789Z","end":"2026-04-29T15:26:29.861072Z","steps":["trace[296923674] 'agreement among raft nodes before linearized reading' (duration: 120.099365ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:26:29.90234Z","caller":"traceutil/trace.go:171","msg":"trace[1673893362] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"112.003106ms","start":"2026-04-29T15:26:29.790308Z","end":"2026-04-29T15:26:29.902311Z","steps":["trace[1673893362] 'process raft request' (duration: 111.820891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:26:29.902432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.273135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-29T15:26:29.902488Z","caller":"traceutil/trace.go:171","msg":"trace[1370436291] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5279; }","duration":"153.399489ms","start":"2026-04-29T15:26:29.749074Z","end":"2026-04-29T15:26:29.902473Z","steps":["trace[1370436291] 'agreement among raft nodes before linearized reading' (duration: 153.188073ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:26:37.450083Z","caller":"traceutil/trace.go:171","msg":"trace[1629379920] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"111.312547ms","start":"2026-04-29T15:26:37.338727Z","end":"2026-04-29T15:26:37.45004Z","steps":["trace[1629379920] 'process raft request' (duration: 110.953516ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:26:48.867847Z","caller":"traceutil/trace.go:171","msg":"trace[1822317175] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"109.373815ms","start":"2026-04-29T15:26:48.758442Z","end":"2026-04-29T15:26:48.867816Z","steps":["trace[1822317175] 'process raft request' (duration: 94.134362ms)","trace[1822317175] 'compare' (duration: 15.12699ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:27:45.501075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-04-29T15:27:45.570771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"68.186941ms","hash":3625043236,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-29T15:27:45.570843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3625043236,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-04-29T15:32:41.899361Z","caller":"traceutil/trace.go:171","msg":"trace[2010251512] linearizableReadLoop","detail":"{readStateIndex:8552; appliedIndex:8551; }","duration":"263.376514ms","start":"2026-04-29T15:32:41.635958Z","end":"2026-04-29T15:32:41.899335Z","steps":["trace[2010251512] 'read index received' (duration: 263.216859ms)","trace[2010251512] 'applied index is now lower than readState.Index' (duration: 158.675µs)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:32:41.899526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.546869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:32:41.899565Z","caller":"traceutil/trace.go:171","msg":"trace[1306694019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8259; }","duration":"263.618881ms","start":"2026-04-29T15:32:41.635932Z","end":"2026-04-29T15:32:41.899551Z","steps":["trace[1306694019] 'agreement among raft nodes before linearized reading' (duration: 263.546949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:32:41.899689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.203562ms","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-29T15:32:41.899778Z","caller":"traceutil/trace.go:171","msg":"trace[145193992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8259; }","duration":"201.351686ms","start":"2026-04-29T15:32:41.69841Z","end":"2026-04-29T15:32:41.899761Z","steps":["trace[145193992] 'agreement among raft nodes before linearized reading' (duration: 201.101129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:32:41.899755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.948225ms","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-29T15:32:41.899845Z","caller":"traceutil/trace.go:171","msg":"trace[2140333953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8259; }","duration":"201.091389ms","start":"2026-04-29T15:32:41.698732Z","end":"2026-04-29T15:32:41.899823Z","steps":["trace[2140333953] 'agreement among raft nodes before linearized reading' (duration: 200.809041ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:32:41.89993Z","caller":"traceutil/trace.go:171","msg":"trace[1000142913] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"338.667708ms","start":"2026-04-29T15:32:41.560772Z","end":"2026-04-29T15:32:41.89944Z","steps":["trace[1000142913] 'process raft request' (duration: 338.42314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:32:41.90006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:32:41.560743Z","time spent":"339.250454ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T15:32:45.508574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6083} {"level":"info","ts":"2026-04-29T15:32:45.605151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6083,"took":"94.572739ms","hash":1132506211,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":23351296,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-29T15:32:45.605246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1132506211,"revision":6083,"compact-revision":2983} {"level":"info","ts":"2026-04-29T15:32:53.502188Z","caller":"traceutil/trace.go:171","msg":"trace[982498252] linearizableReadLoop","detail":"{readStateIndex:8618; appliedIndex:8617; }","duration":"179.626621ms","start":"2026-04-29T15:32:53.322522Z","end":"2026-04-29T15:32:53.502149Z","steps":["trace[982498252] 'read index received' (duration: 179.218779ms)","trace[982498252] 'applied index is now lower than readState.Index' (duration: 406.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:32:53.502318Z","caller":"traceutil/trace.go:171","msg":"trace[488623500] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"207.167051ms","start":"2026-04-29T15:32:53.29511Z","end":"2026-04-29T15:32:53.502277Z","steps":["trace[488623500] 'process raft request' (duration: 206.734839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:32:53.502401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.840096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:32:53.502506Z","caller":"traceutil/trace.go:171","msg":"trace[1507665085] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:8321; }","duration":"180.006251ms","start":"2026-04-29T15:32:53.322476Z","end":"2026-04-29T15:32:53.502482Z","steps":["trace[1507665085] 'agreement among raft nodes before linearized reading' (duration: 179.809216ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:32:54.394198Z","caller":"traceutil/trace.go:171","msg":"trace[1315206169] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"286.699835ms","start":"2026-04-29T15:32:54.107471Z","end":"2026-04-29T15:32:54.39417Z","steps":["trace[1315206169] 'process raft request' (duration: 286.52346ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:32:54.401549Z","caller":"traceutil/trace.go:171","msg":"trace[2004418684] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"293.823556ms","start":"2026-04-29T15:32:54.107692Z","end":"2026-04-29T15:32:54.401515Z","steps":["trace[2004418684] 'process raft request' (duration: 293.553178ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:32:54.401546Z","caller":"traceutil/trace.go:171","msg":"trace[20658329] linearizableReadLoop","detail":"{readStateIndex:8624; appliedIndex:8621; }","duration":"100.049335ms","start":"2026-04-29T15:32:54.301447Z","end":"2026-04-29T15:32:54.401497Z","steps":["trace[20658329] 'read index received' (duration: 92.593304ms)","trace[20658329] 'applied index is now lower than readState.Index' (duration: 7.454461ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:32:54.401583Z","caller":"traceutil/trace.go:171","msg":"trace[1835386717] transaction","detail":"{read_only:false; response_revision:8326; number_of_response:1; }","duration":"292.814068ms","start":"2026-04-29T15:32:54.108736Z","end":"2026-04-29T15:32:54.40155Z","steps":["trace[1835386717] 'process raft request' (duration: 292.700924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:32:54.401906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.423876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-29T15:32:54.401959Z","caller":"traceutil/trace.go:171","msg":"trace[1691678187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8326; }","duration":"100.53948ms","start":"2026-04-29T15:32:54.301406Z","end":"2026-04-29T15:32:54.401945Z","steps":["trace[1691678187] 'agreement among raft nodes before linearized reading' (duration: 100.2366ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:33:22.977879Z","caller":"traceutil/trace.go:171","msg":"trace[1224515767] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"427.250395ms","start":"2026-04-29T15:33:22.550598Z","end":"2026-04-29T15:33:22.977848Z","steps":["trace[1224515767] 'process raft request' (duration: 427.099811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:33:22.978055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:33:22.550579Z","time spent":"427.389699ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T15:33:22.998917Z","caller":"traceutil/trace.go:171","msg":"trace[1319888057] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8835; }","duration":"429.586922ms","start":"2026-04-29T15:33:22.569304Z","end":"2026-04-29T15:33:22.99889Z","steps":["trace[1319888057] 'read index received' (duration: 408.306392ms)","trace[1319888057] 'applied index is now lower than readState.Index' (duration: 21.27955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:33:22.999149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.823049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-29T15:33:22.99919Z","caller":"traceutil/trace.go:171","msg":"trace[1311809830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8532; }","duration":"429.919961ms","start":"2026-04-29T15:33:22.569259Z","end":"2026-04-29T15:33:22.999179Z","steps":["trace[1311809830] 'agreement among raft nodes before linearized reading' (duration: 429.765977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:33:22.999201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.344024ms","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-29T15:33:22.999288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:33:22.569237Z","time spent":"429.986083ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-29T15:33:22.999292Z","caller":"traceutil/trace.go:171","msg":"trace[2046962226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8532; }","duration":"429.467418ms","start":"2026-04-29T15:33:22.569813Z","end":"2026-04-29T15:33:22.999281Z","steps":["trace[2046962226] 'agreement among raft nodes before linearized reading' (duration: 429.272653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:33:22.999307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.921652ms","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-29T15:33:22.999361Z","caller":"traceutil/trace.go:171","msg":"trace[1242061792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8532; }","duration":"160.053086ms","start":"2026-04-29T15:33:22.839296Z","end":"2026-04-29T15:33:22.999349Z","steps":["trace[1242061792] 'agreement among raft nodes before linearized reading' (duration: 159.82366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:33:22.999377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:33:22.569799Z","time spent":"429.525019ms","remote":"127.0.0.1:34750","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-29T15:33:22.999399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.372479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-29T15:33:22.99948Z","caller":"traceutil/trace.go:171","msg":"trace[1299529404] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8532; }","duration":"270.523643ms","start":"2026-04-29T15:33:22.728938Z","end":"2026-04-29T15:33:22.999461Z","steps":["trace[1299529404] 'agreement among raft nodes before linearized reading' (duration: 270.205534ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:33:22.999043Z","caller":"traceutil/trace.go:171","msg":"trace[1954305691] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"435.034146ms","start":"2026-04-29T15:33:22.563985Z","end":"2026-04-29T15:33:22.999019Z","steps":["trace[1954305691] 'process raft request' (duration: 434.735017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:33:22.999649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:33:22.563967Z","time spent":"435.55829ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T15:33:34.907992Z","caller":"traceutil/trace.go:171","msg":"trace[1385871696] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"153.874982ms","start":"2026-04-29T15:33:34.754094Z","end":"2026-04-29T15:33:34.907968Z","steps":["trace[1385871696] 'process raft request' (duration: 153.729248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:33:35.173763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.164066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"info","ts":"2026-04-29T15:33:35.173849Z","caller":"traceutil/trace.go:171","msg":"trace[1776567551] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8636; }","duration":"173.29296ms","start":"2026-04-29T15:33:35.000534Z","end":"2026-04-29T15:33:35.173827Z","steps":["trace[1776567551] 'range keys from in-memory index tree' (duration: 172.9658ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:33:35.173818Z","caller":"traceutil/trace.go:171","msg":"trace[780247664] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"154.156981ms","start":"2026-04-29T15:33:35.019625Z","end":"2026-04-29T15:33:35.173782Z","steps":["trace[780247664] 'process raft request' (duration: 66.551898ms)","trace[780247664] 'compare' (duration: 87.461059ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:33:35.177909Z","caller":"traceutil/trace.go:171","msg":"trace[2015083977] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"134.542136ms","start":"2026-04-29T15:33:35.043346Z","end":"2026-04-29T15:33:35.177888Z","steps":["trace[2015083977] 'process raft request' (duration: 134.275369ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:33:51.495382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b2.wal"} {"level":"info","ts":"2026-04-29T15:35:27.969166Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T15:35:27.975842Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-29T15:35:27.97595Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-29T15:37:45.515967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8278} {"level":"info","ts":"2026-04-29T15:37:45.592783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8278,"took":"74.411901ms","hash":904084698,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":26091520,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-29T15:37:45.592856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":904084698,"revision":8278,"compact-revision":6083} {"level":"info","ts":"2026-04-29T15:38:38.962217Z","caller":"traceutil/trace.go:171","msg":"trace[893125008] linearizableReadLoop","detail":"{readStateIndex:11647; appliedIndex:11646; }","duration":"116.88684ms","start":"2026-04-29T15:38:38.845293Z","end":"2026-04-29T15:38:38.96218Z","steps":["trace[893125008] 'read index received' (duration: 75.316546ms)","trace[893125008] 'applied index is now lower than readState.Index' (duration: 41.569094ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:38:38.962338Z","caller":"traceutil/trace.go:171","msg":"trace[1619040598] transaction","detail":"{read_only:false; response_revision:11261; number_of_response:1; }","duration":"117.574719ms","start":"2026-04-29T15:38:38.844714Z","end":"2026-04-29T15:38:38.962289Z","steps":["trace[1619040598] 'process raft request' (duration: 75.854511ms)","trace[1619040598] 'compare' (duration: 41.265325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:38:38.962536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.210279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-29T15:38:38.962593Z","caller":"traceutil/trace.go:171","msg":"trace[670033437] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11261; }","duration":"117.306282ms","start":"2026-04-29T15:38:38.845269Z","end":"2026-04-29T15:38:38.962575Z","steps":["trace[670033437] 'agreement among raft nodes before linearized reading' (duration: 117.084066ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:42:45.522838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10770} {"level":"info","ts":"2026-04-29T15:42:45.603776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10770,"took":"78.986383ms","hash":747664407,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":27295744,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-29T15:42:45.603832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":747664407,"revision":10770,"compact-revision":8278} {"level":"info","ts":"2026-04-29T15:45:23.167242Z","caller":"traceutil/trace.go:171","msg":"trace[564502680] transaction","detail":"{read_only:false; response_revision:13949; number_of_response:1; }","duration":"171.980809ms","start":"2026-04-29T15:45:22.995203Z","end":"2026-04-29T15:45:23.167184Z","steps":["trace[564502680] 'process raft request' (duration: 171.777253ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:45:44.164371Z","caller":"traceutil/trace.go:171","msg":"trace[1347080794] transaction","detail":"{read_only:false; response_revision:14248; number_of_response:1; }","duration":"179.480842ms","start":"2026-04-29T15:45:43.984858Z","end":"2026-04-29T15:45:44.164339Z","steps":["trace[1347080794] 'process raft request' (duration: 179.217184ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:45:44.455733Z","caller":"traceutil/trace.go:171","msg":"trace[479735520] transaction","detail":"{read_only:false; response_revision:14249; number_of_response:1; }","duration":"331.393832ms","start":"2026-04-29T15:45:44.124297Z","end":"2026-04-29T15:45:44.455691Z","steps":["trace[479735520] 'process raft request' (duration: 331.124185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:45:44.455965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:45:44.124271Z","time spent":"331.580927ms","remote":"127.0.0.1:34750","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-29T15:45:44.455987Z","caller":"traceutil/trace.go:171","msg":"trace[121655179] transaction","detail":"{read_only:false; response_revision:14250; number_of_response:1; }","duration":"307.105215ms","start":"2026-04-29T15:45:44.148829Z","end":"2026-04-29T15:45:44.455934Z","steps":["trace[121655179] 'process raft request' (duration: 306.789225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:45:44.456236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:45:44.148816Z","time spent":"307.29549ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T15:47:01.38151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.524878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-29T15:47:01.381622Z","caller":"traceutil/trace.go:171","msg":"trace[851863834] linearizableReadLoop","detail":"{readStateIndex:15523; appliedIndex:15522; }","duration":"372.093744ms","start":"2026-04-29T15:47:01.009512Z","end":"2026-04-29T15:47:01.381606Z","steps":["trace[851863834] 'read index received' (duration: 154.411472ms)","trace[851863834] 'applied index is now lower than readState.Index' (duration: 217.680592ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:47:01.381852Z","caller":"traceutil/trace.go:171","msg":"trace[547711832] transaction","detail":"{read_only:false; response_revision:15008; number_of_response:1; }","duration":"480.300668ms","start":"2026-04-29T15:47:00.901537Z","end":"2026-04-29T15:47:01.381837Z","steps":["trace[547711832] 'process raft request' (duration: 262.356058ms)","trace[547711832] 'compare' (duration: 216.940121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:47:01.38192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:47:00.901514Z","time spent":"480.37067ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T15:47:01.382121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.610749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-29T15:47:01.382161Z","caller":"traceutil/trace.go:171","msg":"trace[1268438500] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:15008; }","duration":"372.688131ms","start":"2026-04-29T15:47:01.00946Z","end":"2026-04-29T15:47:01.382149Z","steps":["trace[1268438500] 'agreement among raft nodes before linearized reading' (duration: 372.608669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:47:01.382198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:47:01.009443Z","time spent":"372.746763ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-29T15:47:01.382367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.456547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:47:01.382401Z","caller":"traceutil/trace.go:171","msg":"trace[1385438938] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:15008; }","duration":"371.521759ms","start":"2026-04-29T15:47:01.010871Z","end":"2026-04-29T15:47:01.382393Z","steps":["trace[1385438938] 'agreement among raft nodes before linearized reading' (duration: 371.467948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:47:01.382431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:47:01.010853Z","time spent":"371.569451ms","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-29T15:47:01.382714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.596985ms","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-29T15:47:01.382752Z","caller":"traceutil/trace.go:171","msg":"trace[734994469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15008; }","duration":"173.667737ms","start":"2026-04-29T15:47:01.209074Z","end":"2026-04-29T15:47:01.382742Z","steps":["trace[734994469] 'agreement among raft nodes before linearized reading' (duration: 173.552544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:47:01.383018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.336526ms","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-29T15:47:01.383117Z","caller":"traceutil/trace.go:171","msg":"trace[1170736143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15008; }","duration":"186.47941ms","start":"2026-04-29T15:47:01.196616Z","end":"2026-04-29T15:47:01.383095Z","steps":["trace[1170736143] 'agreement among raft nodes before linearized reading' (duration: 186.163171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:47:01.383123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.011479ms","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-29T15:47:01.383218Z","caller":"traceutil/trace.go:171","msg":"trace[78134040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15008; }","duration":"179.150913ms","start":"2026-04-29T15:47:01.204044Z","end":"2026-04-29T15:47:01.383195Z","steps":["trace[78134040] 'agreement among raft nodes before linearized reading' (duration: 178.916666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:47:01.383222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.821968ms","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-29T15:47:01.383277Z","caller":"traceutil/trace.go:171","msg":"trace[402806810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15008; }","duration":"181.91135ms","start":"2026-04-29T15:47:01.201349Z","end":"2026-04-29T15:47:01.38326Z","steps":["trace[402806810] 'agreement among raft nodes before linearized reading' (duration: 181.779277ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:47:01.608422Z","caller":"traceutil/trace.go:171","msg":"trace[960958102] transaction","detail":"{read_only:false; response_revision:15010; number_of_response:1; }","duration":"212.9823ms","start":"2026-04-29T15:47:01.395416Z","end":"2026-04-29T15:47:01.608398Z","steps":["trace[960958102] 'process raft request' (duration: 212.893048ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:47:01.608691Z","caller":"traceutil/trace.go:171","msg":"trace[1336952107] linearizableReadLoop","detail":"{readStateIndex:15527; appliedIndex:15523; }","duration":"148.600287ms","start":"2026-04-29T15:47:01.460075Z","end":"2026-04-29T15:47:01.608675Z","steps":["trace[1336952107] 'read index received' (duration: 91.963783ms)","trace[1336952107] 'applied index is now lower than readState.Index' (duration: 56.635204ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:47:01.60874Z","caller":"traceutil/trace.go:171","msg":"trace[1771088963] transaction","detail":"{read_only:false; response_revision:15011; number_of_response:1; }","duration":"213.213606ms","start":"2026-04-29T15:47:01.395478Z","end":"2026-04-29T15:47:01.608692Z","steps":["trace[1771088963] 'process raft request' (duration: 212.880147ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:47:01.608785Z","caller":"traceutil/trace.go:171","msg":"trace[1743783077] transaction","detail":"{read_only:false; response_revision:15012; number_of_response:1; }","duration":"210.250482ms","start":"2026-04-29T15:47:01.398478Z","end":"2026-04-29T15:47:01.608728Z","steps":["trace[1743783077] 'process raft request' (duration: 210.088858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:47:01.60893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.844805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-04-29T15:47:01.608938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.741762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T15:47:01.608971Z","caller":"traceutil/trace.go:171","msg":"trace[331886476] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15012; }","duration":"148.925667ms","start":"2026-04-29T15:47:01.460033Z","end":"2026-04-29T15:47:01.608958Z","steps":["trace[331886476] 'agreement among raft nodes before linearized reading' (duration: 148.760801ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:47:01.608982Z","caller":"traceutil/trace.go:171","msg":"trace[1568589039] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:15012; }","duration":"100.866746ms","start":"2026-04-29T15:47:01.508101Z","end":"2026-04-29T15:47:01.608968Z","steps":["trace[1568589039] 'agreement among raft nodes before linearized reading' (duration: 100.774453ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:47:01.60847Z","caller":"traceutil/trace.go:171","msg":"trace[131985429] transaction","detail":"{read_only:false; response_revision:15009; number_of_response:1; }","duration":"212.994171ms","start":"2026-04-29T15:47:01.395419Z","end":"2026-04-29T15:47:01.608413Z","steps":["trace[131985429] 'process raft request' (duration: 156.604694ms)","trace[131985429] 'compare' (duration: 56.14349ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:47:45.529431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12845} {"level":"info","ts":"2026-04-29T15:47:45.602804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12845,"took":"70.579918ms","hash":464310046,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":29876224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-29T15:47:45.602865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":464310046,"revision":12845,"compact-revision":10770} {"level":"info","ts":"2026-04-29T15:52:45.542489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15290} {"level":"info","ts":"2026-04-29T15:52:45.634712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15290,"took":"89.445887ms","hash":4259053406,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":31653888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-29T15:52:45.63479Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4259053406,"revision":15290,"compact-revision":12845} {"level":"warn","ts":"2026-04-29T15:52:46.953519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.142208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T15:52:46.953613Z","caller":"traceutil/trace.go:171","msg":"trace[2127963682] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:17580; }","duration":"138.293232ms","start":"2026-04-29T15:52:46.8153Z","end":"2026-04-29T15:52:46.953593Z","steps":["trace[2127963682] 'count revisions from in-memory index tree' (duration: 138.060625ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:52:47.275192Z","caller":"traceutil/trace.go:171","msg":"trace[1163842174] transaction","detail":"{read_only:false; response_revision:17581; number_of_response:1; }","duration":"306.729788ms","start":"2026-04-29T15:52:46.968448Z","end":"2026-04-29T15:52:47.275178Z","steps":["trace[1163842174] 'process raft request' (duration: 306.635455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:52:47.275296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T15:52:46.968417Z","time spent":"306.826661ms","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20895,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T15:52:47.449352Z","caller":"traceutil/trace.go:171","msg":"trace[768121519] transaction","detail":"{read_only:false; response_revision:17582; number_of_response:1; }","duration":"188.417593ms","start":"2026-04-29T15:52:47.260915Z","end":"2026-04-29T15:52:47.449333Z","steps":["trace[768121519] 'process raft request' (duration: 182.011211ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:52:47.44948Z","caller":"traceutil/trace.go:171","msg":"trace[842134403] linearizableReadLoop","detail":"{readStateIndex:18185; appliedIndex:18183; }","duration":"184.637416ms","start":"2026-04-29T15:52:47.264823Z","end":"2026-04-29T15:52:47.44946Z","steps":["trace[842134403] 'read index received' (duration: 10.407355ms)","trace[842134403] 'applied index is now lower than readState.Index' (duration: 174.228581ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T15:52:47.449573Z","caller":"traceutil/trace.go:171","msg":"trace[1082036529] transaction","detail":"{read_only:false; response_revision:17583; number_of_response:1; }","duration":"186.8887ms","start":"2026-04-29T15:52:47.262652Z","end":"2026-04-29T15:52:47.449541Z","steps":["trace[1082036529] 'process raft request' (duration: 186.622512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:52:47.44972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.879632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-29T15:52:47.44977Z","caller":"traceutil/trace.go:171","msg":"trace[1180294829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17583; }","duration":"184.944625ms","start":"2026-04-29T15:52:47.2648Z","end":"2026-04-29T15:52:47.449744Z","steps":["trace[1180294829] 'agreement among raft nodes before linearized reading' (duration: 184.801621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:52:47.449806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.029505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-29T15:52:47.449816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.9678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-29T15:52:47.449847Z","caller":"traceutil/trace.go:171","msg":"trace[857892133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17583; }","duration":"182.020421ms","start":"2026-04-29T15:52:47.267817Z","end":"2026-04-29T15:52:47.449838Z","steps":["trace[857892133] 'agreement among raft nodes before linearized reading' (duration: 181.901538ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:52:47.449849Z","caller":"traceutil/trace.go:171","msg":"trace[349067283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17583; }","duration":"180.089136ms","start":"2026-04-29T15:52:47.269745Z","end":"2026-04-29T15:52:47.449834Z","steps":["trace[349067283] 'agreement among raft nodes before linearized reading' (duration: 179.976253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:52:47.449954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.547278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-29T15:52:47.449976Z","caller":"traceutil/trace.go:171","msg":"trace[1276455234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17583; }","duration":"181.580699ms","start":"2026-04-29T15:52:47.268389Z","end":"2026-04-29T15:52:47.449969Z","steps":["trace[1276455234] 'agreement among raft nodes before linearized reading' (duration: 181.494826ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:56:18.598492Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T15:56:18.604975Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-29T15:56:18.605166Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-29T15:57:45.550006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17574} {"level":"info","ts":"2026-04-29T15:57:45.645209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17574,"took":"92.577492ms","hash":3218574760,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":34013184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-29T15:57:45.645279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3218574760,"revision":17574,"compact-revision":15290} {"level":"info","ts":"2026-04-29T15:58:27.296037Z","caller":"traceutil/trace.go:171","msg":"trace[2058612832] transaction","detail":"{read_only:false; response_revision:20234; number_of_response:1; }","duration":"138.838062ms","start":"2026-04-29T15:58:27.157176Z","end":"2026-04-29T15:58:27.296014Z","steps":["trace[2058612832] 'process raft request' (duration: 138.690527ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:58:27.445667Z","caller":"traceutil/trace.go:171","msg":"trace[698207629] transaction","detail":"{read_only:false; response_revision:20235; number_of_response:1; }","duration":"286.920997ms","start":"2026-04-29T15:58:27.158723Z","end":"2026-04-29T15:58:27.445644Z","steps":["trace[698207629] 'process raft request' (duration: 286.739972ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:58:27.445772Z","caller":"traceutil/trace.go:171","msg":"trace[9352193] linearizableReadLoop","detail":"{readStateIndex:20923; appliedIndex:20921; }","duration":"286.426032ms","start":"2026-04-29T15:58:27.159318Z","end":"2026-04-29T15:58:27.445744Z","steps":["trace[9352193] 'read index received' (duration: 136.603038ms)","trace[9352193] 'applied index is now lower than readState.Index' (duration: 149.820503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T15:58:27.44598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.353924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-29T15:58:27.44601Z","caller":"traceutil/trace.go:171","msg":"trace[1537971429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20236; }","duration":"111.430216ms","start":"2026-04-29T15:58:27.334573Z","end":"2026-04-29T15:58:27.446003Z","steps":["trace[1537971429] 'agreement among raft nodes before linearized reading' (duration: 111.325403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:58:27.446029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.149986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-29T15:58:27.446014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.673388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T15:58:27.446071Z","caller":"traceutil/trace.go:171","msg":"trace[391248618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20236; }","duration":"285.248508ms","start":"2026-04-29T15:58:27.160812Z","end":"2026-04-29T15:58:27.446061Z","steps":["trace[391248618] 'agreement among raft nodes before linearized reading' (duration: 285.081774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T15:58:27.446041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.693516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T15:58:27.446108Z","caller":"traceutil/trace.go:171","msg":"trace[1243744264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20236; }","duration":"286.799592ms","start":"2026-04-29T15:58:27.159286Z","end":"2026-04-29T15:58:27.446086Z","steps":["trace[1243744264] 'agreement among raft nodes before linearized reading' (duration: 286.542575ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:58:27.446142Z","caller":"traceutil/trace.go:171","msg":"trace[1465544823] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:20236; }","duration":"175.866881ms","start":"2026-04-29T15:58:27.270254Z","end":"2026-04-29T15:58:27.446121Z","steps":["trace[1465544823] 'agreement among raft nodes before linearized reading' (duration: 175.659475ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:58:27.446756Z","caller":"traceutil/trace.go:171","msg":"trace[1827097392] transaction","detail":"{read_only:false; response_revision:20236; number_of_response:1; }","duration":"250.414983ms","start":"2026-04-29T15:58:27.195365Z","end":"2026-04-29T15:58:27.44578Z","steps":["trace[1827097392] 'process raft request' (duration: 250.223707ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T15:58:27.599449Z","caller":"traceutil/trace.go:171","msg":"trace[1794183213] transaction","detail":"{read_only:false; response_revision:20239; number_of_response:1; }","duration":"138.366889ms","start":"2026-04-29T15:58:27.461053Z","end":"2026-04-29T15:58:27.59942Z","steps":["trace[1794183213] 'process raft request' (duration: 130.130186ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:02:45.556307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20023} {"level":"info","ts":"2026-04-29T16:02:45.662434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20023,"took":"102.060831ms","hash":2398745716,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":41189376,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-29T16:02:45.662521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2398745716,"revision":20023,"compact-revision":17574} {"level":"info","ts":"2026-04-29T16:04:06.130029Z","caller":"traceutil/trace.go:171","msg":"trace[2045458289] transaction","detail":"{read_only:false; response_revision:23868; number_of_response:1; }","duration":"241.411198ms","start":"2026-04-29T16:04:05.888589Z","end":"2026-04-29T16:04:06.13Z","steps":["trace[2045458289] 'process raft request' (duration: 241.230091ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:04:06.13379Z","caller":"traceutil/trace.go:171","msg":"trace[79399575] transaction","detail":"{read_only:false; response_revision:23869; number_of_response:1; }","duration":"234.727998ms","start":"2026-04-29T16:04:05.899042Z","end":"2026-04-29T16:04:06.13377Z","steps":["trace[79399575] 'process raft request' (duration: 234.592304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:04:06.782781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.590091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:04:06.782861Z","caller":"traceutil/trace.go:171","msg":"trace[147354578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23870; }","duration":"141.711794ms","start":"2026-04-29T16:04:06.641134Z","end":"2026-04-29T16:04:06.782846Z","steps":["trace[147354578] 'range keys from in-memory index tree' (duration: 141.537799ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.388181Z","caller":"traceutil/trace.go:171","msg":"trace[1041839279] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"298.895755ms","start":"2026-04-29T16:05:37.089264Z","end":"2026-04-29T16:05:37.38816Z","steps":["trace[1041839279] 'process raft request' (duration: 298.748831ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.446569Z","caller":"traceutil/trace.go:171","msg":"trace[547937018] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"151.271469ms","start":"2026-04-29T16:05:37.29528Z","end":"2026-04-29T16:05:37.446552Z","steps":["trace[547937018] 'process raft request' (duration: 151.099874ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.446593Z","caller":"traceutil/trace.go:171","msg":"trace[31084474] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"214.963966ms","start":"2026-04-29T16:05:37.23149Z","end":"2026-04-29T16:05:37.446454Z","steps":["trace[31084474] 'process raft request' (duration: 214.800691ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.446595Z","caller":"traceutil/trace.go:171","msg":"trace[102921917] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"299.258504ms","start":"2026-04-29T16:05:37.147305Z","end":"2026-04-29T16:05:37.446564Z","steps":["trace[102921917] 'process raft request' (duration: 269.002221ms)","trace[102921917] 'compare' (duration: 29.80186ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:37.533001Z","caller":"traceutil/trace.go:171","msg":"trace[1691318116] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"117.62357ms","start":"2026-04-29T16:05:37.415357Z","end":"2026-04-29T16:05:37.532981Z","steps":["trace[1691318116] 'process raft request' (duration: 117.560658ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.533209Z","caller":"traceutil/trace.go:171","msg":"trace[2073218489] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"129.603267ms","start":"2026-04-29T16:05:37.403571Z","end":"2026-04-29T16:05:37.533174Z","steps":["trace[2073218489] 'process raft request' (duration: 129.244527ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.734289Z","caller":"traceutil/trace.go:171","msg":"trace[1296384363] linearizableReadLoop","detail":"{readStateIndex:25632; appliedIndex:25631; }","duration":"245.525488ms","start":"2026-04-29T16:05:37.488721Z","end":"2026-04-29T16:05:37.734247Z","steps":["trace[1296384363] 'read index received' (duration: 44.256309ms)","trace[1296384363] 'applied index is now lower than readState.Index' (duration: 201.267219ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:37.734497Z","caller":"traceutil/trace.go:171","msg":"trace[2015616338] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"284.441266ms","start":"2026-04-29T16:05:37.450021Z","end":"2026-04-29T16:05:37.734463Z","steps":["trace[2015616338] 'process raft request' (duration: 247.899945ms)","trace[2015616338] 'compare' (duration: 36.115599ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:37.73462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.856458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-29T16:05:37.734697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.724347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-29T16:05:37.73474Z","caller":"traceutil/trace.go:171","msg":"trace[535502246] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24816; }","duration":"246.012702ms","start":"2026-04-29T16:05:37.4887Z","end":"2026-04-29T16:05:37.734713Z","steps":["trace[535502246] 'agreement among raft nodes before linearized reading' (duration: 245.760995ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.734789Z","caller":"traceutil/trace.go:171","msg":"trace[172368290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24816; }","duration":"114.877052ms","start":"2026-04-29T16:05:37.619889Z","end":"2026-04-29T16:05:37.734766Z","steps":["trace[172368290] 'agreement among raft nodes before linearized reading' (duration: 114.681756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:37.734798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.947699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-29T16:05:37.734791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.891694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-29T16:05:37.734853Z","caller":"traceutil/trace.go:171","msg":"trace[1469299905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24816; }","duration":"117.044052ms","start":"2026-04-29T16:05:37.617798Z","end":"2026-04-29T16:05:37.734842Z","steps":["trace[1469299905] 'agreement among raft nodes before linearized reading' (duration: 116.877237ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.734867Z","caller":"traceutil/trace.go:171","msg":"trace[62442548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24816; }","duration":"153.007157ms","start":"2026-04-29T16:05:37.581844Z","end":"2026-04-29T16:05:37.734851Z","steps":["trace[62442548] 'agreement among raft nodes before linearized reading' (duration: 152.649477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:37.993108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.231348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:37.993348Z","caller":"traceutil/trace.go:171","msg":"trace[1009917300] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"254.165823ms","start":"2026-04-29T16:05:37.739167Z","end":"2026-04-29T16:05:37.993333Z","steps":["trace[1009917300] 'process raft request' (duration: 254.037019ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.99334Z","caller":"traceutil/trace.go:171","msg":"trace[1048854511] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"254.413429ms","start":"2026-04-29T16:05:37.738899Z","end":"2026-04-29T16:05:37.993312Z","steps":["trace[1048854511] 'process raft request' (duration: 136.878273ms)","trace[1048854511] 'compare' (duration: 117.004531ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:37.993425Z","caller":"traceutil/trace.go:171","msg":"trace[1682700494] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"187.81034ms","start":"2026-04-29T16:05:37.805598Z","end":"2026-04-29T16:05:37.993408Z","steps":["trace[1682700494] 'process raft request' (duration: 187.769919ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.993437Z","caller":"traceutil/trace.go:171","msg":"trace[1887913282] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"253.453993ms","start":"2026-04-29T16:05:37.73996Z","end":"2026-04-29T16:05:37.993414Z","steps":["trace[1887913282] 'process raft request' (duration: 253.32884ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:37.993468Z","caller":"traceutil/trace.go:171","msg":"trace[1745638839] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"252.596149ms","start":"2026-04-29T16:05:37.740862Z","end":"2026-04-29T16:05:37.993458Z","steps":["trace[1745638839] 'process raft request' (duration: 252.462535ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:43.921778Z","caller":"traceutil/trace.go:171","msg":"trace[1921453160] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"449.027602ms","start":"2026-04-29T16:05:43.472517Z","end":"2026-04-29T16:05:43.921544Z","steps":["trace[1921453160] 'process raft request' (duration: 448.780125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:43.921939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.472502Z","time spent":"449.352531ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.23741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.410027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:44.237841Z","caller":"traceutil/trace.go:171","msg":"trace[1440103533] linearizableReadLoop","detail":"{readStateIndex:25681; appliedIndex:25677; }","duration":"602.4449ms","start":"2026-04-29T16:05:43.635381Z","end":"2026-04-29T16:05:44.237826Z","steps":["trace[1440103533] 'read index received' (duration: 286.037591ms)","trace[1440103533] 'applied index is now lower than readState.Index' (duration: 316.406259ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:44.237906Z","caller":"traceutil/trace.go:171","msg":"trace[819518412] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"763.698071ms","start":"2026-04-29T16:05:43.474165Z","end":"2026-04-29T16:05:44.237863Z","steps":["trace[819518412] 'process raft request' (duration: 763.425014ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:44.238019Z","caller":"traceutil/trace.go:171","msg":"trace[1348580709] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"764.949747ms","start":"2026-04-29T16:05:43.473027Z","end":"2026-04-29T16:05:44.237977Z","steps":["trace[1348580709] 'process raft request' (duration: 609.852919ms)","trace[1348580709] 'compare' (duration: 153.449871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:44.238211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.474146Z","time spent":"763.887137ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.238291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.473004Z","time spent":"765.119321ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.238407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.032767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:44.237983Z","caller":"traceutil/trace.go:171","msg":"trace[1863291818] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"683.486117ms","start":"2026-04-29T16:05:43.554486Z","end":"2026-04-29T16:05:44.237973Z","steps":["trace[1863291818] 'process raft request' (duration: 683.256471ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:44.238498Z","caller":"traceutil/trace.go:171","msg":"trace[854147826] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"226.184543ms","start":"2026-04-29T16:05:44.012307Z","end":"2026-04-29T16:05:44.238492Z","steps":["trace[854147826] 'process raft request' (duration: 225.471673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.238541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.554464Z","time spent":"684.037503ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.238316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.796355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-29T16:05:44.238473Z","caller":"traceutil/trace.go:171","msg":"trace[1542944656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24865; }","duration":"603.060488ms","start":"2026-04-29T16:05:43.635369Z","end":"2026-04-29T16:05:44.23843Z","steps":["trace[1542944656] 'agreement among raft nodes before linearized reading' (duration: 602.629776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.238689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.635231Z","time spent":"603.449878ms","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-29T16:05:44.238645Z","caller":"traceutil/trace.go:171","msg":"trace[643453336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24865; }","duration":"483.151885ms","start":"2026-04-29T16:05:43.755475Z","end":"2026-04-29T16:05:44.238627Z","steps":["trace[643453336] 'agreement among raft nodes before linearized reading' (duration: 482.599059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.238787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.755457Z","time spent":"483.31175ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-29T16:05:44.238928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.019084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T16:05:44.23896Z","caller":"traceutil/trace.go:171","msg":"trace[928654506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24865; }","duration":"217.053005ms","start":"2026-04-29T16:05:44.0219Z","end":"2026-04-29T16:05:44.238953Z","steps":["trace[928654506] 'agreement among raft nodes before linearized reading' (duration: 216.944392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.239178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.342796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-29T16:05:44.239222Z","caller":"traceutil/trace.go:171","msg":"trace[367851016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24865; }","duration":"221.420128ms","start":"2026-04-29T16:05:44.017795Z","end":"2026-04-29T16:05:44.239215Z","steps":["trace[367851016] 'agreement among raft nodes before linearized reading' (duration: 221.314655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.239374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.481287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-29T16:05:44.239398Z","caller":"traceutil/trace.go:171","msg":"trace[1559839124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24865; }","duration":"222.528019ms","start":"2026-04-29T16:05:44.016863Z","end":"2026-04-29T16:05:44.239391Z","steps":["trace[1559839124] 'agreement among raft nodes before linearized reading' (duration: 222.443897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.239563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.076352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-29T16:05:44.23969Z","caller":"traceutil/trace.go:171","msg":"trace[1029018404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24865; }","duration":"225.167063ms","start":"2026-04-29T16:05:44.014452Z","end":"2026-04-29T16:05:44.239619Z","steps":["trace[1029018404] 'agreement among raft nodes before linearized reading' (duration: 225.04142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.239801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.29587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:44.239829Z","caller":"traceutil/trace.go:171","msg":"trace[279210607] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24865; }","duration":"448.345322ms","start":"2026-04-29T16:05:43.791474Z","end":"2026-04-29T16:05:44.23982Z","steps":["trace[279210607] 'agreement among raft nodes before linearized reading' (duration: 448.299061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.239883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:43.791463Z","time spent":"448.410233ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:05:44.620878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.044078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:44.621219Z","caller":"traceutil/trace.go:171","msg":"trace[140519482] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"378.483931ms","start":"2026-04-29T16:05:44.242706Z","end":"2026-04-29T16:05:44.62119Z","steps":["trace[140519482] 'process raft request' (duration: 180.04312ms)","trace[140519482] 'compare' (duration: 197.933055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:44.621373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.242677Z","time spent":"378.628405ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.800588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.591669ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-29T16:05:44.801076Z","caller":"traceutil/trace.go:171","msg":"trace[1085757391] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"557.158803ms","start":"2026-04-29T16:05:44.243886Z","end":"2026-04-29T16:05:44.801044Z","steps":["trace[1085757391] 'process raft request' (duration: 556.806713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.801225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.243868Z","time spent":"557.277246ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.947548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.439262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:44.94785Z","caller":"traceutil/trace.go:171","msg":"trace[1972740361] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"470.549579ms","start":"2026-04-29T16:05:44.477283Z","end":"2026-04-29T16:05:44.947833Z","steps":["trace[1972740361] 'process raft request' (duration: 470.509107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.947927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.477276Z","time spent":"470.61769ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:44.948072Z","caller":"traceutil/trace.go:171","msg":"trace[752632566] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"703.208414ms","start":"2026-04-29T16:05:44.244829Z","end":"2026-04-29T16:05:44.948037Z","steps":["trace[752632566] 'process raft request' (duration: 702.855774ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:44.948151Z","caller":"traceutil/trace.go:171","msg":"trace[1951420080] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25682; }","duration":"526.557709ms","start":"2026-04-29T16:05:44.421585Z","end":"2026-04-29T16:05:44.948143Z","steps":["trace[1951420080] 'read index received' (duration: 1.198784ms)","trace[1951420080] 'applied index is now lower than readState.Index' (duration: 525.357865ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:44.948231Z","caller":"traceutil/trace.go:171","msg":"trace[1370886547] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"471.629238ms","start":"2026-04-29T16:05:44.476594Z","end":"2026-04-29T16:05:44.948223Z","steps":["trace[1370886547] 'process raft request' (duration: 471.165856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.948273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.244809Z","time spent":"703.353008ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.948417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.843607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:44.948463Z","caller":"traceutil/trace.go:171","msg":"trace[1342632052] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24873; }","duration":"526.95984ms","start":"2026-04-29T16:05:44.42149Z","end":"2026-04-29T16:05:44.94845Z","steps":["trace[1342632052] 'agreement among raft nodes before linearized reading' (duration: 526.867337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.948495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.421468Z","time spent":"527.018142ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"info","ts":"2026-04-29T16:05:44.948678Z","caller":"traceutil/trace.go:171","msg":"trace[1929107272] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"704.726386ms","start":"2026-04-29T16:05:44.243936Z","end":"2026-04-29T16:05:44.948663Z","steps":["trace[1929107272] 'process raft request' (duration: 557.09419ms)","trace[1929107272] 'compare' (duration: 146.34202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:44.948753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.243932Z","time spent":"704.788147ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:44.948882Z","caller":"traceutil/trace.go:171","msg":"trace[1933058260] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"704.400237ms","start":"2026-04-29T16:05:44.244471Z","end":"2026-04-29T16:05:44.948872Z","steps":["trace[1933058260] 'process raft request' (duration: 703.168212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.948939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.244464Z","time spent":"704.447819ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:44.948121Z","caller":"traceutil/trace.go:171","msg":"trace[2035986688] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"671.287092ms","start":"2026-04-29T16:05:44.276824Z","end":"2026-04-29T16:05:44.948111Z","steps":["trace[2035986688] 'process raft request' (duration: 670.893811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:44.949101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.276798Z","time spent":"672.271811ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:44.948681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.317553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T16:05:44.94829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:44.476573Z","time spent":"471.68745ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:44.949203Z","caller":"traceutil/trace.go:171","msg":"trace[817458003] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24873; }","duration":"166.873509ms","start":"2026-04-29T16:05:44.782316Z","end":"2026-04-29T16:05:44.949189Z","steps":["trace[817458003] 'agreement among raft nodes before linearized reading' (duration: 166.333893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:45.439773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.49469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:45.439833Z","caller":"traceutil/trace.go:171","msg":"trace[764505831] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:24873; }","duration":"244.592392ms","start":"2026-04-29T16:05:45.195225Z","end":"2026-04-29T16:05:45.439817Z","steps":["trace[764505831] 'count revisions from in-memory index tree' (duration: 244.424968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:45.439835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.158968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:45.439916Z","caller":"traceutil/trace.go:171","msg":"trace[844416669] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24873; }","duration":"391.250531ms","start":"2026-04-29T16:05:45.048643Z","end":"2026-04-29T16:05:45.439893Z","steps":["trace[844416669] 'range keys from in-memory index tree' (duration: 391.072236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:45.439955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:45.048565Z","time spent":"391.381405ms","remote":"127.0.0.1:34934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-29T16:05:45.635506Z","caller":"traceutil/trace.go:171","msg":"trace[31193143] linearizableReadLoop","detail":"{readStateIndex:25692; appliedIndex:25691; }","duration":"178.24733ms","start":"2026-04-29T16:05:45.457232Z","end":"2026-04-29T16:05:45.635479Z","steps":["trace[31193143] 'read index received' (duration: 178.042414ms)","trace[31193143] 'applied index is now lower than readState.Index' (duration: 203.906µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:45.635586Z","caller":"traceutil/trace.go:171","msg":"trace[2042326828] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"190.839456ms","start":"2026-04-29T16:05:45.444694Z","end":"2026-04-29T16:05:45.635533Z","steps":["trace[2042326828] 'process raft request' (duration: 190.628631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:45.63573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.521368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-29T16:05:45.635765Z","caller":"traceutil/trace.go:171","msg":"trace[856590436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24874; }","duration":"178.662932ms","start":"2026-04-29T16:05:45.45709Z","end":"2026-04-29T16:05:45.635753Z","steps":["trace[856590436] 'agreement among raft nodes before linearized reading' (duration: 178.509177ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:45.857164Z","caller":"traceutil/trace.go:171","msg":"trace[552593090] linearizableReadLoop","detail":"{readStateIndex:25693; appliedIndex:25692; }","duration":"221.567282ms","start":"2026-04-29T16:05:45.635573Z","end":"2026-04-29T16:05:45.85714Z","steps":["trace[552593090] 'read index received' (duration: 186.716198ms)","trace[552593090] 'applied index is now lower than readState.Index' (duration: 34.850254ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:45.857188Z","caller":"traceutil/trace.go:171","msg":"trace[675107814] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"275.468064ms","start":"2026-04-29T16:05:45.58169Z","end":"2026-04-29T16:05:45.857158Z","steps":["trace[675107814] 'process raft request' (duration: 240.60221ms)","trace[675107814] 'compare' (duration: 34.744271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:45.857319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.738443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T16:05:45.857352Z","caller":"traceutil/trace.go:171","msg":"trace[164901418] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24875; }","duration":"325.830856ms","start":"2026-04-29T16:05:45.531511Z","end":"2026-04-29T16:05:45.857342Z","steps":["trace[164901418] 'agreement among raft nodes before linearized reading' (duration: 325.755873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:45.857384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:45.53149Z","time spent":"325.884546ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:05:45.857433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.030119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18585"} {"level":"info","ts":"2026-04-29T16:05:45.857457Z","caller":"traceutil/trace.go:171","msg":"trace[1559014476] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24875; }","duration":"220.085261ms","start":"2026-04-29T16:05:45.637365Z","end":"2026-04-29T16:05:45.85745Z","steps":["trace[1559014476] 'agreement among raft nodes before linearized reading' (duration: 220.001828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:45.857348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.873192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:45.857512Z","caller":"traceutil/trace.go:171","msg":"trace[1821109939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24875; }","duration":"221.054088ms","start":"2026-04-29T16:05:45.636444Z","end":"2026-04-29T16:05:45.857498Z","steps":["trace[1821109939] 'agreement among raft nodes before linearized reading' (duration: 220.873523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.168359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.775865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:46.1685Z","caller":"traceutil/trace.go:171","msg":"trace[42625223] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"241.991859ms","start":"2026-04-29T16:05:45.926484Z","end":"2026-04-29T16:05:46.168475Z","steps":["trace[42625223] 'process raft request' (duration: 80.008428ms)","trace[42625223] 'compare' (duration: 161.504398ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:46.662557Z","caller":"traceutil/trace.go:171","msg":"trace[1763635860] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"415.980058ms","start":"2026-04-29T16:05:46.246556Z","end":"2026-04-29T16:05:46.662536Z","steps":["trace[1763635860] 'process raft request' (duration: 415.849754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.662777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.246534Z","time spent":"416.163854ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:46.69472Z","caller":"traceutil/trace.go:171","msg":"trace[279124465] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"448.020623ms","start":"2026-04-29T16:05:46.246679Z","end":"2026-04-29T16:05:46.6947Z","steps":["trace[279124465] 'process raft request' (duration: 447.823578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.694889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.24665Z","time spent":"448.151896ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:46.695071Z","caller":"traceutil/trace.go:171","msg":"trace[779936761] linearizableReadLoop","detail":"{readStateIndex:25698; appliedIndex:25695; }","duration":"399.973117ms","start":"2026-04-29T16:05:46.295071Z","end":"2026-04-29T16:05:46.695045Z","steps":["trace[779936761] 'read index received' (duration: 368.265011ms)","trace[779936761] 'applied index is now lower than readState.Index' (duration: 31.707246ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:46.695115Z","caller":"traceutil/trace.go:171","msg":"trace[484985019] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"447.54591ms","start":"2026-04-29T16:05:46.247536Z","end":"2026-04-29T16:05:46.695082Z","steps":["trace[484985019] 'process raft request' (duration: 447.103328ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:46.695206Z","caller":"traceutil/trace.go:171","msg":"trace[1435832801] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"446.743837ms","start":"2026-04-29T16:05:46.248451Z","end":"2026-04-29T16:05:46.695195Z","steps":["trace[1435832801] 'process raft request' (duration: 446.458549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.695316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.24752Z","time spent":"447.700114ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:46.695321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.248439Z","time spent":"446.804089ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:46.695417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.630265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:46.696055Z","caller":"traceutil/trace.go:171","msg":"trace[808133979] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24880; }","duration":"317.296634ms","start":"2026-04-29T16:05:46.378747Z","end":"2026-04-29T16:05:46.696044Z","steps":["trace[808133979] 'agreement among raft nodes before linearized reading' (duration: 316.590364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.696108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.378726Z","time spent":"317.373456ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:05:46.695648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.580124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-zthl7.18aadee40085229a\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-04-29T16:05:46.696265Z","caller":"traceutil/trace.go:171","msg":"trace[1724532967] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-zthl7.18aadee40085229a; range_end:; response_count:1; response_revision:24880; }","duration":"401.227632ms","start":"2026-04-29T16:05:46.295021Z","end":"2026-04-29T16:05:46.696249Z","steps":["trace[1724532967] 'agreement among raft nodes before linearized reading' (duration: 400.528163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.695698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.287033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T16:05:46.696343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.295004Z","time spent":"401.322675ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":980,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-zthl7.18aadee40085229a\" "} {"level":"info","ts":"2026-04-29T16:05:46.696379Z","caller":"traceutil/trace.go:171","msg":"trace[1703356271] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24880; }","duration":"302.005063ms","start":"2026-04-29T16:05:46.394361Z","end":"2026-04-29T16:05:46.696366Z","steps":["trace[1703356271] 'agreement among raft nodes before linearized reading' (duration: 301.314673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:46.696451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.394338Z","time spent":"302.068155ms","remote":"127.0.0.1:49746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:05:47.018724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.751307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:47.019188Z","caller":"traceutil/trace.go:171","msg":"trace[480802293] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"321.053349ms","start":"2026-04-29T16:05:46.698034Z","end":"2026-04-29T16:05:47.019087Z","steps":["trace[480802293] 'process raft request' (duration: 148.852071ms)","trace[480802293] 'compare' (duration: 171.451547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:47.019328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.698015Z","time spent":"321.258695ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":939,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:47.234282Z","caller":"traceutil/trace.go:171","msg":"trace[2060902726] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"534.799181ms","start":"2026-04-29T16:05:46.699445Z","end":"2026-04-29T16:05:47.234244Z","steps":["trace[2060902726] 'process raft request' (duration: 479.726608ms)","trace[2060902726] 'compare' (duration: 54.906889ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:47.234369Z","caller":"traceutil/trace.go:171","msg":"trace[2138517203] linearizableReadLoop","detail":"{readStateIndex:25700; appliedIndex:25698; }","duration":"429.89434ms","start":"2026-04-29T16:05:46.804465Z","end":"2026-04-29T16:05:47.234359Z","steps":["trace[2138517203] 'read index received' (duration: 42.421477ms)","trace[2138517203] 'applied index is now lower than readState.Index' (duration: 387.472053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:47.234502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.699434Z","time spent":"534.961806ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:47.234506Z","caller":"traceutil/trace.go:171","msg":"trace[514962313] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"279.052884ms","start":"2026-04-29T16:05:46.955422Z","end":"2026-04-29T16:05:47.234475Z","steps":["trace[514962313] 'process raft request' (duration: 278.827787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.234582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.108657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-29T16:05:47.234616Z","caller":"traceutil/trace.go:171","msg":"trace[732931259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24884; }","duration":"430.196239ms","start":"2026-04-29T16:05:46.80441Z","end":"2026-04-29T16:05:47.234606Z","steps":["trace[732931259] 'agreement among raft nodes before linearized reading' (duration: 430.021094ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:47.234307Z","caller":"traceutil/trace.go:171","msg":"trace[1418886499] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"278.90985ms","start":"2026-04-29T16:05:46.955374Z","end":"2026-04-29T16:05:47.234284Z","steps":["trace[1418886499] 'process raft request' (duration: 278.834138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.234648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.804389Z","time spent":"430.25138ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-29T16:05:47.234846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.916116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"warn","ts":"2026-04-29T16:05:47.234836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.229727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:47.234874Z","caller":"traceutil/trace.go:171","msg":"trace[838509429] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24884; }","duration":"242.00074ms","start":"2026-04-29T16:05:46.992865Z","end":"2026-04-29T16:05:47.234866Z","steps":["trace[838509429] 'agreement among raft nodes before linearized reading' (duration: 241.888725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.234889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.553228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T16:05:47.234899Z","caller":"traceutil/trace.go:171","msg":"trace[594607727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24884; }","duration":"375.356242ms","start":"2026-04-29T16:05:46.85952Z","end":"2026-04-29T16:05:47.234877Z","steps":["trace[594607727] 'agreement among raft nodes before linearized reading' (duration: 375.218117ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:47.234932Z","caller":"traceutil/trace.go:171","msg":"trace[102775828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24884; }","duration":"280.599339ms","start":"2026-04-29T16:05:46.954319Z","end":"2026-04-29T16:05:47.234919Z","steps":["trace[102775828] 'agreement among raft nodes before linearized reading' (duration: 280.445384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.234952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:46.8595Z","time spent":"375.436235ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:05:47.235002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.6102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-29T16:05:47.235049Z","caller":"traceutil/trace.go:171","msg":"trace[386531898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24884; }","duration":"280.679021ms","start":"2026-04-29T16:05:46.954354Z","end":"2026-04-29T16:05:47.235033Z","steps":["trace[386531898] 'agreement among raft nodes before linearized reading' (duration: 280.500456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.235061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.35233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T16:05:47.235085Z","caller":"traceutil/trace.go:171","msg":"trace[94127758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24884; }","duration":"124.404481ms","start":"2026-04-29T16:05:47.110671Z","end":"2026-04-29T16:05:47.235075Z","steps":["trace[94127758] 'agreement among raft nodes before linearized reading' (duration: 124.36324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.235114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.554329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-ht9cs.18aadedb26029b54\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-04-29T16:05:47.235194Z","caller":"traceutil/trace.go:171","msg":"trace[2097980054] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-ht9cs.18aadedb26029b54; range_end:; response_count:1; response_revision:24884; }","duration":"212.666621ms","start":"2026-04-29T16:05:47.022506Z","end":"2026-04-29T16:05:47.235172Z","steps":["trace[2097980054] 'agreement among raft nodes before linearized reading' (duration: 212.400604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.235208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.920138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-29T16:05:47.235133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.097922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130208"} {"level":"info","ts":"2026-04-29T16:05:47.235254Z","caller":"traceutil/trace.go:171","msg":"trace[111192630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24884; }","duration":"280.98649ms","start":"2026-04-29T16:05:46.954254Z","end":"2026-04-29T16:05:47.23524Z","steps":["trace[111192630] 'agreement among raft nodes before linearized reading' (duration: 280.766334ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:47.235278Z","caller":"traceutil/trace.go:171","msg":"trace[1309462066] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24884; }","duration":"220.281337ms","start":"2026-04-29T16:05:47.014978Z","end":"2026-04-29T16:05:47.235259Z","steps":["trace[1309462066] 'agreement among raft nodes before linearized reading' (duration: 220.018409ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:47.466494Z","caller":"traceutil/trace.go:171","msg":"trace[796112870] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"228.986962ms","start":"2026-04-29T16:05:47.237472Z","end":"2026-04-29T16:05:47.466459Z","steps":["trace[796112870] 'process raft request' (duration: 216.036747ms)","trace[796112870] 'compare' (duration: 12.656727ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:47.719328Z","caller":"traceutil/trace.go:171","msg":"trace[548304708] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"480.981952ms","start":"2026-04-29T16:05:47.23832Z","end":"2026-04-29T16:05:47.719302Z","steps":["trace[548304708] 'process raft request' (duration: 480.791296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.719522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.238305Z","time spent":"481.100846ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:47.719711Z","caller":"traceutil/trace.go:171","msg":"trace[1075924594] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"480.454958ms","start":"2026-04-29T16:05:47.239216Z","end":"2026-04-29T16:05:47.719671Z","steps":["trace[1075924594] 'process raft request' (duration: 480.045126ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:47.719751Z","caller":"traceutil/trace.go:171","msg":"trace[2080642171] linearizableReadLoop","detail":"{readStateIndex:25704; appliedIndex:25702; }","duration":"481.246641ms","start":"2026-04-29T16:05:47.23848Z","end":"2026-04-29T16:05:47.719727Z","steps":["trace[2080642171] 'read index received' (duration: 215.039219ms)","trace[2080642171] 'applied index is now lower than readState.Index' (duration: 266.206452ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:47.719877Z","caller":"traceutil/trace.go:171","msg":"trace[742107080] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"479.670827ms","start":"2026-04-29T16:05:47.240195Z","end":"2026-04-29T16:05:47.719866Z","steps":["trace[742107080] 'process raft request' (duration: 479.475601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.71988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.239201Z","time spent":"480.590422ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:47.71989Z","caller":"traceutil/trace.go:171","msg":"trace[720707471] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"480.639713ms","start":"2026-04-29T16:05:47.239222Z","end":"2026-04-29T16:05:47.719862Z","steps":["trace[720707471] 'process raft request' (duration: 480.320315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.719994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.240182Z","time spent":"479.731198ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:47.720067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.23921Z","time spent":"480.766077ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:47.720626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.139305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:90 size:118802"} {"level":"info","ts":"2026-04-29T16:05:47.720685Z","caller":"traceutil/trace.go:171","msg":"trace[992530791] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:90; response_revision:24889; }","duration":"482.246658ms","start":"2026-04-29T16:05:47.238424Z","end":"2026-04-29T16:05:47.72067Z","steps":["trace[992530791] 'agreement among raft nodes before linearized reading' (duration: 481.5942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.720724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.238389Z","time spent":"482.32531ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":90,"response size":118825,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-29T16:05:47.93368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.104792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:05:47.933916Z","caller":"traceutil/trace.go:171","msg":"trace[742950035] linearizableReadLoop","detail":"{readStateIndex:25709; appliedIndex:25707; }","duration":"214.070131ms","start":"2026-04-29T16:05:47.719824Z","end":"2026-04-29T16:05:47.933894Z","steps":["trace[742950035] 'read index received' (duration: 61.66305ms)","trace[742950035] 'applied index is now lower than readState.Index' (duration: 152.405891ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:47.933944Z","caller":"traceutil/trace.go:171","msg":"trace[1993742715] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"292.316469ms","start":"2026-04-29T16:05:47.64161Z","end":"2026-04-29T16:05:47.933926Z","steps":["trace[1993742715] 'process raft request' (duration: 292.190575ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:47.933911Z","caller":"traceutil/trace.go:171","msg":"trace[1015902722] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"386.421025ms","start":"2026-04-29T16:05:47.547458Z","end":"2026-04-29T16:05:47.933879Z","steps":["trace[1015902722] 'process raft request' (duration: 233.996274ms)","trace[1015902722] 'compare' (duration: 151.904646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:05:47.934071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.503353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:47.934171Z","caller":"traceutil/trace.go:171","msg":"trace[1077732795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24891; }","duration":"298.682278ms","start":"2026-04-29T16:05:47.635477Z","end":"2026-04-29T16:05:47.934159Z","steps":["trace[1077732795] 'agreement among raft nodes before linearized reading' (duration: 298.469132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.934223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.380102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130208"} {"level":"info","ts":"2026-04-29T16:05:47.934287Z","caller":"traceutil/trace.go:171","msg":"trace[2010624423] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24891; }","duration":"207.485065ms","start":"2026-04-29T16:05:47.726787Z","end":"2026-04-29T16:05:47.934272Z","steps":["trace[2010624423] 'agreement among raft nodes before linearized reading' (duration: 207.255278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.934351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.569366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"warn","ts":"2026-04-29T16:05:47.934101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.547433Z","time spent":"386.59529ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:47.934434Z","caller":"traceutil/trace.go:171","msg":"trace[880151451] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24891; }","duration":"691.684349ms","start":"2026-04-29T16:05:47.242732Z","end":"2026-04-29T16:05:47.934416Z","steps":["trace[880151451] 'agreement among raft nodes before linearized reading' (duration: 691.249236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:47.934486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:47.242722Z","time spent":"691.754731ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-29T16:05:48.328178Z","caller":"traceutil/trace.go:171","msg":"trace[118446495] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"152.70712ms","start":"2026-04-29T16:05:48.175445Z","end":"2026-04-29T16:05:48.328152Z","steps":["trace[118446495] 'process raft request' (duration: 152.387071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:48.466554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.049065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:48.466652Z","caller":"traceutil/trace.go:171","msg":"trace[887934316] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24892; }","duration":"161.213839ms","start":"2026-04-29T16:05:48.305413Z","end":"2026-04-29T16:05:48.466627Z","steps":["trace[887934316] 'agreement among raft nodes before linearized reading' (duration: 22.695211ms)","trace[887934316] 'count revisions from in-memory index tree' (duration: 138.335053ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:49.028219Z","caller":"traceutil/trace.go:171","msg":"trace[1117488667] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25710; }","duration":"392.27534ms","start":"2026-04-29T16:05:48.635926Z","end":"2026-04-29T16:05:49.028201Z","steps":["trace[1117488667] 'read index received' (duration: 392.231639ms)","trace[1117488667] 'applied index is now lower than readState.Index' (duration: 42.721µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:05:49.028311Z","caller":"traceutil/trace.go:171","msg":"trace[28265749] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"413.459958ms","start":"2026-04-29T16:05:48.614843Z","end":"2026-04-29T16:05:49.028303Z","steps":["trace[28265749] 'process raft request' (duration: 413.243492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:49.02839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.614834Z","time spent":"413.494969ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:49.028499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.583958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:05:49.028525Z","caller":"traceutil/trace.go:171","msg":"trace[2042025665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24893; }","duration":"392.64782ms","start":"2026-04-29T16:05:48.63587Z","end":"2026-04-29T16:05:49.028518Z","steps":["trace[2042025665] 'agreement among raft nodes before linearized reading' (duration: 392.604718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:49.028543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.635849Z","time spent":"392.691311ms","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-29T16:05:49.050743Z","caller":"traceutil/trace.go:171","msg":"trace[1106337654] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"350.452839ms","start":"2026-04-29T16:05:48.700263Z","end":"2026-04-29T16:05:49.050716Z","steps":["trace[1106337654] 'process raft request' (duration: 350.381337ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:49.050949Z","caller":"traceutil/trace.go:171","msg":"trace[1168696317] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"350.210972ms","start":"2026-04-29T16:05:48.700728Z","end":"2026-04-29T16:05:49.050939Z","steps":["trace[1168696317] 'process raft request' (duration: 349.954335ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:49.05076Z","caller":"traceutil/trace.go:171","msg":"trace[2001729663] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"383.513083ms","start":"2026-04-29T16:05:48.667221Z","end":"2026-04-29T16:05:49.050734Z","steps":["trace[2001729663] 'process raft request' (duration: 383.263596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:49.051062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.700705Z","time spent":"350.287725ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:49.051175Z","caller":"traceutil/trace.go:171","msg":"trace[2140665914] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"348.806954ms","start":"2026-04-29T16:05:48.702356Z","end":"2026-04-29T16:05:49.051163Z","steps":["trace[2140665914] 'process raft request' (duration: 348.515625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:49.051171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.667193Z","time spent":"383.870663ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:49.051226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.702323Z","time spent":"348.878806ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:05:49.051238Z","caller":"traceutil/trace.go:171","msg":"trace[695969866] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"347.870577ms","start":"2026-04-29T16:05:48.703338Z","end":"2026-04-29T16:05:49.051208Z","steps":["trace[695969866] 'process raft request' (duration: 347.768714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:49.051304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.194577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T16:05:49.050948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.700237Z","time spent":"350.630854ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:49.051375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.882551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-29T16:05:49.051409Z","caller":"traceutil/trace.go:171","msg":"trace[855008091] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:24898; }","duration":"294.951493ms","start":"2026-04-29T16:05:48.756449Z","end":"2026-04-29T16:05:49.0514Z","steps":["trace[855008091] 'agreement among raft nodes before linearized reading' (duration: 294.84626ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:05:49.051405Z","caller":"traceutil/trace.go:171","msg":"trace[782884072] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24898; }","duration":"353.343821ms","start":"2026-04-29T16:05:48.698037Z","end":"2026-04-29T16:05:49.051381Z","steps":["trace[782884072] 'agreement among raft nodes before linearized reading' (duration: 353.201477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:05:49.051338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.703321Z","time spent":"347.968269ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:05:49.051457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:05:48.698016Z","time spent":"353.426233ms","remote":"127.0.0.1:35550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:06:04.424025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.586539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89c2be8e-e6fd-4efa-9c94-a6d30b14d685\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-04-29T16:06:04.424124Z","caller":"traceutil/trace.go:171","msg":"trace[185669683] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89c2be8e-e6fd-4efa-9c94-a6d30b14d685; range_end:; response_count:1; response_revision:25068; }","duration":"189.741453ms","start":"2026-04-29T16:06:04.234359Z","end":"2026-04-29T16:06:04.4241Z","steps":["trace[185669683] 'range keys from in-memory index tree' (duration: 189.469006ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.249202Z","caller":"traceutil/trace.go:171","msg":"trace[424207261] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25891; }","duration":"245.456497ms","start":"2026-04-29T16:06:05.003724Z","end":"2026-04-29T16:06:05.24918Z","steps":["trace[424207261] 'read index received' (duration: 245.254841ms)","trace[424207261] 'applied index is now lower than readState.Index' (duration: 200.596µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:05.249257Z","caller":"traceutil/trace.go:171","msg":"trace[1910415316] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"248.393009ms","start":"2026-04-29T16:06:05.00084Z","end":"2026-04-29T16:06:05.249233Z","steps":["trace[1910415316] 'process raft request' (duration: 248.143082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:05.249355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.612771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:05.249401Z","caller":"traceutil/trace.go:171","msg":"trace[850137997] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25070; }","duration":"245.672203ms","start":"2026-04-29T16:06:05.003716Z","end":"2026-04-29T16:06:05.249388Z","steps":["trace[850137997] 'agreement among raft nodes before linearized reading' (duration: 245.58854ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.471378Z","caller":"traceutil/trace.go:171","msg":"trace[1594960297] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"364.484525ms","start":"2026-04-29T16:06:05.106866Z","end":"2026-04-29T16:06:05.471351Z","steps":["trace[1594960297] 'process raft request' (duration: 327.645535ms)","trace[1594960297] 'compare' (duration: 36.496921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:05.47154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.106844Z","time spent":"364.613949ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:06:05.471685Z","caller":"traceutil/trace.go:171","msg":"trace[1530951975] linearizableReadLoop","detail":"{readStateIndex:25897; appliedIndex:25892; }","duration":"222.388685ms","start":"2026-04-29T16:06:05.24928Z","end":"2026-04-29T16:06:05.471669Z","steps":["trace[1530951975] 'read index received' (duration: 185.182245ms)","trace[1530951975] 'applied index is now lower than readState.Index' (duration: 37.20587ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:05.471697Z","caller":"traceutil/trace.go:171","msg":"trace[1482398537] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"357.149998ms","start":"2026-04-29T16:06:05.114505Z","end":"2026-04-29T16:06:05.471655Z","steps":["trace[1482398537] 'process raft request' (duration: 356.755827ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.471759Z","caller":"traceutil/trace.go:171","msg":"trace[589119580] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"349.560603ms","start":"2026-04-29T16:06:05.122189Z","end":"2026-04-29T16:06:05.47175Z","steps":["trace[589119580] 'process raft request' (duration: 349.44296ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.471728Z","caller":"traceutil/trace.go:171","msg":"trace[1763535685] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"353.841105ms","start":"2026-04-29T16:06:05.11786Z","end":"2026-04-29T16:06:05.471701Z","steps":["trace[1763535685] 'process raft request' (duration: 353.741422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:05.471841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.122183Z","time spent":"349.595135ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:05.471879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.114479Z","time spent":"357.298512ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:05.471899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.117854Z","time spent":"353.948348ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:05.472081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.876319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-04-29T16:06:05.472094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.816345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:05.472104Z","caller":"traceutil/trace.go:171","msg":"trace[2027830521] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25075; }","duration":"245.941091ms","start":"2026-04-29T16:06:05.226157Z","end":"2026-04-29T16:06:05.472098Z","steps":["trace[2027830521] 'agreement among raft nodes before linearized reading' (duration: 245.855289ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.472128Z","caller":"traceutil/trace.go:171","msg":"trace[1292631052] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25075; }","duration":"398.877496ms","start":"2026-04-29T16:06:05.073239Z","end":"2026-04-29T16:06:05.472117Z","steps":["trace[1292631052] 'agreement among raft nodes before linearized reading' (duration: 398.809515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:05.472161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.073203Z","time spent":"398.948408ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-04-29T16:06:05.472342Z","caller":"traceutil/trace.go:171","msg":"trace[303841943] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"356.749948ms","start":"2026-04-29T16:06:05.115575Z","end":"2026-04-29T16:06:05.472325Z","steps":["trace[303841943] 'process raft request' (duration: 355.958385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:05.47243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.115564Z","time spent":"356.81196ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:06:05.491278Z","caller":"traceutil/trace.go:171","msg":"trace[2097667652] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"125.767618ms","start":"2026-04-29T16:06:05.3655Z","end":"2026-04-29T16:06:05.491268Z","steps":["trace[2097667652] 'process raft request' (duration: 125.557222ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.49136Z","caller":"traceutil/trace.go:171","msg":"trace[684224550] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"111.202968ms","start":"2026-04-29T16:06:05.380153Z","end":"2026-04-29T16:06:05.491356Z","steps":["trace[684224550] 'process raft request' (duration: 110.953131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:05.491549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.323896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-29T16:06:05.491571Z","caller":"traceutil/trace.go:171","msg":"trace[688185421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25077; }","duration":"103.398678ms","start":"2026-04-29T16:06:05.388167Z","end":"2026-04-29T16:06:05.491566Z","steps":["trace[688185421] 'agreement among raft nodes before linearized reading' (duration: 103.351057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:05.49165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.859205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:05.49167Z","caller":"traceutil/trace.go:171","msg":"trace[1700715941] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25077; }","duration":"236.903345ms","start":"2026-04-29T16:06:05.254761Z","end":"2026-04-29T16:06:05.491664Z","steps":["trace[1700715941] 'agreement among raft nodes before linearized reading' (duration: 236.872044ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:05.837364Z","caller":"traceutil/trace.go:171","msg":"trace[1966376772] linearizableReadLoop","detail":"{readStateIndex:25901; appliedIndex:25900; }","duration":"282.654767ms","start":"2026-04-29T16:06:05.554663Z","end":"2026-04-29T16:06:05.837317Z","steps":["trace[1966376772] 'read index received' (duration: 241.951498ms)","trace[1966376772] 'applied index is now lower than readState.Index' (duration: 40.700869ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:05.837556Z","caller":"traceutil/trace.go:171","msg":"trace[1777664136] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"284.683994ms","start":"2026-04-29T16:06:05.552837Z","end":"2026-04-29T16:06:05.837521Z","steps":["trace[1777664136] 'process raft request' (duration: 243.77868ms)","trace[1777664136] 'compare' (duration: 40.427321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:05.837822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.109289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-04-29T16:06:05.837891Z","caller":"traceutil/trace.go:171","msg":"trace[1328386251] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25079; }","duration":"283.230162ms","start":"2026-04-29T16:06:05.554644Z","end":"2026-04-29T16:06:05.837875Z","steps":["trace[1328386251] 'agreement among raft nodes before linearized reading' (duration: 282.893683ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:06.116258Z","caller":"traceutil/trace.go:171","msg":"trace[1582051993] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"274.355472ms","start":"2026-04-29T16:06:05.841871Z","end":"2026-04-29T16:06:06.116227Z","steps":["trace[1582051993] 'process raft request' (duration: 234.098385ms)","trace[1582051993] 'compare' (duration: 40.078741ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:06.194628Z","caller":"traceutil/trace.go:171","msg":"trace[1691694573] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"352.598469ms","start":"2026-04-29T16:06:05.841995Z","end":"2026-04-29T16:06:06.194593Z","steps":["trace[1691694573] 'process raft request' (duration: 352.418164ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:06.194649Z","caller":"traceutil/trace.go:171","msg":"trace[515161096] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25901; }","duration":"341.537847ms","start":"2026-04-29T16:06:05.853084Z","end":"2026-04-29T16:06:06.194622Z","steps":["trace[515161096] 'read index received' (duration: 222.89954ms)","trace[515161096] 'applied index is now lower than readState.Index' (duration: 118.637057ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:06.194661Z","caller":"traceutil/trace.go:171","msg":"trace[74815673] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"351.716935ms","start":"2026-04-29T16:06:05.842905Z","end":"2026-04-29T16:06:06.194622Z","steps":["trace[74815673] 'process raft request' (duration: 351.55166ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:06.19476Z","caller":"traceutil/trace.go:171","msg":"trace[107529753] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"352.790116ms","start":"2026-04-29T16:06:05.841936Z","end":"2026-04-29T16:06:06.194726Z","steps":["trace[107529753] 'process raft request' (duration: 352.326902ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:06.194791Z","caller":"traceutil/trace.go:171","msg":"trace[219611275] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"351.406817ms","start":"2026-04-29T16:06:05.843265Z","end":"2026-04-29T16:06:06.194672Z","steps":["trace[219611275] 'process raft request' (duration: 351.239742ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:06.194794Z","caller":"traceutil/trace.go:171","msg":"trace[1305866096] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"199.757308ms","start":"2026-04-29T16:06:05.995Z","end":"2026-04-29T16:06:06.194758Z","steps":["trace[1305866096] 'process raft request' (duration: 199.567051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.19492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.841922Z","time spent":"352.924019ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:06.194919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.843258Z","time spent":"351.587912ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:06.194984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.84288Z","time spent":"351.851669ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:06.195119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.023191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-04-29T16:06:06.195459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.841972Z","time spent":"352.725974ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:06:06.195725Z","caller":"traceutil/trace.go:171","msg":"trace[1938054940] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25085; }","duration":"342.148545ms","start":"2026-04-29T16:06:05.85302Z","end":"2026-04-29T16:06:06.195169Z","steps":["trace[1938054940] 'agreement among raft nodes before linearized reading' (duration: 341.777585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.195825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:05.853006Z","time spent":"342.791993ms","remote":"127.0.0.1:34948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9627,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-04-29T16:06:06.541196Z","caller":"traceutil/trace.go:171","msg":"trace[353271219] linearizableReadLoop","detail":"{readStateIndex:25908; appliedIndex:25907; }","duration":"336.726862ms","start":"2026-04-29T16:06:06.204444Z","end":"2026-04-29T16:06:06.54117Z","steps":["trace[353271219] 'read index received' (duration: 336.583738ms)","trace[353271219] 'applied index is now lower than readState.Index' (duration: 141.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:06.541271Z","caller":"traceutil/trace.go:171","msg":"trace[510929574] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"339.045687ms","start":"2026-04-29T16:06:06.202185Z","end":"2026-04-29T16:06:06.54123Z","steps":["trace[510929574] 'process raft request' (duration: 338.814321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.541361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.902447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:06.541408Z","caller":"traceutil/trace.go:171","msg":"trace[1413676189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25086; }","duration":"336.986859ms","start":"2026-04-29T16:06:06.204407Z","end":"2026-04-29T16:06:06.541393Z","steps":["trace[1413676189] 'agreement among raft nodes before linearized reading' (duration: 336.890076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.541446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:06.202172Z","time spent":"339.171081ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":729,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:06.541452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:06.204398Z","time spent":"337.04209ms","remote":"127.0.0.1:41972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-29T16:06:06.560468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.882791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:06.560587Z","caller":"traceutil/trace.go:171","msg":"trace[314118597] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25087; }","duration":"346.023544ms","start":"2026-04-29T16:06:06.21454Z","end":"2026-04-29T16:06:06.560564Z","steps":["trace[314118597] 'agreement among raft nodes before linearized reading' (duration: 345.844839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.560633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:06.214532Z","time spent":"346.088896ms","remote":"127.0.0.1:49610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-04-29T16:06:06.560759Z","caller":"traceutil/trace.go:171","msg":"trace[1016398779] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"125.976495ms","start":"2026-04-29T16:06:06.434745Z","end":"2026-04-29T16:06:06.560721Z","steps":["trace[1016398779] 'process raft request' (duration: 125.455331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.561025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.033234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:06.561124Z","caller":"traceutil/trace.go:171","msg":"trace[294917091] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25087; }","duration":"302.230419ms","start":"2026-04-29T16:06:06.258869Z","end":"2026-04-29T16:06:06.561099Z","steps":["trace[294917091] 'agreement among raft nodes before linearized reading' (duration: 302.031253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:06.561189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:06.258841Z","time spent":"302.336852ms","remote":"127.0.0.1:34934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-29T16:06:13.086951Z","caller":"traceutil/trace.go:171","msg":"trace[271651773] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"110.491398ms","start":"2026-04-29T16:06:12.976419Z","end":"2026-04-29T16:06:13.08691Z","steps":["trace[271651773] 'process raft request' (duration: 110.104748ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:13.724965Z","caller":"traceutil/trace.go:171","msg":"trace[524213958] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"222.253732ms","start":"2026-04-29T16:06:13.502683Z","end":"2026-04-29T16:06:13.724937Z","steps":["trace[524213958] 'process raft request' (duration: 222.16057ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:13.724987Z","caller":"traceutil/trace.go:171","msg":"trace[798518416] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"225.913376ms","start":"2026-04-29T16:06:13.499036Z","end":"2026-04-29T16:06:13.72495Z","steps":["trace[798518416] 'process raft request' (duration: 225.585215ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:13.725308Z","caller":"traceutil/trace.go:171","msg":"trace[1086844382] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"221.693605ms","start":"2026-04-29T16:06:13.503572Z","end":"2026-04-29T16:06:13.725266Z","steps":["trace[1086844382] 'process raft request' (duration: 221.322235ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:13.725435Z","caller":"traceutil/trace.go:171","msg":"trace[202408075] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25980; }","duration":"144.788496ms","start":"2026-04-29T16:06:13.580601Z","end":"2026-04-29T16:06:13.725389Z","steps":["trace[202408075] 'read index received' (duration: 144.074365ms)","trace[202408075] 'applied index is now lower than readState.Index' (duration: 713.171µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:13.725495Z","caller":"traceutil/trace.go:171","msg":"trace[248409925] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"214.77215ms","start":"2026-04-29T16:06:13.510708Z","end":"2026-04-29T16:06:13.72548Z","steps":["trace[248409925] 'process raft request' (duration: 214.604476ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:13.725516Z","caller":"traceutil/trace.go:171","msg":"trace[1288455511] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"215.225353ms","start":"2026-04-29T16:06:13.510253Z","end":"2026-04-29T16:06:13.725478Z","steps":["trace[1288455511] 'process raft request' (duration: 214.872193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:13.725668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.056944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-29T16:06:13.72572Z","caller":"traceutil/trace.go:171","msg":"trace[1640951282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25161; }","duration":"145.177677ms","start":"2026-04-29T16:06:13.580528Z","end":"2026-04-29T16:06:13.725706Z","steps":["trace[1640951282] 'agreement among raft nodes before linearized reading' (duration: 145.006372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:31.180111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.08105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:06:31.180252Z","caller":"traceutil/trace.go:171","msg":"trace[1219760267] linearizableReadLoop","detail":"{readStateIndex:26132; appliedIndex:26131; }","duration":"247.67121ms","start":"2026-04-29T16:06:30.932558Z","end":"2026-04-29T16:06:31.180229Z","steps":["trace[1219760267] 'read index received' (duration: 111.386104ms)","trace[1219760267] 'applied index is now lower than readState.Index' (duration: 136.283166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:31.180533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.965338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-04-29T16:06:31.180592Z","caller":"traceutil/trace.go:171","msg":"trace[1362350502] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25303; }","duration":"248.058341ms","start":"2026-04-29T16:06:30.932518Z","end":"2026-04-29T16:06:31.180577Z","steps":["trace[1362350502] 'agreement among raft nodes before linearized reading' (duration: 247.774973ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:31.18124Z","caller":"traceutil/trace.go:171","msg":"trace[741165033] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"260.439399ms","start":"2026-04-29T16:06:30.920762Z","end":"2026-04-29T16:06:31.181202Z","steps":["trace[741165033] 'process raft request' (duration: 123.164295ms)","trace[741165033] 'compare' (duration: 135.818283ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:31.183039Z","caller":"traceutil/trace.go:171","msg":"trace[300640454] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"234.856397ms","start":"2026-04-29T16:06:30.948159Z","end":"2026-04-29T16:06:31.183015Z","steps":["trace[300640454] 'process raft request' (duration: 234.735483ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:31.342691Z","caller":"traceutil/trace.go:171","msg":"trace[522574930] linearizableReadLoop","detail":"{readStateIndex:26134; appliedIndex:26133; }","duration":"150.648621ms","start":"2026-04-29T16:06:31.192012Z","end":"2026-04-29T16:06:31.34266Z","steps":["trace[522574930] 'read index received' (duration: 126.794738ms)","trace[522574930] 'applied index is now lower than readState.Index' (duration: 23.852433ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:31.342851Z","caller":"traceutil/trace.go:171","msg":"trace[449574223] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"154.195962ms","start":"2026-04-29T16:06:31.188582Z","end":"2026-04-29T16:06:31.342778Z","steps":["trace[449574223] 'process raft request' (duration: 130.191664ms)","trace[449574223] 'compare' (duration: 23.722499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:31.342976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.9479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10671"} {"level":"info","ts":"2026-04-29T16:06:31.343044Z","caller":"traceutil/trace.go:171","msg":"trace[666444644] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25305; }","duration":"151.052983ms","start":"2026-04-29T16:06:31.191974Z","end":"2026-04-29T16:06:31.343027Z","steps":["trace[666444644] 'agreement among raft nodes before linearized reading' (duration: 150.804206ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:31.732164Z","caller":"traceutil/trace.go:171","msg":"trace[2130642706] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"145.483405ms","start":"2026-04-29T16:06:31.586643Z","end":"2026-04-29T16:06:31.732127Z","steps":["trace[2130642706] 'process raft request' (duration: 144.732373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.222212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.555683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:06:32.222321Z","caller":"traceutil/trace.go:171","msg":"trace[813262853] linearizableReadLoop","detail":"{readStateIndex:26141; appliedIndex:26140; }","duration":"267.665302ms","start":"2026-04-29T16:06:31.954634Z","end":"2026-04-29T16:06:32.222299Z","steps":["trace[813262853] 'read index received' (duration: 63.912694ms)","trace[813262853] 'applied index is now lower than readState.Index' (duration: 203.750978ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:32.222405Z","caller":"traceutil/trace.go:171","msg":"trace[1678439224] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"336.63813ms","start":"2026-04-29T16:06:31.885733Z","end":"2026-04-29T16:06:32.222371Z","steps":["trace[1678439224] 'process raft request' (duration: 132.828969ms)","trace[1678439224] 'compare' (duration: 203.370588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:32.222579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.939511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-29T16:06:32.222617Z","caller":"traceutil/trace.go:171","msg":"trace[258072056] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25312; }","duration":"268.012553ms","start":"2026-04-29T16:06:31.954593Z","end":"2026-04-29T16:06:32.222606Z","steps":["trace[258072056] 'agreement among raft nodes before linearized reading' (duration: 267.756356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.222731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:31.885533Z","time spent":"336.936009ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:32.521314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.978781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:06:32.521396Z","caller":"traceutil/trace.go:171","msg":"trace[234137286] linearizableReadLoop","detail":"{readStateIndex:26142; appliedIndex:26141; }","duration":"240.144466ms","start":"2026-04-29T16:06:32.281238Z","end":"2026-04-29T16:06:32.521382Z","steps":["trace[234137286] 'read index received' (duration: 84.033611ms)","trace[234137286] 'applied index is now lower than readState.Index' (duration: 156.110015ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:32.521546Z","caller":"traceutil/trace.go:171","msg":"trace[1660027729] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"273.266121ms","start":"2026-04-29T16:06:32.248272Z","end":"2026-04-29T16:06:32.521538Z","steps":["trace[1660027729] 'process raft request' (duration: 116.987192ms)","trace[1660027729] 'compare' (duration: 155.856487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:32.521718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.475786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:32.521741Z","caller":"traceutil/trace.go:171","msg":"trace[709910265] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25313; }","duration":"240.526986ms","start":"2026-04-29T16:06:32.281204Z","end":"2026-04-29T16:06:32.521731Z","steps":["trace[709910265] 'agreement among raft nodes before linearized reading' (duration: 240.481285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.843226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.275629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:06:32.843574Z","caller":"traceutil/trace.go:171","msg":"trace[425393482] linearizableReadLoop","detail":"{readStateIndex:26144; appliedIndex:26142; }","duration":"322.142571ms","start":"2026-04-29T16:06:32.521413Z","end":"2026-04-29T16:06:32.843555Z","steps":["trace[425393482] 'read index received' (duration: 125.495902ms)","trace[425393482] 'applied index is now lower than readState.Index' (duration: 196.645459ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:32.843684Z","caller":"traceutil/trace.go:171","msg":"trace[1545200403] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"556.464202ms","start":"2026-04-29T16:06:32.287204Z","end":"2026-04-29T16:06:32.843639Z","steps":["trace[1545200403] 'process raft request' (duration: 556.173504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.843863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.287178Z","time spent":"556.539144ms","remote":"127.0.0.1:34948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9529,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:06:32.843893Z","caller":"traceutil/trace.go:171","msg":"trace[1371282445] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"196.144185ms","start":"2026-04-29T16:06:32.64771Z","end":"2026-04-29T16:06:32.843854Z","steps":["trace[1371282445] 'process raft request' (duration: 195.786165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.844079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.331226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-29T16:06:32.844218Z","caller":"traceutil/trace.go:171","msg":"trace[475974810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25316; }","duration":"524.509731ms","start":"2026-04-29T16:06:32.319683Z","end":"2026-04-29T16:06:32.844192Z","steps":["trace[475974810] 'agreement among raft nodes before linearized reading' (duration: 524.098739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.844269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.31967Z","time spent":"524.586904ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-29T16:06:32.844276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.217695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-29T16:06:32.844309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.048058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-29T16:06:32.844335Z","caller":"traceutil/trace.go:171","msg":"trace[325554639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25316; }","duration":"523.303777ms","start":"2026-04-29T16:06:32.321016Z","end":"2026-04-29T16:06:32.84432Z","steps":["trace[325554639] 'agreement among raft nodes before linearized reading' (duration: 523.155932ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:32.844381Z","caller":"traceutil/trace.go:171","msg":"trace[303872593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25316; }","duration":"530.156691ms","start":"2026-04-29T16:06:32.31421Z","end":"2026-04-29T16:06:32.844367Z","steps":["trace[303872593] 'agreement among raft nodes before linearized reading' (duration: 529.951415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.84438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.321003Z","time spent":"523.36998ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-29T16:06:32.844413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.314197Z","time spent":"530.210863ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-29T16:06:32.844691Z","caller":"traceutil/trace.go:171","msg":"trace[1124083833] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"561.913987ms","start":"2026-04-29T16:06:32.282727Z","end":"2026-04-29T16:06:32.844641Z","steps":["trace[1124083833] 'process raft request' (duration: 364.131845ms)","trace[1124083833] 'compare' (duration: 196.143965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:32.844891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.282709Z","time spent":"562.071421ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:32.844272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.207922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T16:06:32.845158Z","caller":"traceutil/trace.go:171","msg":"trace[511245076] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25316; }","duration":"547.130459ms","start":"2026-04-29T16:06:32.298011Z","end":"2026-04-29T16:06:32.845142Z","steps":["trace[511245076] 'agreement among raft nodes before linearized reading' (duration: 545.605376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:32.845198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.297959Z","time spent":"547.229163ms","remote":"127.0.0.1:34934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-29T16:06:33.168747Z","caller":"traceutil/trace.go:171","msg":"trace[1798263656] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"318.24274ms","start":"2026-04-29T16:06:32.850478Z","end":"2026-04-29T16:06:33.168721Z","steps":["trace[1798263656] 'process raft request' (duration: 306.429817ms)","trace[1798263656] 'compare' (duration: 11.420332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:33.168893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.850463Z","time spent":"318.352012ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:06:33.169113Z","caller":"traceutil/trace.go:171","msg":"trace[1354499462] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"316.956794ms","start":"2026-04-29T16:06:32.852138Z","end":"2026-04-29T16:06:33.169095Z","steps":["trace[1354499462] 'process raft request' (duration: 316.521882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:33.169199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.85213Z","time spent":"317.028386ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T16:06:33.169449Z","caller":"traceutil/trace.go:171","msg":"trace[1634711356] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"314.503306ms","start":"2026-04-29T16:06:32.854932Z","end":"2026-04-29T16:06:33.169435Z","steps":["trace[1634711356] 'process raft request' (duration: 313.96004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:33.169518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:32.85492Z","time spent":"314.561647ms","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:33.169743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.952919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-29T16:06:33.169801Z","caller":"traceutil/trace.go:171","msg":"trace[26885665] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26146; }","duration":"291.413323ms","start":"2026-04-29T16:06:32.877763Z","end":"2026-04-29T16:06:33.169176Z","steps":["trace[26885665] 'read index received' (duration: 279.158238ms)","trace[26885665] 'applied index is now lower than readState.Index' (duration: 12.254145ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:33.169833Z","caller":"traceutil/trace.go:171","msg":"trace[894383557] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25320; }","duration":"292.064152ms","start":"2026-04-29T16:06:32.87775Z","end":"2026-04-29T16:06:33.169814Z","steps":["trace[894383557] 'agreement among raft nodes before linearized reading' (duration: 291.777274ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:33.448828Z","caller":"traceutil/trace.go:171","msg":"trace[2085390928] linearizableReadLoop","detail":"{readStateIndex:26150; appliedIndex:26149; }","duration":"278.942931ms","start":"2026-04-29T16:06:33.169829Z","end":"2026-04-29T16:06:33.448772Z","steps":["trace[2085390928] 'read index received' (duration: 238.346795ms)","trace[2085390928] 'applied index is now lower than readState.Index' (duration: 40.594356ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:33.44901Z","caller":"traceutil/trace.go:171","msg":"trace[283826966] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"335.25294ms","start":"2026-04-29T16:06:33.113719Z","end":"2026-04-29T16:06:33.448972Z","steps":["trace[283826966] 'process raft request' (duration: 294.435369ms)","trace[283826966] 'compare' (duration: 40.152563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T16:06:33.449186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:33.113702Z","time spent":"335.386464ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T16:06:33.449187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.579727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-04-29T16:06:33.449268Z","caller":"traceutil/trace.go:171","msg":"trace[940336167] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25321; }","duration":"331.682019ms","start":"2026-04-29T16:06:33.117565Z","end":"2026-04-29T16:06:33.449247Z","steps":["trace[940336167] 'agreement among raft nodes before linearized reading' (duration: 331.462763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:33.44932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T16:06:33.117559Z","time spent":"331.751132ms","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-29T16:06:33.449326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.999415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18585"} {"level":"warn","ts":"2026-04-29T16:06:33.44938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.184765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-04-29T16:06:33.449412Z","caller":"traceutil/trace.go:171","msg":"trace[1738986536] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25321; }","duration":"270.238366ms","start":"2026-04-29T16:06:33.179164Z","end":"2026-04-29T16:06:33.449403Z","steps":["trace[1738986536] 'agreement among raft nodes before linearized reading' (duration: 270.116732ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:33.449407Z","caller":"traceutil/trace.go:171","msg":"trace[592522090] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25321; }","duration":"278.1365ms","start":"2026-04-29T16:06:33.171252Z","end":"2026-04-29T16:06:33.449388Z","steps":["trace[592522090] 'agreement among raft nodes before linearized reading' (duration: 277.884722ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:33.912964Z","caller":"traceutil/trace.go:171","msg":"trace[787787029] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"176.271434ms","start":"2026-04-29T16:06:33.736674Z","end":"2026-04-29T16:06:33.912945Z","steps":["trace[787787029] 'process raft request' (duration: 176.129581ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:34.091249Z","caller":"traceutil/trace.go:171","msg":"trace[1993170156] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"206.819977ms","start":"2026-04-29T16:06:33.88437Z","end":"2026-04-29T16:06:34.09119Z","steps":["trace[1993170156] 'process raft request' (duration: 206.528329ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:34.091602Z","caller":"traceutil/trace.go:171","msg":"trace[1022241049] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"206.817146ms","start":"2026-04-29T16:06:33.88475Z","end":"2026-04-29T16:06:34.091567Z","steps":["trace[1022241049] 'process raft request' (duration: 206.366494ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:34.091698Z","caller":"traceutil/trace.go:171","msg":"trace[1041372039] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"206.739114ms","start":"2026-04-29T16:06:33.88495Z","end":"2026-04-29T16:06:34.091689Z","steps":["trace[1041372039] 'process raft request' (duration: 206.384364ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:34.091632Z","caller":"traceutil/trace.go:171","msg":"trace[377598694] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"206.660892ms","start":"2026-04-29T16:06:33.884943Z","end":"2026-04-29T16:06:34.091603Z","steps":["trace[377598694] 'process raft request' (duration: 206.522259ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:34.091868Z","caller":"traceutil/trace.go:171","msg":"trace[1277606573] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"206.894739ms","start":"2026-04-29T16:06:33.884944Z","end":"2026-04-29T16:06:34.091839Z","steps":["trace[1277606573] 'process raft request' (duration: 206.552759ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:36.040859Z","caller":"traceutil/trace.go:171","msg":"trace[1571391954] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"122.770025ms","start":"2026-04-29T16:06:35.918057Z","end":"2026-04-29T16:06:36.040827Z","steps":["trace[1571391954] 'process raft request' (duration: 122.59521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:36.276649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.522025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T16:06:36.276764Z","caller":"traceutil/trace.go:171","msg":"trace[1800337307] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"180.257237ms","start":"2026-04-29T16:06:36.096485Z","end":"2026-04-29T16:06:36.276742Z","steps":["trace[1800337307] 'process raft request' (duration: 55.539217ms)","trace[1800337307] 'compare' (duration: 123.975019ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:36.2838Z","caller":"traceutil/trace.go:171","msg":"trace[1419257537] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"185.133864ms","start":"2026-04-29T16:06:36.098646Z","end":"2026-04-29T16:06:36.28378Z","steps":["trace[1419257537] 'process raft request' (duration: 184.960679ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:36.284082Z","caller":"traceutil/trace.go:171","msg":"trace[1777497743] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"183.508978ms","start":"2026-04-29T16:06:36.100561Z","end":"2026-04-29T16:06:36.28407Z","steps":["trace[1777497743] 'process raft request' (duration: 183.179049ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:36.284278Z","caller":"traceutil/trace.go:171","msg":"trace[1535000853] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"183.331083ms","start":"2026-04-29T16:06:36.100904Z","end":"2026-04-29T16:06:36.284235Z","steps":["trace[1535000853] 'process raft request' (duration: 183.107776ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:36.284485Z","caller":"traceutil/trace.go:171","msg":"trace[68788076] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"183.049666ms","start":"2026-04-29T16:06:36.101399Z","end":"2026-04-29T16:06:36.284449Z","steps":["trace[68788076] 'process raft request' (duration: 182.780257ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:36.284519Z","caller":"traceutil/trace.go:171","msg":"trace[1361408794] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"161.876508ms","start":"2026-04-29T16:06:36.12262Z","end":"2026-04-29T16:06:36.284496Z","steps":["trace[1361408794] 'process raft request' (duration: 161.708662ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.500656Z","caller":"traceutil/trace.go:171","msg":"trace[1998573324] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"209.959955ms","start":"2026-04-29T16:06:40.290665Z","end":"2026-04-29T16:06:40.500624Z","steps":["trace[1998573324] 'process raft request' (duration: 209.745628ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.501524Z","caller":"traceutil/trace.go:171","msg":"trace[544655099] linearizableReadLoop","detail":"{readStateIndex:26204; appliedIndex:26199; }","duration":"198.700167ms","start":"2026-04-29T16:06:40.302793Z","end":"2026-04-29T16:06:40.501493Z","steps":["trace[544655099] 'read index received' (duration: 197.629296ms)","trace[544655099] 'applied index is now lower than readState.Index' (duration: 1.069551ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:40.501601Z","caller":"traceutil/trace.go:171","msg":"trace[1794503652] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"203.689438ms","start":"2026-04-29T16:06:40.297878Z","end":"2026-04-29T16:06:40.501568Z","steps":["trace[1794503652] 'process raft request' (duration: 203.497323ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.50165Z","caller":"traceutil/trace.go:171","msg":"trace[367590994] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"203.02259ms","start":"2026-04-29T16:06:40.298613Z","end":"2026-04-29T16:06:40.501636Z","steps":["trace[367590994] 'process raft request' (duration: 202.805144ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.501796Z","caller":"traceutil/trace.go:171","msg":"trace[1721187661] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"202.891935ms","start":"2026-04-29T16:06:40.298895Z","end":"2026-04-29T16:06:40.501787Z","steps":["trace[1721187661] 'process raft request' (duration: 202.558786ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.502005Z","caller":"traceutil/trace.go:171","msg":"trace[1010272943] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"204.198423ms","start":"2026-04-29T16:06:40.297789Z","end":"2026-04-29T16:06:40.501987Z","steps":["trace[1010272943] 'process raft request' (duration: 203.447152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:40.502186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.375046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-29T16:06:40.502223Z","caller":"traceutil/trace.go:171","msg":"trace[1911980085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25373; }","duration":"199.487059ms","start":"2026-04-29T16:06:40.302725Z","end":"2026-04-29T16:06:40.502212Z","steps":["trace[1911980085] 'agreement among raft nodes before linearized reading' (duration: 199.344685ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.700161Z","caller":"traceutil/trace.go:171","msg":"trace[826322500] linearizableReadLoop","detail":"{readStateIndex:26205; appliedIndex:26204; }","duration":"133.195339ms","start":"2026-04-29T16:06:40.566942Z","end":"2026-04-29T16:06:40.700138Z","steps":["trace[826322500] 'read index received' (duration: 133.105796ms)","trace[826322500] 'applied index is now lower than readState.Index' (duration: 88.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:40.700284Z","caller":"traceutil/trace.go:171","msg":"trace[1652935703] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"193.377177ms","start":"2026-04-29T16:06:40.506874Z","end":"2026-04-29T16:06:40.700252Z","steps":["trace[1652935703] 'process raft request' (duration: 193.111299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:40.700405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.439226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-29T16:06:40.700447Z","caller":"traceutil/trace.go:171","msg":"trace[1567671404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25374; }","duration":"133.633651ms","start":"2026-04-29T16:06:40.566798Z","end":"2026-04-29T16:06:40.700432Z","steps":["trace[1567671404] 'agreement among raft nodes before linearized reading' (duration: 133.446986ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:40.734551Z","caller":"traceutil/trace.go:171","msg":"trace[1058012257] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"137.546692ms","start":"2026-04-29T16:06:40.596966Z","end":"2026-04-29T16:06:40.734512Z","steps":["trace[1058012257] 'process raft request' (duration: 136.963465ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:43.103825Z","caller":"traceutil/trace.go:171","msg":"trace[147033013] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"219.36454ms","start":"2026-04-29T16:06:42.884442Z","end":"2026-04-29T16:06:43.103807Z","steps":["trace[147033013] 'process raft request' (duration: 219.207626ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:43.152004Z","caller":"traceutil/trace.go:171","msg":"trace[1049612341] linearizableReadLoop","detail":"{readStateIndex:26222; appliedIndex:26220; }","duration":"241.772162ms","start":"2026-04-29T16:06:42.910194Z","end":"2026-04-29T16:06:43.151967Z","steps":["trace[1049612341] 'read index received' (duration: 193.565352ms)","trace[1049612341] 'applied index is now lower than readState.Index' (duration: 48.20506ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:43.152179Z","caller":"traceutil/trace.go:171","msg":"trace[369111757] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"267.17273ms","start":"2026-04-29T16:06:42.884991Z","end":"2026-04-29T16:06:43.152163Z","steps":["trace[369111757] 'process raft request' (duration: 266.760658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:43.152536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.172628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T16:06:43.152602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.365479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-29T16:06:43.152766Z","caller":"traceutil/trace.go:171","msg":"trace[1644998046] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25391; }","duration":"172.476476ms","start":"2026-04-29T16:06:42.980248Z","end":"2026-04-29T16:06:43.152725Z","steps":["trace[1644998046] 'agreement among raft nodes before linearized reading' (duration: 172.125506ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:43.152777Z","caller":"traceutil/trace.go:171","msg":"trace[290027663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25391; }","duration":"242.609556ms","start":"2026-04-29T16:06:42.910146Z","end":"2026-04-29T16:06:43.152755Z","steps":["trace[290027663] 'agreement among raft nodes before linearized reading' (duration: 242.211455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T16:06:48.821215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.685376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-29T16:06:48.821293Z","caller":"traceutil/trace.go:171","msg":"trace[831132491] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25430; }","duration":"148.815199ms","start":"2026-04-29T16:06:48.67246Z","end":"2026-04-29T16:06:48.821276Z","steps":["trace[831132491] 'range keys from in-memory index tree' (duration: 148.560792ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:06:48.9406Z","caller":"traceutil/trace.go:171","msg":"trace[1417794640] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"115.867469ms","start":"2026-04-29T16:06:48.824703Z","end":"2026-04-29T16:06:48.94057Z","steps":["trace[1417794640] 'process raft request' (duration: 99.91757ms)","trace[1417794640] 'compare' (duration: 15.695822ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:06:48.9409Z","caller":"traceutil/trace.go:171","msg":"trace[1750448693] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"115.068297ms","start":"2026-04-29T16:06:48.825819Z","end":"2026-04-29T16:06:48.940887Z","steps":["trace[1750448693] 'process raft request' (duration: 114.696527ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:07:16.990494Z","caller":"traceutil/trace.go:171","msg":"trace[1687197178] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"246.253069ms","start":"2026-04-29T16:07:16.744202Z","end":"2026-04-29T16:07:16.990455Z","steps":["trace[1687197178] 'process raft request' (duration: 233.14613ms)","trace[1687197178] 'compare' (duration: 12.865013ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T16:07:16.99084Z","caller":"traceutil/trace.go:171","msg":"trace[1326956806] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"243.093319ms","start":"2026-04-29T16:07:16.74771Z","end":"2026-04-29T16:07:16.990803Z","steps":["trace[1326956806] 'process raft request' (duration: 243.019447ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:07:16.990896Z","caller":"traceutil/trace.go:171","msg":"trace[1180145326] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"245.842766ms","start":"2026-04-29T16:07:16.745043Z","end":"2026-04-29T16:07:16.990886Z","steps":["trace[1180145326] 'process raft request' (duration: 245.357673ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:07:16.990793Z","caller":"traceutil/trace.go:171","msg":"trace[1766853853] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"245.443026ms","start":"2026-04-29T16:07:16.745319Z","end":"2026-04-29T16:07:16.990762Z","steps":["trace[1766853853] 'process raft request' (duration: 245.296752ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T16:07:19.169045Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000681e.wal"} {"level":"info","ts":"2026-04-29T16:07:45.562625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23008} {"level":"info","ts":"2026-04-29T16:07:45.698301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23008,"took":"131.402638ms","hash":2298767796,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":36642816,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-29T16:07:45.698365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2298767796,"revision":23008,"compact-revision":20023} {"level":"info","ts":"2026-04-29T16:12:45.568986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26036} {"level":"info","ts":"2026-04-29T16:12:45.716598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26036,"took":"143.421287ms","hash":4017044358,"current-db-size-bytes":61407232,"current-db-size":"61 MB","current-db-size-in-use-bytes":34492416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-29T16:12:45.716674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4017044358,"revision":26036,"compact-revision":23008} {"level":"info","ts":"2026-04-29T16:13:31.472408Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T16:13:31.477523Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-29T16:13:31.477654Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-29T16:17:45.574426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28739} {"level":"info","ts":"2026-04-29T16:17:45.71794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28739,"took":"137.490056ms","hash":2043007524,"current-db-size-bytes":61407232,"current-db-size":"61 MB","current-db-size-in-use-bytes":30138368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-29T16:17:45.718039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2043007524,"revision":28739,"compact-revision":26036} {"level":"info","ts":"2026-04-29T16:22:45.580884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30963} {"level":"info","ts":"2026-04-29T16:22:45.694111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30963,"took":"110.316622ms","hash":3674414487,"current-db-size-bytes":61407232,"current-db-size":"61 MB","current-db-size-in-use-bytes":30990336,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-29T16:22:45.694184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3674414487,"revision":30963,"compact-revision":28739} {"level":"info","ts":"2026-04-29T16:27:45.587254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33226} {"level":"info","ts":"2026-04-29T16:27:45.733647Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33226,"took":"142.150775ms","hash":1675549824,"current-db-size-bytes":62451712,"current-db-size":"62 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-29T16:27:45.7337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1675549824,"revision":33226,"compact-revision":30963} {"level":"info","ts":"2026-04-29T16:32:45.593575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35522} {"level":"info","ts":"2026-04-29T16:32:45.733256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35522,"took":"134.877941ms","hash":2153307469,"current-db-size-bytes":63336448,"current-db-size":"63 MB","current-db-size-in-use-bytes":31010816,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-29T16:32:45.73332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2153307469,"revision":35522,"compact-revision":33226} {"level":"info","ts":"2026-04-29T16:34:59.502385Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T16:34:59.509048Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-29T16:34:59.509173Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-29T16:37:45.600101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37754} {"level":"info","ts":"2026-04-29T16:37:45.726625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37754,"took":"120.508808ms","hash":1305831782,"current-db-size-bytes":63336448,"current-db-size":"63 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-29T16:37:45.726705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1305831782,"revision":37754,"compact-revision":35522}