{"level":"warn","ts":"2026-04-20T10:42:57.237303Z","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-20T10:42:57.237426Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-20T10:42:57.237518Z","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-20T10:42:57.237541Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-20T10:42:57.237596Z","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-20T10:42:57.238435Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-20T10:42:57.238518Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-20T10:42:57.242402Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.637475ms"} {"level":"info","ts":"2026-04-20T10:42:57.249844Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-20T10:42:57.249921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:42:57.249995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-20T10:42:57.250024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:42:57.250039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-20T10:42:57.250149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-20T10:42:57.257136Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:42:57.260854Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:42:57.262284Z","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-20T10:42:57.264868Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:42:57.265057Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:42:57.265628Z","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-20T10:42:57.266157Z","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-20T10:42:57.266171Z","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-20T10:42:57.266854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-20T10:42:57.267432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-20T10:42:57.269581Z","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-20T10:42:57.269722Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-20T10:42:57.269805Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-20T10:42:57.269832Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:42:57.269889Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:42:58.150751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:42:58.151392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:42:58.15162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-20T10:42:58.151863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:42:58.151886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-20T10:42:58.151911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-20T10:42:58.151934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-20T10:42:58.155649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:42:58.155737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:42:58.155761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:42:58.155901Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:58.156735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:58.156847Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:58.156881Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:58.157011Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:42:58.157035Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:42:58.158001Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-20T10:42:58.165386Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:43:26.487256Z","caller":"traceutil/trace.go:171","msg":"trace[2017289138] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"137.196823ms","start":"2026-04-20T10:43:26.35004Z","end":"2026-04-20T10:43:26.487237Z","steps":["trace[2017289138] 'process raft request' (duration: 136.394578ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:28.281553Z","caller":"traceutil/trace.go:171","msg":"trace[1926188265] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"128.1535ms","start":"2026-04-20T10:43:28.153375Z","end":"2026-04-20T10:43:28.281528Z","steps":["trace[1926188265] 'process raft request' (duration: 128.006927ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:28.669378Z","caller":"traceutil/trace.go:171","msg":"trace[1086260045] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:636; }","duration":"104.397203ms","start":"2026-04-20T10:43:28.564962Z","end":"2026-04-20T10:43:28.669359Z","steps":["trace[1086260045] 'read index received' (duration: 104.150998ms)","trace[1086260045] 'applied index is now lower than readState.Index' (duration: 245.605µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:43:28.669555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.570886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mbvkk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-20T10:43:28.6696Z","caller":"traceutil/trace.go:171","msg":"trace[1292282942] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mbvkk; range_end:; response_count:1; response_revision:615; }","duration":"104.684138ms","start":"2026-04-20T10:43:28.564908Z","end":"2026-04-20T10:43:28.669592Z","steps":["trace[1292282942] 'agreement among raft nodes before linearized reading' (duration: 104.529215ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:28.669899Z","caller":"traceutil/trace.go:171","msg":"trace[2121861754] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"324.924245ms","start":"2026-04-20T10:43:28.344965Z","end":"2026-04-20T10:43:28.669889Z","steps":["trace[2121861754] 'process raft request' (duration: 324.214773ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:28.67019Z","caller":"traceutil/trace.go:171","msg":"trace[354949763] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"324.865885ms","start":"2026-04-20T10:43:28.345299Z","end":"2026-04-20T10:43:28.670165Z","steps":["trace[354949763] 'process raft request' (duration: 324.01449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:28.670215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:28.344958Z","time spent":"324.964307ms","remote":"127.0.0.1:38528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:43:28.670309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:28.345267Z","time spent":"324.982837ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:43:28.670504Z","caller":"traceutil/trace.go:171","msg":"trace[1094044013] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"325.344523ms","start":"2026-04-20T10:43:28.345151Z","end":"2026-04-20T10:43:28.670496Z","steps":["trace[1094044013] 'process raft request' (duration: 324.125511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:28.670554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:28.345131Z","time spent":"325.391723ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:43:28.819384Z","caller":"traceutil/trace.go:171","msg":"trace[1513929909] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"132.243223ms","start":"2026-04-20T10:43:28.687116Z","end":"2026-04-20T10:43:28.819359Z","steps":["trace[1513929909] 'process raft request' (duration: 131.983439ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:30.972956Z","caller":"traceutil/trace.go:171","msg":"trace[1375010440] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"206.007252ms","start":"2026-04-20T10:43:30.766889Z","end":"2026-04-20T10:43:30.972897Z","steps":["trace[1375010440] 'process raft request' (duration: 205.747238ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:33.738065Z","caller":"traceutil/trace.go:171","msg":"trace[519526305] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"170.249924ms","start":"2026-04-20T10:45:33.567784Z","end":"2026-04-20T10:45:33.738034Z","steps":["trace[519526305] 'process raft request' (duration: 169.722285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:33.743515Z","caller":"traceutil/trace.go:171","msg":"trace[1733217633] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"146.62405ms","start":"2026-04-20T10:45:33.596872Z","end":"2026-04-20T10:45:33.743496Z","steps":["trace[1733217633] 'process raft request' (duration: 146.02139ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:43.362506Z","caller":"traceutil/trace.go:171","msg":"trace[699907038] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"113.69611ms","start":"2026-04-20T10:45:43.248787Z","end":"2026-04-20T10:45:43.362483Z","steps":["trace[699907038] 'process raft request' (duration: 113.643669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:43.362523Z","caller":"traceutil/trace.go:171","msg":"trace[2104901723] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"115.202047ms","start":"2026-04-20T10:45:43.247304Z","end":"2026-04-20T10:45:43.362506Z","steps":["trace[2104901723] 'process raft request' (duration: 114.983453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:45.273927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.9648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-9zc2j\" ","response":"range_response_count:1 size:3888"} {"level":"info","ts":"2026-04-20T10:45:45.273997Z","caller":"traceutil/trace.go:171","msg":"trace[2033449363] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-9zc2j; range_end:; response_count:1; response_revision:1556; }","duration":"121.040391ms","start":"2026-04-20T10:45:45.152939Z","end":"2026-04-20T10:45:45.273979Z","steps":["trace[2033449363] 'range keys from in-memory index tree' (duration: 120.476141ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:04.398522Z","caller":"traceutil/trace.go:171","msg":"trace[712949759] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"104.431043ms","start":"2026-04-20T10:46:04.294066Z","end":"2026-04-20T10:46:04.398497Z","steps":["trace[712949759] 'process raft request' (duration: 98.067949ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:09.187233Z","caller":"traceutil/trace.go:171","msg":"trace[1854007811] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"101.084534ms","start":"2026-04-20T10:46:09.086088Z","end":"2026-04-20T10:46:09.187172Z","steps":["trace[1854007811] 'process raft request' (duration: 97.828665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:09.501526Z","caller":"traceutil/trace.go:171","msg":"trace[1257283785] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"113.245063ms","start":"2026-04-20T10:46:09.388252Z","end":"2026-04-20T10:46:09.501497Z","steps":["trace[1257283785] 'process raft request' (duration: 64.266603ms)","trace[1257283785] 'compare' (duration: 48.862498ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:09.502593Z","caller":"traceutil/trace.go:171","msg":"trace[1817115275] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2137; }","duration":"100.689536ms","start":"2026-04-20T10:46:09.401876Z","end":"2026-04-20T10:46:09.502566Z","steps":["trace[1817115275] 'read index received' (duration: 50.669309ms)","trace[1817115275] 'applied index is now lower than readState.Index' (duration: 50.018967ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:09.502925Z","caller":"traceutil/trace.go:171","msg":"trace[857686498] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"108.587738ms","start":"2026-04-20T10:46:09.394298Z","end":"2026-04-20T10:46:09.502885Z","steps":["trace[857686498] 'process raft request' (duration: 108.066409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:09.50304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.120424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:09.503079Z","caller":"traceutil/trace.go:171","msg":"trace[1871359126] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2049; }","duration":"101.215876ms","start":"2026-04-20T10:46:09.401854Z","end":"2026-04-20T10:46:09.50307Z","steps":["trace[1871359126] 'agreement among raft nodes before linearized reading' (duration: 101.105614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:09.503278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.376638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:09.503334Z","caller":"traceutil/trace.go:171","msg":"trace[1934061486] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2049; }","duration":"101.481161ms","start":"2026-04-20T10:46:09.401839Z","end":"2026-04-20T10:46:09.50332Z","steps":["trace[1934061486] 'agreement among raft nodes before linearized reading' (duration: 101.373619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:09.514324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.157412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:09.514408Z","caller":"traceutil/trace.go:171","msg":"trace[1365724198] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2050; }","duration":"112.379416ms","start":"2026-04-20T10:46:09.40201Z","end":"2026-04-20T10:46:09.514389Z","steps":["trace[1365724198] 'agreement among raft nodes before linearized reading' (duration: 112.227543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:16.937478Z","caller":"traceutil/trace.go:171","msg":"trace[677457526] linearizableReadLoop","detail":"{readStateIndex:2335; appliedIndex:2334; }","duration":"103.440565ms","start":"2026-04-20T10:46:16.833998Z","end":"2026-04-20T10:46:16.937439Z","steps":["trace[677457526] 'read index received' (duration: 14.850267ms)","trace[677457526] 'applied index is now lower than readState.Index' (duration: 88.588808ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:16.937622Z","caller":"traceutil/trace.go:171","msg":"trace[1355339772] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"114.82966ms","start":"2026-04-20T10:46:16.822752Z","end":"2026-04-20T10:46:16.937582Z","steps":["trace[1355339772] 'process raft request' (duration: 26.223341ms)","trace[1355339772] 'compare' (duration: 88.228922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:16.937854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.841323ms","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-20T10:46:16.937893Z","caller":"traceutil/trace.go:171","msg":"trace[935120711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2237; }","duration":"103.941594ms","start":"2026-04-20T10:46:16.833938Z","end":"2026-04-20T10:46:16.937879Z","steps":["trace[935120711] 'agreement among raft nodes before linearized reading' (duration: 103.764381ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:17.077701Z","caller":"traceutil/trace.go:171","msg":"trace[1472465279] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"133.729189ms","start":"2026-04-20T10:46:16.943945Z","end":"2026-04-20T10:46:17.077675Z","steps":["trace[1472465279] 'process raft request' (duration: 98.62792ms)","trace[1472465279] 'compare' (duration: 34.950566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:17.493507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.902301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3d2cfd7-b954-4fab-8be6-20865c5716bb\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-04-20T10:46:17.493601Z","caller":"traceutil/trace.go:171","msg":"trace[107848887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3d2cfd7-b954-4fab-8be6-20865c5716bb; range_end:; response_count:1; response_revision:2239; }","duration":"207.046284ms","start":"2026-04-20T10:46:17.286526Z","end":"2026-04-20T10:46:17.493573Z","steps":["trace[107848887] 'range keys from in-memory index tree' (duration: 206.726768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:17.493625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.562108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T10:46:17.49366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.375734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a7bc6d4-af0c-4667-8a2b-f3804228500d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-20T10:46:17.493752Z","caller":"traceutil/trace.go:171","msg":"trace[1922885331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2239; }","duration":"190.750602ms","start":"2026-04-20T10:46:17.302976Z","end":"2026-04-20T10:46:17.493727Z","steps":["trace[1922885331] 'range keys from in-memory index tree' (duration: 190.351665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:17.493761Z","caller":"traceutil/trace.go:171","msg":"trace[31494216] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a7bc6d4-af0c-4667-8a2b-f3804228500d; range_end:; response_count:1; response_revision:2239; }","duration":"152.541277ms","start":"2026-04-20T10:46:17.341195Z","end":"2026-04-20T10:46:17.493736Z","steps":["trace[31494216] 'range keys from in-memory index tree' (duration: 151.848405ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:17.771799Z","caller":"traceutil/trace.go:171","msg":"trace[1694172394] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"267.272574ms","start":"2026-04-20T10:46:17.504491Z","end":"2026-04-20T10:46:17.771764Z","steps":["trace[1694172394] 'process raft request' (duration: 232.581122ms)","trace[1694172394] 'compare' (duration: 34.374797ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:17.771976Z","caller":"traceutil/trace.go:171","msg":"trace[2118120794] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"213.580441ms","start":"2026-04-20T10:46:17.558355Z","end":"2026-04-20T10:46:17.771936Z","steps":["trace[2118120794] 'process raft request' (duration: 213.307957ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:17.782091Z","caller":"traceutil/trace.go:171","msg":"trace[1515858276] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"124.28706ms","start":"2026-04-20T10:46:17.657772Z","end":"2026-04-20T10:46:17.782059Z","steps":["trace[1515858276] 'process raft request' (duration: 123.998655ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:34.071771Z","caller":"traceutil/trace.go:171","msg":"trace[549545349] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"224.953063ms","start":"2026-04-20T10:46:33.846792Z","end":"2026-04-20T10:46:34.071745Z","steps":["trace[549545349] 'process raft request' (duration: 224.655008ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:34.101382Z","caller":"traceutil/trace.go:171","msg":"trace[1541154206] linearizableReadLoop","detail":"{readStateIndex:2494; appliedIndex:2492; }","duration":"203.299418ms","start":"2026-04-20T10:46:33.898056Z","end":"2026-04-20T10:46:34.101355Z","steps":["trace[1541154206] 'read index received' (duration: 173.503294ms)","trace[1541154206] 'applied index is now lower than readState.Index' (duration: 29.794724ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:34.101508Z","caller":"traceutil/trace.go:171","msg":"trace[1479527109] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"140.991676ms","start":"2026-04-20T10:46:33.960502Z","end":"2026-04-20T10:46:34.101494Z","steps":["trace[1479527109] 'process raft request' (duration: 140.806183ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:34.1015Z","caller":"traceutil/trace.go:171","msg":"trace[1181877026] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"252.67532ms","start":"2026-04-20T10:46:33.848779Z","end":"2026-04-20T10:46:34.101454Z","steps":["trace[1181877026] 'process raft request' (duration: 252.404877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:34.101908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.837835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:34.101981Z","caller":"traceutil/trace.go:171","msg":"trace[926967915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2393; }","duration":"203.924766ms","start":"2026-04-20T10:46:33.898039Z","end":"2026-04-20T10:46:34.101964Z","steps":["trace[926967915] 'agreement among raft nodes before linearized reading' (duration: 203.805154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:34.102249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.975259ms","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-20T10:46:34.10237Z","caller":"traceutil/trace.go:171","msg":"trace[876747712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2393; }","duration":"192.150251ms","start":"2026-04-20T10:46:33.91019Z","end":"2026-04-20T10:46:34.10234Z","steps":["trace[876747712] 'agreement among raft nodes before linearized reading' (duration: 191.777376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:35.388697Z","caller":"traceutil/trace.go:171","msg":"trace[2127913035] linearizableReadLoop","detail":"{readStateIndex:2503; appliedIndex:2502; }","duration":"278.143161ms","start":"2026-04-20T10:46:35.110526Z","end":"2026-04-20T10:46:35.388669Z","steps":["trace[2127913035] 'read index received' (duration: 277.791337ms)","trace[2127913035] 'applied index is now lower than readState.Index' (duration: 350.644µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:35.388744Z","caller":"traceutil/trace.go:171","msg":"trace[1927800728] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"352.946104ms","start":"2026-04-20T10:46:35.035769Z","end":"2026-04-20T10:46:35.388715Z","steps":["trace[1927800728] 'process raft request' (duration: 352.704671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:35.388986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.437166ms","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-20T10:46:35.389033Z","caller":"traceutil/trace.go:171","msg":"trace[1666449785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2401; }","duration":"278.501107ms","start":"2026-04-20T10:46:35.110518Z","end":"2026-04-20T10:46:35.38902Z","steps":["trace[1666449785] 'agreement among raft nodes before linearized reading' (duration: 278.297164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:35.389157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.098233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T10:46:35.389223Z","caller":"traceutil/trace.go:171","msg":"trace[112911141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2401; }","duration":"245.219784ms","start":"2026-04-20T10:46:35.143987Z","end":"2026-04-20T10:46:35.389207Z","steps":["trace[112911141] 'agreement among raft nodes before linearized reading' (duration: 244.991631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:35.389159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.660899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-20T10:46:35.389319Z","caller":"traceutil/trace.go:171","msg":"trace[1813182501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2401; }","duration":"199.879251ms","start":"2026-04-20T10:46:35.18943Z","end":"2026-04-20T10:46:35.389309Z","steps":["trace[1813182501] 'agreement among raft nodes before linearized reading' (duration: 199.565377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:35.38896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:35.035757Z","time spent":"353.059595ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:46:46.942452Z","caller":"traceutil/trace.go:171","msg":"trace[1345518027] linearizableReadLoop","detail":"{readStateIndex:2576; appliedIndex:2575; }","duration":"187.779736ms","start":"2026-04-20T10:46:46.754649Z","end":"2026-04-20T10:46:46.942429Z","steps":["trace[1345518027] 'read index received' (duration: 187.623364ms)","trace[1345518027] 'applied index is now lower than readState.Index' (duration: 155.582µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:46.942531Z","caller":"traceutil/trace.go:171","msg":"trace[1406194575] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"217.618582ms","start":"2026-04-20T10:46:46.724872Z","end":"2026-04-20T10:46:46.942491Z","steps":["trace[1406194575] 'process raft request' (duration: 217.361959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:46.942704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.999469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:46.942838Z","caller":"traceutil/trace.go:171","msg":"trace[1236193961] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2471; }","duration":"188.220781ms","start":"2026-04-20T10:46:46.75459Z","end":"2026-04-20T10:46:46.942811Z","steps":["trace[1236193961] 'agreement among raft nodes before linearized reading' (duration: 187.953818ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:47.136548Z","caller":"traceutil/trace.go:171","msg":"trace[1418637633] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"189.118028ms","start":"2026-04-20T10:46:46.947407Z","end":"2026-04-20T10:46:47.136525Z","steps":["trace[1418637633] 'process raft request' (duration: 188.730332ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:47.600536Z","caller":"traceutil/trace.go:171","msg":"trace[2127698665] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"109.93292ms","start":"2026-04-20T10:46:47.490572Z","end":"2026-04-20T10:46:47.600505Z","steps":["trace[2127698665] 'process raft request' (duration: 46.96521ms)","trace[2127698665] 'compare' (duration: 62.851838ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:48.246819Z","caller":"traceutil/trace.go:171","msg":"trace[1180406218] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"119.809408ms","start":"2026-04-20T10:46:48.12699Z","end":"2026-04-20T10:46:48.2468Z","steps":["trace[1180406218] 'process raft request' (duration: 119.681786ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:48.367495Z","caller":"traceutil/trace.go:171","msg":"trace[1088868644] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2583; }","duration":"120.275134ms","start":"2026-04-20T10:46:48.247192Z","end":"2026-04-20T10:46:48.367468Z","steps":["trace[1088868644] 'read index received' (duration: 46.333183ms)","trace[1088868644] 'applied index is now lower than readState.Index' (duration: 73.940951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:48.367719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.515867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-04-20T10:46:48.367753Z","caller":"traceutil/trace.go:171","msg":"trace[1513817505] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2479; }","duration":"120.618118ms","start":"2026-04-20T10:46:48.247123Z","end":"2026-04-20T10:46:48.367741Z","steps":["trace[1513817505] 'agreement among raft nodes before linearized reading' (duration: 120.441676ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:48.367782Z","caller":"traceutil/trace.go:171","msg":"trace[2111305707] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"183.963375ms","start":"2026-04-20T10:46:48.183784Z","end":"2026-04-20T10:46:48.367747Z","steps":["trace[2111305707] 'process raft request' (duration: 109.77771ms)","trace[2111305707] 'compare' (duration: 73.775689ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:48.625808Z","caller":"traceutil/trace.go:171","msg":"trace[1136451901] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"194.035892ms","start":"2026-04-20T10:46:48.431746Z","end":"2026-04-20T10:46:48.625782Z","steps":["trace[1136451901] 'process raft request' (duration: 128.821551ms)","trace[1136451901] 'compare' (duration: 64.988768ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:48.625707Z","caller":"traceutil/trace.go:171","msg":"trace[104343547] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2585; }","duration":"137.634192ms","start":"2026-04-20T10:46:48.488043Z","end":"2026-04-20T10:46:48.625677Z","steps":["trace[104343547] 'read index received' (duration: 72.542892ms)","trace[104343547] 'applied index is now lower than readState.Index' (duration: 65.090359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:48.626038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.978326ms","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-20T10:46:48.626096Z","caller":"traceutil/trace.go:171","msg":"trace[804400407] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2481; }","duration":"138.093298ms","start":"2026-04-20T10:46:48.487987Z","end":"2026-04-20T10:46:48.62608Z","steps":["trace[804400407] 'agreement among raft nodes before linearized reading' (duration: 137.880955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:49.903638Z","caller":"traceutil/trace.go:171","msg":"trace[1355342144] linearizableReadLoop","detail":"{readStateIndex:2588; appliedIndex:2587; }","duration":"421.440833ms","start":"2026-04-20T10:46:49.482181Z","end":"2026-04-20T10:46:49.903622Z","steps":["trace[1355342144] 'read index received' (duration: 421.327852ms)","trace[1355342144] 'applied index is now lower than readState.Index' (duration: 112.171µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:49.903942Z","caller":"traceutil/trace.go:171","msg":"trace[451982887] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"429.167219ms","start":"2026-04-20T10:46:49.474764Z","end":"2026-04-20T10:46:49.903931Z","steps":["trace[451982887] 'process raft request' (duration: 428.751914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.90413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.597724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:49.904175Z","caller":"traceutil/trace.go:171","msg":"trace[891047261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2483; }","duration":"264.705906ms","start":"2026-04-20T10:46:49.639457Z","end":"2026-04-20T10:46:49.904163Z","steps":["trace[891047261] 'agreement among raft nodes before linearized reading' (duration: 264.626285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.904185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.559386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:46:49.904185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.976245ms","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-20T10:46:49.904277Z","caller":"traceutil/trace.go:171","msg":"trace[2019012646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2483; }","duration":"123.124167ms","start":"2026-04-20T10:46:49.781133Z","end":"2026-04-20T10:46:49.904257Z","steps":["trace[2019012646] 'agreement among raft nodes before linearized reading' (duration: 122.846303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.904389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.733242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T10:46:49.904223Z","caller":"traceutil/trace.go:171","msg":"trace[1076278329] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2483; }","duration":"297.638437ms","start":"2026-04-20T10:46:49.606573Z","end":"2026-04-20T10:46:49.904212Z","steps":["trace[1076278329] 'agreement among raft nodes before linearized reading' (duration: 297.573296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:49.904488Z","caller":"traceutil/trace.go:171","msg":"trace[892419039] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2483; }","duration":"198.883525ms","start":"2026-04-20T10:46:49.705585Z","end":"2026-04-20T10:46:49.904469Z","steps":["trace[892419039] 'agreement among raft nodes before linearized reading' (duration: 198.5402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.904438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.263754ms","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-20T10:46:49.904633Z","caller":"traceutil/trace.go:171","msg":"trace[2096659928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2483; }","duration":"422.487557ms","start":"2026-04-20T10:46:49.482137Z","end":"2026-04-20T10:46:49.904625Z","steps":["trace[2096659928] 'agreement among raft nodes before linearized reading' (duration: 422.229613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.904658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:49.482119Z","time spent":"422.531367ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:46:49.904716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.014527ms","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-20T10:46:49.90475Z","caller":"traceutil/trace.go:171","msg":"trace[237769390] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2483; }","duration":"272.077788ms","start":"2026-04-20T10:46:49.632661Z","end":"2026-04-20T10:46:49.904739Z","steps":["trace[237769390] 'agreement among raft nodes before linearized reading' (duration: 271.437879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.904055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:49.474747Z","time spent":"429.23155ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:46:49.90516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.138806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T10:46:49.905207Z","caller":"traceutil/trace.go:171","msg":"trace[1042674050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2483; }","duration":"301.223556ms","start":"2026-04-20T10:46:49.60397Z","end":"2026-04-20T10:46:49.905193Z","steps":["trace[1042674050] 'agreement among raft nodes before linearized reading' (duration: 300.006619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:49.905455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:49.603947Z","time spent":"301.494791ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T10:46:50.051338Z","caller":"traceutil/trace.go:171","msg":"trace[1879289709] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"141.550651ms","start":"2026-04-20T10:46:49.909763Z","end":"2026-04-20T10:46:50.051313Z","steps":["trace[1879289709] 'process raft request' (duration: 141.47267ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:50.051419Z","caller":"traceutil/trace.go:171","msg":"trace[1453912143] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"142.245401ms","start":"2026-04-20T10:46:49.909131Z","end":"2026-04-20T10:46:50.051376Z","steps":["trace[1453912143] 'process raft request' (duration: 55.739754ms)","trace[1453912143] 'compare' (duration: 86.221133ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:20.930916Z","caller":"traceutil/trace.go:171","msg":"trace[1522459056] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"123.845635ms","start":"2026-04-20T10:47:20.807028Z","end":"2026-04-20T10:47:20.930874Z","steps":["trace[1522459056] 'process raft request' (duration: 123.553191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:21.327188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.217924ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-20T10:47:21.327383Z","caller":"traceutil/trace.go:171","msg":"trace[163484791] linearizableReadLoop","detail":"{readStateIndex:2791; appliedIndex:2790; }","duration":"124.561948ms","start":"2026-04-20T10:47:21.202799Z","end":"2026-04-20T10:47:21.327361Z","steps":["trace[163484791] 'read index received' (duration: 58.2µs)","trace[163484791] 'applied index is now lower than readState.Index' (duration: 124.501878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:21.327579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.783163ms","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-20T10:47:21.327634Z","caller":"traceutil/trace.go:171","msg":"trace[1033523518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2679; }","duration":"124.898314ms","start":"2026-04-20T10:47:21.202726Z","end":"2026-04-20T10:47:21.327624Z","steps":["trace[1033523518] 'agreement among raft nodes before linearized reading' (duration: 124.704221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:21.327851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:20.949721Z","time spent":"378.126979ms","remote":"127.0.0.1:38342","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T10:47:21.825306Z","caller":"traceutil/trace.go:171","msg":"trace[1062333110] linearizableReadLoop","detail":"{readStateIndex:2793; appliedIndex:2792; }","duration":"421.761343ms","start":"2026-04-20T10:47:21.403521Z","end":"2026-04-20T10:47:21.825282Z","steps":["trace[1062333110] 'read index received' (duration: 421.733833ms)","trace[1062333110] 'applied index is now lower than readState.Index' (duration: 26.2µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:21.825341Z","caller":"traceutil/trace.go:171","msg":"trace[266573319] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"423.271726ms","start":"2026-04-20T10:47:21.402038Z","end":"2026-04-20T10:47:21.825309Z","steps":["trace[266573319] 'process raft request' (duration: 423.091413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:21.825481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:21.402015Z","time spent":"423.389877ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:21.825499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.969336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:47:21.825548Z","caller":"traceutil/trace.go:171","msg":"trace[225873422] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2681; }","duration":"422.035207ms","start":"2026-04-20T10:47:21.4035Z","end":"2026-04-20T10:47:21.825535Z","steps":["trace[225873422] 'agreement among raft nodes before linearized reading' (duration: 421.884675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:21.825799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:21.40349Z","time spent":"422.155038ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-20T10:47:21.892952Z","caller":"traceutil/trace.go:171","msg":"trace[663520588] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"183.18547ms","start":"2026-04-20T10:47:21.709708Z","end":"2026-04-20T10:47:21.892894Z","steps":["trace[663520588] 'process raft request' (duration: 183.059918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.366595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.889246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T10:47:22.366658Z","caller":"traceutil/trace.go:171","msg":"trace[1443902570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2682; }","duration":"177.999298ms","start":"2026-04-20T10:47:22.188644Z","end":"2026-04-20T10:47:22.366643Z","steps":["trace[1443902570] 'range keys from in-memory index tree' (duration: 177.690914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.366704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.841375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T10:47:22.366765Z","caller":"traceutil/trace.go:171","msg":"trace[375848996] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2682; }","duration":"426.954327ms","start":"2026-04-20T10:47:21.939792Z","end":"2026-04-20T10:47:22.366746Z","steps":["trace[375848996] 'range keys from in-memory index tree' (duration: 426.572341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.36675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.87965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-20T10:47:22.366793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:21.939771Z","time spent":"427.014878ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-20T10:47:22.366809Z","caller":"traceutil/trace.go:171","msg":"trace[710173077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2682; }","duration":"169.992312ms","start":"2026-04-20T10:47:22.196801Z","end":"2026-04-20T10:47:22.366794Z","steps":["trace[710173077] 'range keys from in-memory index tree' (duration: 169.729337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.366854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.69293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:22.366888Z","caller":"traceutil/trace.go:171","msg":"trace[371883517] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2682; }","duration":"348.765382ms","start":"2026-04-20T10:47:22.018113Z","end":"2026-04-20T10:47:22.366878Z","steps":["trace[371883517] 'count revisions from in-memory index tree' (duration: 348.570849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.366911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.018062Z","time spent":"348.842103ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:47:22.603476Z","caller":"traceutil/trace.go:171","msg":"trace[1733156071] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"231.362734ms","start":"2026-04-20T10:47:22.372093Z","end":"2026-04-20T10:47:22.603456Z","steps":["trace[1733156071] 'process raft request' (duration: 231.327774ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.603497Z","caller":"traceutil/trace.go:171","msg":"trace[1168892752] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"232.190756ms","start":"2026-04-20T10:47:22.371269Z","end":"2026-04-20T10:47:22.60346Z","steps":["trace[1168892752] 'process raft request' (duration: 224.767448ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.603827Z","caller":"traceutil/trace.go:171","msg":"trace[1467721653] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"232.499ms","start":"2026-04-20T10:47:22.371315Z","end":"2026-04-20T10:47:22.603814Z","steps":["trace[1467721653] 'process raft request' (duration: 232.034214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.603938Z","caller":"traceutil/trace.go:171","msg":"trace[813165206] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"232.143525ms","start":"2026-04-20T10:47:22.371784Z","end":"2026-04-20T10:47:22.603928Z","steps":["trace[813165206] 'process raft request' (duration: 231.610807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.640953Z","caller":"traceutil/trace.go:171","msg":"trace[1230247791] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"218.664679ms","start":"2026-04-20T10:47:22.422227Z","end":"2026-04-20T10:47:22.640892Z","steps":["trace[1230247791] 'process raft request' (duration: 218.216723ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.641751Z","caller":"traceutil/trace.go:171","msg":"trace[1688315120] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"138.015707ms","start":"2026-04-20T10:47:22.503719Z","end":"2026-04-20T10:47:22.641735Z","steps":["trace[1688315120] 'process raft request' (duration: 137.112403ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.641848Z","caller":"traceutil/trace.go:171","msg":"trace[860236980] linearizableReadLoop","detail":"{readStateIndex:2800; appliedIndex:2795; }","duration":"143.247412ms","start":"2026-04-20T10:47:22.498563Z","end":"2026-04-20T10:47:22.641811Z","steps":["trace[860236980] 'read index received' (duration: 97.605048ms)","trace[860236980] 'applied index is now lower than readState.Index' (duration: 45.638894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:22.642336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.745849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a80ae2da2c32bb\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-20T10:47:22.642386Z","caller":"traceutil/trace.go:171","msg":"trace[887921377] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a80ae2da2c32bb; range_end:; response_count:1; response_revision:2689; }","duration":"143.859141ms","start":"2026-04-20T10:47:22.498514Z","end":"2026-04-20T10:47:22.642373Z","steps":["trace[887921377] 'agreement among raft nodes before linearized reading' (duration: 143.406825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:23.084234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.716794ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:23.084335Z","caller":"traceutil/trace.go:171","msg":"trace[2116724747] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2689; }","duration":"196.843936ms","start":"2026-04-20T10:47:22.887475Z","end":"2026-04-20T10:47:23.084319Z","steps":["trace[2116724747] 'range keys from in-memory index tree' (duration: 196.695764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:23.085365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.33342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:23.085534Z","caller":"traceutil/trace.go:171","msg":"trace[1099245733] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"483.97497ms","start":"2026-04-20T10:47:22.601527Z","end":"2026-04-20T10:47:23.085502Z","steps":["trace[1099245733] 'process raft request' (duration: 106.287035ms)","trace[1099245733] 'compare' (duration: 376.717111ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:23.085885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.601486Z","time spent":"484.287335ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:23.107687Z","caller":"traceutil/trace.go:171","msg":"trace[312352561] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2801; }","duration":"417.099817ms","start":"2026-04-20T10:47:22.690554Z","end":"2026-04-20T10:47:23.107654Z","steps":["trace[312352561] 'read index received' (duration: 17.279061ms)","trace[312352561] 'applied index is now lower than readState.Index' (duration: 399.819696ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:23.10784Z","caller":"traceutil/trace.go:171","msg":"trace[1955439344] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"463.36605ms","start":"2026-04-20T10:47:22.644434Z","end":"2026-04-20T10:47:23.1078Z","steps":["trace[1955439344] 'process raft request' (duration: 463.027316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:23.108003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.417223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:23.108542Z","caller":"traceutil/trace.go:171","msg":"trace[12645251] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2691; }","duration":"418.026831ms","start":"2026-04-20T10:47:22.690483Z","end":"2026-04-20T10:47:23.108509Z","steps":["trace[12645251] 'agreement among raft nodes before linearized reading' (duration: 417.421782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:23.108666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.690453Z","time spent":"418.200284ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:23.108868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.644403Z","time spent":"463.822077ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:23.116525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.434577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:23.116588Z","caller":"traceutil/trace.go:171","msg":"trace[1563465503] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2692; }","duration":"114.544999ms","start":"2026-04-20T10:47:23.002028Z","end":"2026-04-20T10:47:23.116573Z","steps":["trace[1563465503] 'agreement among raft nodes before linearized reading' (duration: 114.418967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:23.116651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.764616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-20T10:47:23.116658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.547349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:47:23.116545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.374093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:23.116681Z","caller":"traceutil/trace.go:171","msg":"trace[659627319] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2692; }","duration":"170.790736ms","start":"2026-04-20T10:47:22.945879Z","end":"2026-04-20T10:47:23.11667Z","steps":["trace[659627319] 'agreement among raft nodes before linearized reading' (duration: 170.689915ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:23.116702Z","caller":"traceutil/trace.go:171","msg":"trace[13495299] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2692; }","duration":"114.63391ms","start":"2026-04-20T10:47:23.002055Z","end":"2026-04-20T10:47:23.116689Z","steps":["trace[13495299] 'agreement among raft nodes before linearized reading' (duration: 114.560969ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:23.11674Z","caller":"traceutil/trace.go:171","msg":"trace[2104527337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2692; }","duration":"219.594836ms","start":"2026-04-20T10:47:22.897117Z","end":"2026-04-20T10:47:23.116712Z","steps":["trace[2104527337] 'agreement among raft nodes before linearized reading' (duration: 219.350362ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:23.117059Z","caller":"traceutil/trace.go:171","msg":"trace[770444975] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"180.428647ms","start":"2026-04-20T10:47:22.93662Z","end":"2026-04-20T10:47:23.117048Z","steps":["trace[770444975] 'process raft request' (duration: 179.657966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:23.117221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.918704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:23.11738Z","caller":"traceutil/trace.go:171","msg":"trace[1651151092] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2692; }","duration":"113.148748ms","start":"2026-04-20T10:47:23.004219Z","end":"2026-04-20T10:47:23.117368Z","steps":["trace[1651151092] 'agreement among raft nodes before linearized reading' (duration: 112.872874ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:15.069065Z","caller":"traceutil/trace.go:171","msg":"trace[555968020] linearizableReadLoop","detail":"{readStateIndex:3148; appliedIndex:3146; }","duration":"200.290976ms","start":"2026-04-20T10:48:14.86874Z","end":"2026-04-20T10:48:15.06903Z","steps":["trace[555968020] 'read index received' (duration: 6.266237ms)","trace[555968020] 'applied index is now lower than readState.Index' (duration: 194.022989ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:15.069133Z","caller":"traceutil/trace.go:171","msg":"trace[1271744674] transaction","detail":"{read_only:false; response_revision:3025; number_of_response:1; }","duration":"206.061435ms","start":"2026-04-20T10:48:14.863025Z","end":"2026-04-20T10:48:15.069086Z","steps":["trace[1271744674] 'process raft request' (duration: 204.541661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:15.069445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.021014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T10:48:15.069525Z","caller":"traceutil/trace.go:171","msg":"trace[634995498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3025; }","duration":"164.106675ms","start":"2026-04-20T10:48:14.9054Z","end":"2026-04-20T10:48:15.069506Z","steps":["trace[634995498] 'agreement among raft nodes before linearized reading' (duration: 163.930142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:15.069504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.310409ms","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-20T10:48:15.069583Z","caller":"traceutil/trace.go:171","msg":"trace[1964507304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3025; }","duration":"195.41354ms","start":"2026-04-20T10:48:14.874151Z","end":"2026-04-20T10:48:15.069565Z","steps":["trace[1964507304] 'agreement among raft nodes before linearized reading' (duration: 195.157776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:15.069602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.770533ms","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-20T10:48:15.069654Z","caller":"traceutil/trace.go:171","msg":"trace[416852124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3025; }","duration":"200.930815ms","start":"2026-04-20T10:48:14.86871Z","end":"2026-04-20T10:48:15.069641Z","steps":["trace[416852124] 'agreement among raft nodes before linearized reading' (duration: 200.481698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:15.07066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.116749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:15.070758Z","caller":"traceutil/trace.go:171","msg":"trace[369121113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3025; }","duration":"174.228782ms","start":"2026-04-20T10:48:14.896489Z","end":"2026-04-20T10:48:15.070718Z","steps":["trace[369121113] 'agreement among raft nodes before linearized reading' (duration: 172.937662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.385779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.881176ms","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-20T10:48:40.385982Z","caller":"traceutil/trace.go:171","msg":"trace[65593164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3168; }","duration":"157.199941ms","start":"2026-04-20T10:48:40.228736Z","end":"2026-04-20T10:48:40.385935Z","steps":["trace[65593164] 'range keys from in-memory index tree' (duration: 156.523891ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:52:04.5356Z","caller":"traceutil/trace.go:171","msg":"trace[1855942358] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"108.88534ms","start":"2026-04-20T10:52:04.426687Z","end":"2026-04-20T10:52:04.535572Z","steps":["trace[1855942358] 'process raft request' (duration: 108.813028ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:52:04.536166Z","caller":"traceutil/trace.go:171","msg":"trace[1409828007] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"111.545695ms","start":"2026-04-20T10:52:04.424602Z","end":"2026-04-20T10:52:04.536147Z","steps":["trace[1409828007] 'process raft request' (duration: 96.451027ms)","trace[1409828007] 'compare' (duration: 14.290124ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:52:50.385136Z","caller":"traceutil/trace.go:171","msg":"trace[387525214] linearizableReadLoop","detail":"{readStateIndex:5760; appliedIndex:5759; }","duration":"109.978079ms","start":"2026-04-20T10:52:50.275134Z","end":"2026-04-20T10:52:50.385113Z","steps":["trace[387525214] 'read index received' (duration: 109.855007ms)","trace[387525214] 'applied index is now lower than readState.Index' (duration: 122.202µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:52:50.385201Z","caller":"traceutil/trace.go:171","msg":"trace[979503615] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"130.573402ms","start":"2026-04-20T10:52:50.254594Z","end":"2026-04-20T10:52:50.385167Z","steps":["trace[979503615] 'process raft request' (duration: 130.40684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:52:50.385344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.184292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T10:52:50.385379Z","caller":"traceutil/trace.go:171","msg":"trace[1126312859] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5544; }","duration":"110.274084ms","start":"2026-04-20T10:52:50.275094Z","end":"2026-04-20T10:52:50.385368Z","steps":["trace[1126312859] 'agreement among raft nodes before linearized reading' (duration: 110.132061ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:52:58.197519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-04-20T10:52:58.294566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"95.807827ms","hash":1091210867,"current-db-size-bytes":54890496,"current-db-size":"55 MB","current-db-size-in-use-bytes":30593024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-20T10:52:58.294641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1091210867,"revision":2896,"compact-revision":-1}