{"level":"warn","ts":"2026-10-14T02:47:16.640502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T02:47:16.640595Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.28:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.28:2380","--initial-cluster=instance=https://162.253.55.28:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.28:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.28: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-10-14T02:47:16.64066Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T02:47:16.640672Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-10-14T02:47:16.6407Z","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-10-14T02:47:16.641536Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"]} {"level":"info","ts":"2026-10-14T02:47:16.641636Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.28: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-10-14T02:47:16.64637Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.485522ms"} {"level":"info","ts":"2026-10-14T02:47:16.65762Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"328ca0518abde8c2","cluster-id":"3b57b7d6a07cbbf8"} {"level":"info","ts":"2026-10-14T02:47:16.657694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T02:47:16.65771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 0"} {"level":"info","ts":"2026-10-14T02:47:16.65772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 328ca0518abde8c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T02:47:16.657732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 1"} {"level":"info","ts":"2026-10-14T02:47:16.657777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"warn","ts":"2026-10-14T02:47:16.665003Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T02:47:16.667071Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T02:47:16.668382Z","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-10-14T02:47:16.670836Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"328ca0518abde8c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T02:47:16.671011Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"328ca0518abde8c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T02:47:16.671191Z","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-10-14T02:47:16.671297Z","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-10-14T02:47:16.671308Z","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-10-14T02:47:16.672881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"info","ts":"2026-10-14T02:47:16.673049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","added-peer-id":"328ca0518abde8c2","added-peer-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-10-14T02:47:16.677454Z","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-10-14T02:47:16.677743Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"328ca0518abde8c2","initial-advertise-peer-urls":["https://162.253.55.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T02:47:16.677787Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T02:47:16.677929Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-10-14T02:47:16.677945Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-10-14T02:47:16.758564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T02:47:16.758622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T02:47:16.758644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgPreVoteResp from 328ca0518abde8c2 at term 1"} {"level":"info","ts":"2026-10-14T02:47:16.758662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became candidate at term 2"} {"level":"info","ts":"2026-10-14T02:47:16.758673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgVoteResp from 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-10-14T02:47:16.758698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became leader at term 2"} {"level":"info","ts":"2026-10-14T02:47:16.758731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 328ca0518abde8c2 elected leader 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-10-14T02:47:16.760098Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"328ca0518abde8c2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.28:2379]}","request-path":"/0/members/328ca0518abde8c2/attributes","cluster-id":"3b57b7d6a07cbbf8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T02:47:16.760142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:47:16.760335Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:47:16.760706Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:47:16.76073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T02:47:16.760825Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T02:47:16.761693Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:47:16.761782Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:47:16.761814Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:47:16.763999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T02:47:16.764341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.28:2379"} {"level":"info","ts":"2026-10-14T02:48:07.498042Z","caller":"traceutil/trace.go:171","msg":"trace[1472840618] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"125.370708ms","start":"2026-10-14T02:48:07.372638Z","end":"2026-10-14T02:48:07.498009Z","steps":["trace[1472840618] 'process raft request' (duration: 125.123661ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:48:08.260538Z","caller":"traceutil/trace.go:171","msg":"trace[101410237] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"543.886278ms","start":"2026-10-14T02:48:07.71658Z","end":"2026-10-14T02:48:08.260467Z","steps":["trace[101410237] 'process raft request' (duration: 542.755524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:48:08.261297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:48:07.716549Z","time spent":"544.095974ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:48:52.602264Z","caller":"traceutil/trace.go:171","msg":"trace[167255545] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"170.177934ms","start":"2026-10-14T02:48:52.432054Z","end":"2026-10-14T02:48:52.602232Z","steps":["trace[167255545] 'process raft request' (duration: 169.975148ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:49:30.964815Z","caller":"traceutil/trace.go:171","msg":"trace[472673163] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"119.573246ms","start":"2026-10-14T02:49:30.845216Z","end":"2026-10-14T02:49:30.964789Z","steps":["trace[472673163] 'process raft request' (duration: 117.621448ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:49:30.967697Z","caller":"traceutil/trace.go:171","msg":"trace[1176175373] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"121.822152ms","start":"2026-10-14T02:49:30.84585Z","end":"2026-10-14T02:49:30.967672Z","steps":["trace[1176175373] 'process raft request' (duration: 121.664078ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:49:36.013028Z","caller":"traceutil/trace.go:171","msg":"trace[1971357315] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"153.564247ms","start":"2026-10-14T02:49:35.859426Z","end":"2026-10-14T02:49:36.01299Z","steps":["trace[1971357315] 'read index received' (duration: 105.545389ms)","trace[1971357315] 'applied index is now lower than readState.Index' (duration: 48.016868ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:49:36.013217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.768373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:49:36.013317Z","caller":"traceutil/trace.go:171","msg":"trace[1072816777] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1793; }","duration":"153.915157ms","start":"2026-10-14T02:49:35.859381Z","end":"2026-10-14T02:49:36.013296Z","steps":["trace[1072816777] 'agreement among raft nodes before linearized reading' (duration: 153.743182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:49:40.471491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.068724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-14T02:49:40.471673Z","caller":"traceutil/trace.go:171","msg":"trace[1148433588] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1817; }","duration":"106.27284ms","start":"2026-10-14T02:49:40.365332Z","end":"2026-10-14T02:49:40.471604Z","steps":["trace[1148433588] 'range keys from in-memory index tree' (duration: 105.947871ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:49:45.224941Z","caller":"traceutil/trace.go:171","msg":"trace[149600218] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"308.153753ms","start":"2026-10-14T02:49:44.916761Z","end":"2026-10-14T02:49:45.224915Z","steps":["trace[149600218] 'process raft request' (duration: 307.979948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:49:45.225112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:49:44.916748Z","time spent":"308.275707ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:49:45.239838Z","caller":"traceutil/trace.go:171","msg":"trace[171819159] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"277.558084ms","start":"2026-10-14T02:49:44.962253Z","end":"2026-10-14T02:49:45.239811Z","steps":["trace[171819159] 'process raft request' (duration: 277.291195ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:49:45.240077Z","caller":"traceutil/trace.go:171","msg":"trace[845584740] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"209.273083ms","start":"2026-10-14T02:49:45.030784Z","end":"2026-10-14T02:49:45.240057Z","steps":["trace[845584740] 'read index received' (duration: 194.111792ms)","trace[845584740] 'applied index is now lower than readState.Index' (duration: 15.159661ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:49:45.240201Z","caller":"traceutil/trace.go:171","msg":"trace[1014509638] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"102.493207ms","start":"2026-10-14T02:49:45.137657Z","end":"2026-10-14T02:49:45.240151Z","steps":["trace[1014509638] 'process raft request' (duration: 102.095024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:49:45.240344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.553171ms","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-10-14T02:49:45.240386Z","caller":"traceutil/trace.go:171","msg":"trace[156812918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1914; }","duration":"209.639775ms","start":"2026-10-14T02:49:45.030735Z","end":"2026-10-14T02:49:45.240375Z","steps":["trace[156812918] 'agreement among raft nodes before linearized reading' (duration: 209.427648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:49:45.240535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.66779ms","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":"warn","ts":"2026-10-14T02:49:45.240528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.698471ms","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-10-14T02:49:45.240678Z","caller":"traceutil/trace.go:171","msg":"trace[1934465739] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1914; }","duration":"115.819834ms","start":"2026-10-14T02:49:45.124822Z","end":"2026-10-14T02:49:45.240642Z","steps":["trace[1934465739] 'agreement among raft nodes before linearized reading' (duration: 115.432883ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:49:45.240704Z","caller":"traceutil/trace.go:171","msg":"trace[1620871947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1914; }","duration":"186.931108ms","start":"2026-10-14T02:49:45.05374Z","end":"2026-10-14T02:49:45.240671Z","steps":["trace[1620871947] 'agreement among raft nodes before linearized reading' (duration: 186.493215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:49:52.444747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.962689ms","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-10-14T02:49:52.444866Z","caller":"traceutil/trace.go:171","msg":"trace[1635964711] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1983; }","duration":"110.127834ms","start":"2026-10-14T02:49:52.334696Z","end":"2026-10-14T02:49:52.444824Z","steps":["trace[1635964711] 'range keys from in-memory index tree' (duration: 109.710613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:49:52.444867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.893978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11977"} {"level":"info","ts":"2026-10-14T02:49:52.445005Z","caller":"traceutil/trace.go:171","msg":"trace[1639720996] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1983; }","duration":"109.072453ms","start":"2026-10-14T02:49:52.335901Z","end":"2026-10-14T02:49:52.444973Z","steps":["trace[1639720996] 'range keys from in-memory index tree' (duration: 108.667141ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:50:30.517132Z","caller":"traceutil/trace.go:171","msg":"trace[1155000435] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"150.512353ms","start":"2026-10-14T02:50:30.366554Z","end":"2026-10-14T02:50:30.517067Z","steps":["trace[1155000435] 'process raft request' (duration: 89.307181ms)","trace[1155000435] 'compare' (duration: 60.900233ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:50:36.517831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.093929ms","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-10-14T02:50:36.517905Z","caller":"traceutil/trace.go:171","msg":"trace[157631668] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2243; }","duration":"244.222543ms","start":"2026-10-14T02:50:36.273664Z","end":"2026-10-14T02:50:36.517886Z","steps":["trace[157631668] 'count revisions from in-memory index tree' (duration: 243.990955ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:55:03.205932Z","caller":"traceutil/trace.go:171","msg":"trace[1922324604] transaction","detail":"{read_only:false; response_revision:4059; number_of_response:1; }","duration":"176.538587ms","start":"2026-10-14T02:55:03.029366Z","end":"2026-10-14T02:55:03.205905Z","steps":["trace[1922324604] 'process raft request' (duration: 171.940044ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:27.192604Z","caller":"traceutil/trace.go:171","msg":"trace[858943764] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"115.324723ms","start":"2026-10-14T02:56:27.077244Z","end":"2026-10-14T02:56:27.192569Z","steps":["trace[858943764] 'process raft request' (duration: 109.211335ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:27.192688Z","caller":"traceutil/trace.go:171","msg":"trace[874667914] linearizableReadLoop","detail":"{readStateIndex:5279; appliedIndex:5273; }","duration":"112.448769ms","start":"2026-10-14T02:56:27.08022Z","end":"2026-10-14T02:56:27.192669Z","steps":["trace[874667914] 'read index received' (duration: 3.692808ms)","trace[874667914] 'applied index is now lower than readState.Index' (duration: 108.754651ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:56:27.192806Z","caller":"traceutil/trace.go:171","msg":"trace[1782886535] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"115.55104ms","start":"2026-10-14T02:56:27.077246Z","end":"2026-10-14T02:56:27.192797Z","steps":["trace[1782886535] 'process raft request' (duration: 115.274401ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:27.192984Z","caller":"traceutil/trace.go:171","msg":"trace[79558814] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"109.73818ms","start":"2026-10-14T02:56:27.083219Z","end":"2026-10-14T02:56:27.192957Z","steps":["trace[79558814] 'process raft request' (duration: 109.398299ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:27.193118Z","caller":"traceutil/trace.go:171","msg":"trace[1395616294] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"115.643953ms","start":"2026-10-14T02:56:27.077446Z","end":"2026-10-14T02:56:27.19309Z","steps":["trace[1395616294] 'process raft request' (duration: 115.102806ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:27.193185Z","caller":"traceutil/trace.go:171","msg":"trace[1613996027] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"113.15524ms","start":"2026-10-14T02:56:27.080018Z","end":"2026-10-14T02:56:27.193173Z","steps":["trace[1613996027] 'process raft request' (duration: 112.552872ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:27.193193Z","caller":"traceutil/trace.go:171","msg":"trace[1535660268] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"113.110778ms","start":"2026-10-14T02:56:27.080066Z","end":"2026-10-14T02:56:27.193177Z","steps":["trace[1535660268] 'process raft request' (duration: 112.528061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:56:27.193672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.423857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-14T02:56:27.193775Z","caller":"traceutil/trace.go:171","msg":"trace[844529432] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5094; }","duration":"113.612953ms","start":"2026-10-14T02:56:27.080144Z","end":"2026-10-14T02:56:27.193757Z","steps":["trace[844529432] 'agreement among raft nodes before linearized reading' (duration: 112.769849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:30.186038Z","caller":"traceutil/trace.go:171","msg":"trace[1287822283] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"128.320351ms","start":"2026-10-14T02:56:30.057695Z","end":"2026-10-14T02:56:30.186016Z","steps":["trace[1287822283] 'process raft request' (duration: 128.170557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:56:30.333024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.329523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:56:30.333125Z","caller":"traceutil/trace.go:171","msg":"trace[1996456263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5231; }","duration":"125.484068ms","start":"2026-10-14T02:56:30.207621Z","end":"2026-10-14T02:56:30.333105Z","steps":["trace[1996456263] 'range keys from in-memory index tree' (duration: 125.17592ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:34.825533Z","caller":"traceutil/trace.go:171","msg":"trace[1065338007] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"101.019366ms","start":"2026-10-14T02:56:34.72448Z","end":"2026-10-14T02:56:34.8255Z","steps":["trace[1065338007] 'process raft request' (duration: 87.11726ms)","trace[1065338007] 'compare' (duration: 13.642078ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:56:45.650902Z","caller":"traceutil/trace.go:171","msg":"trace[2118616881] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"125.418177ms","start":"2026-10-14T02:56:45.525443Z","end":"2026-10-14T02:56:45.650861Z","steps":["trace[2118616881] 'process raft request' (duration: 65.349216ms)","trace[2118616881] 'compare' (duration: 59.625818ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:56:46.03979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.615972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-10-14T02:56:53.862579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.190512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abc830f1-1826-4e06-b777-4ad634b64388\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-14T02:56:53.862674Z","caller":"traceutil/trace.go:171","msg":"trace[647835746] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abc830f1-1826-4e06-b777-4ad634b64388; range_end:; response_count:1; response_revision:5648; }","duration":"122.335726ms","start":"2026-10-14T02:56:53.740322Z","end":"2026-10-14T02:56:53.862657Z","steps":["trace[647835746] 'range keys from in-memory index tree' (duration: 121.987875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:56:53.862674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.944165ms","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:5"} {"level":"info","ts":"2026-10-14T02:56:53.862789Z","caller":"traceutil/trace.go:171","msg":"trace[1952056615] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5648; }","duration":"121.10812ms","start":"2026-10-14T02:56:53.741656Z","end":"2026-10-14T02:56:53.862764Z","steps":["trace[1952056615] 'count revisions from in-memory index tree' (duration: 120.844362ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:56:54.437522Z","caller":"traceutil/trace.go:171","msg":"trace[395272332] linearizableReadLoop","detail":"{readStateIndex:5854; appliedIndex:5853; }","duration":"251.567753ms","start":"2026-10-14T02:56:54.185928Z","end":"2026-10-14T02:56:54.437496Z","steps":["trace[395272332] 'read index received' (duration: 251.372217ms)","trace[395272332] 'applied index is now lower than readState.Index' (duration: 194.306µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:56:54.437593Z","caller":"traceutil/trace.go:171","msg":"trace[1493163926] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"312.54228ms","start":"2026-10-14T02:56:54.125016Z","end":"2026-10-14T02:56:54.437558Z","steps":["trace[1493163926] 'process raft request' (duration: 312.310073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:56:54.437791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:56:54.124988Z","time spent":"312.699184ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:56:54.437793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.83111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abc830f1-1826-4e06-b777-4ad634b64388\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-14T02:56:54.437859Z","caller":"traceutil/trace.go:171","msg":"trace[1871878661] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abc830f1-1826-4e06-b777-4ad634b64388; range_end:; response_count:1; response_revision:5652; }","duration":"251.954584ms","start":"2026-10-14T02:56:54.185884Z","end":"2026-10-14T02:56:54.437838Z","steps":["trace[1871878661] 'agreement among raft nodes before linearized reading' (duration: 251.713097ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:57:06.684018Z","caller":"traceutil/trace.go:171","msg":"trace[77477218] transaction","detail":"{read_only:false; response_revision:5833; number_of_response:1; }","duration":"113.894411ms","start":"2026-10-14T02:57:06.570089Z","end":"2026-10-14T02:57:06.683984Z","steps":["trace[77477218] 'process raft request' (duration: 105.301009ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:57:17.092331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-10-14T02:57:17.177592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"82.953917ms","hash":779761860,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T02:57:17.17767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779761860,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-10-14T02:57:46.240012Z","caller":"traceutil/trace.go:171","msg":"trace[625536992] linearizableReadLoop","detail":"{readStateIndex:6403; appliedIndex:6402; }","duration":"123.759368ms","start":"2026-10-14T02:57:46.116229Z","end":"2026-10-14T02:57:46.239988Z","steps":["trace[625536992] 'read index received' (duration: 123.545082ms)","trace[625536992] 'applied index is now lower than readState.Index' (duration: 213.416µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:57:46.240059Z","caller":"traceutil/trace.go:171","msg":"trace[2068094648] transaction","detail":"{read_only:false; response_revision:6184; number_of_response:1; }","duration":"126.686494ms","start":"2026-10-14T02:57:46.113349Z","end":"2026-10-14T02:57:46.240035Z","steps":["trace[2068094648] 'process raft request' (duration: 126.483078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:57:46.240143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.887712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:57:46.24018Z","caller":"traceutil/trace.go:171","msg":"trace[506367451] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:6184; }","duration":"123.964833ms","start":"2026-10-14T02:57:46.116205Z","end":"2026-10-14T02:57:46.24017Z","steps":["trace[506367451] 'agreement among raft nodes before linearized reading' (duration: 123.883091ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:02:17.109554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5906} {"level":"info","ts":"2026-10-14T03:02:17.281169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5906,"took":"169.891012ms","hash":3131794764,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":26693632,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-14T03:02:17.281242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3131794764,"revision":5906,"compact-revision":2981} {"level":"info","ts":"2026-10-14T03:03:11.123922Z","caller":"traceutil/trace.go:171","msg":"trace[2061116666] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"313.438386ms","start":"2026-10-14T03:03:10.810459Z","end":"2026-10-14T03:03:11.123897Z","steps":["trace[2061116666] 'process raft request' (duration: 313.25338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:11.124078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:10.810441Z","time spent":"313.565109ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:03:11.352446Z","caller":"traceutil/trace.go:171","msg":"trace[23071915] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"284.353966ms","start":"2026-10-14T03:03:11.068036Z","end":"2026-10-14T03:03:11.35239Z","steps":["trace[23071915] 'process raft request' (duration: 266.682284ms)","trace[23071915] 'compare' (duration: 17.291651ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:03:22.264115Z","caller":"traceutil/trace.go:171","msg":"trace[2003781810] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8919; }","duration":"154.276703ms","start":"2026-10-14T03:03:22.10981Z","end":"2026-10-14T03:03:22.264087Z","steps":["trace[2003781810] 'read index received' (duration: 109.890653ms)","trace[2003781810] 'applied index is now lower than readState.Index' (duration: 44.38497ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:03:22.264444Z","caller":"traceutil/trace.go:171","msg":"trace[222476991] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"277.726707ms","start":"2026-10-14T03:03:21.986698Z","end":"2026-10-14T03:03:22.264424Z","steps":["trace[222476991] 'process raft request' (duration: 233.082489ms)","trace[222476991] 'compare' (duration: 44.193365ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:03:22.26481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.943433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T03:03:22.264906Z","caller":"traceutil/trace.go:171","msg":"trace[1478405939] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8622; }","duration":"155.040146ms","start":"2026-10-14T03:03:22.109796Z","end":"2026-10-14T03:03:22.264836Z","steps":["trace[1478405939] 'agreement among raft nodes before linearized reading' (duration: 154.387647ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:23.712175Z","caller":"traceutil/trace.go:171","msg":"trace[1736665895] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"107.348659ms","start":"2026-10-14T03:03:23.604797Z","end":"2026-10-14T03:03:23.712146Z","steps":["trace[1736665895] 'process raft request' (duration: 107.158124ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:34.347663Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002321.wal"} {"level":"warn","ts":"2026-10-14T03:03:52.616161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.932744ms","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-10-14T03:03:52.616311Z","caller":"traceutil/trace.go:171","msg":"trace[373970436] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8834; }","duration":"298.085939ms","start":"2026-10-14T03:03:52.318201Z","end":"2026-10-14T03:03:52.616287Z","steps":["trace[373970436] 'range keys from in-memory index tree' (duration: 297.917492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:52.61671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.79741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T03:03:52.61685Z","caller":"traceutil/trace.go:171","msg":"trace[2129395001] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"523.76498ms","start":"2026-10-14T03:03:52.093059Z","end":"2026-10-14T03:03:52.616824Z","steps":["trace[2129395001] 'process raft request' (duration: 348.741303ms)","trace[2129395001] 'compare' (duration: 174.325866ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:03:52.617431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:52.093034Z","time spent":"524.002347ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:03:52.709675Z","caller":"traceutil/trace.go:171","msg":"trace[1587774972] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"610.50149ms","start":"2026-10-14T03:03:52.099148Z","end":"2026-10-14T03:03:52.709649Z","steps":["trace[1587774972] 'process raft request' (duration: 610.322695ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:52.709815Z","caller":"traceutil/trace.go:171","msg":"trace[803612823] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"341.280943ms","start":"2026-10-14T03:03:52.368519Z","end":"2026-10-14T03:03:52.7098Z","steps":["trace[803612823] 'process raft request' (duration: 341.076357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:03:52.709843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:52.099131Z","time spent":"610.637935ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:03:52.709896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:03:52.368485Z","time spent":"341.358855ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:03:52.710377Z","caller":"traceutil/trace.go:171","msg":"trace[1464301994] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"123.379352ms","start":"2026-10-14T03:03:52.586976Z","end":"2026-10-14T03:03:52.710355Z","steps":["trace[1464301994] 'process raft request' (duration: 123.255908ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:54.81698Z","caller":"traceutil/trace.go:171","msg":"trace[1923748995] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"193.623075ms","start":"2026-10-14T03:03:54.623332Z","end":"2026-10-14T03:03:54.816955Z","steps":["trace[1923748995] 'process raft request' (duration: 193.395178ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:54.955077Z","caller":"traceutil/trace.go:171","msg":"trace[185279843] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"239.340265ms","start":"2026-10-14T03:03:54.715687Z","end":"2026-10-14T03:03:54.955028Z","steps":["trace[185279843] 'process raft request' (duration: 238.445268ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:54.955341Z","caller":"traceutil/trace.go:171","msg":"trace[2026360] linearizableReadLoop","detail":"{readStateIndex:9154; appliedIndex:9152; }","duration":"104.066702ms","start":"2026-10-14T03:03:54.851225Z","end":"2026-10-14T03:03:54.955292Z","steps":["trace[2026360] 'read index received' (duration: 102.844895ms)","trace[2026360] 'applied index is now lower than readState.Index' (duration: 1.220546ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:03:54.955484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.243146ms","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-10-14T03:03:54.955532Z","caller":"traceutil/trace.go:171","msg":"trace[1094173634] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8847; }","duration":"104.330219ms","start":"2026-10-14T03:03:54.851175Z","end":"2026-10-14T03:03:54.955505Z","steps":["trace[1094173634] 'agreement among raft nodes before linearized reading' (duration: 104.189745ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:03:54.955791Z","caller":"traceutil/trace.go:171","msg":"trace[1816690340] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"239.969213ms","start":"2026-10-14T03:03:54.715782Z","end":"2026-10-14T03:03:54.955751Z","steps":["trace[1816690340] 'process raft request' (duration: 239.142549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:04:03.207311Z","caller":"traceutil/trace.go:171","msg":"trace[1449497336] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"214.973846ms","start":"2026-10-14T03:04:02.992311Z","end":"2026-10-14T03:04:03.207285Z","steps":["trace[1449497336] 'process raft request' (duration: 214.814061ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:04:03.327463Z","caller":"traceutil/trace.go:171","msg":"trace[981220670] linearizableReadLoop","detail":"{readStateIndex:9202; appliedIndex:9200; }","duration":"260.082347ms","start":"2026-10-14T03:04:03.067352Z","end":"2026-10-14T03:04:03.327434Z","steps":["trace[981220670] 'read index received' (duration: 139.725075ms)","trace[981220670] 'applied index is now lower than readState.Index' (duration: 120.356182ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:04:03.327603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.230761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T03:04:03.327641Z","caller":"traceutil/trace.go:171","msg":"trace[26439740] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8893; }","duration":"260.318534ms","start":"2026-10-14T03:04:03.067311Z","end":"2026-10-14T03:04:03.32763Z","steps":["trace[26439740] 'agreement among raft nodes before linearized reading' (duration: 260.229211ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:04:03.327684Z","caller":"traceutil/trace.go:171","msg":"trace[268812988] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"267.154216ms","start":"2026-10-14T03:04:03.060425Z","end":"2026-10-14T03:04:03.327579Z","steps":["trace[268812988] 'process raft request' (duration: 169.262296ms)","trace[268812988] 'compare' (duration: 97.626561ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:04:05.024189Z","caller":"traceutil/trace.go:171","msg":"trace[1214938953] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"108.154762ms","start":"2026-10-14T03:04:04.916006Z","end":"2026-10-14T03:04:05.024161Z","steps":["trace[1214938953] 'process raft request' (duration: 102.292259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:04:41.234187Z","caller":"traceutil/trace.go:171","msg":"trace[269318928] linearizableReadLoop","detail":"{readStateIndex:9603; appliedIndex:9602; }","duration":"109.43123ms","start":"2026-10-14T03:04:41.124732Z","end":"2026-10-14T03:04:41.234163Z","steps":["trace[269318928] 'read index received' (duration: 109.188003ms)","trace[269318928] 'applied index is now lower than readState.Index' (duration: 242.157µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:04:41.234316Z","caller":"traceutil/trace.go:171","msg":"trace[1878241152] transaction","detail":"{read_only:false; response_revision:9283; number_of_response:1; }","duration":"148.865614ms","start":"2026-10-14T03:04:41.085391Z","end":"2026-10-14T03:04:41.234257Z","steps":["trace[1878241152] 'process raft request' (duration: 148.622287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:04:41.234342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.595535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T03:04:41.23463Z","caller":"traceutil/trace.go:171","msg":"trace[2138950505] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:9283; }","duration":"109.924394ms","start":"2026-10-14T03:04:41.124679Z","end":"2026-10-14T03:04:41.234604Z","steps":["trace[2138950505] 'agreement among raft nodes before linearized reading' (duration: 109.599165ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:04:41.397143Z","caller":"traceutil/trace.go:171","msg":"trace[1078850861] linearizableReadLoop","detail":"{readStateIndex:9604; appliedIndex:9603; }","duration":"120.810806ms","start":"2026-10-14T03:04:41.276306Z","end":"2026-10-14T03:04:41.397117Z","steps":["trace[1078850861] 'read index received' (duration: 56.680514ms)","trace[1078850861] 'applied index is now lower than readState.Index' (duration: 64.129142ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:04:41.39727Z","caller":"traceutil/trace.go:171","msg":"trace[1423043441] transaction","detail":"{read_only:false; response_revision:9284; number_of_response:1; }","duration":"138.124727ms","start":"2026-10-14T03:04:41.259094Z","end":"2026-10-14T03:04:41.397219Z","steps":["trace[1423043441] 'process raft request' (duration: 73.953933ms)","trace[1423043441] 'compare' (duration: 63.963848ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:04:41.397303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.9825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T03:04:41.397482Z","caller":"traceutil/trace.go:171","msg":"trace[1024078155] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:9284; }","duration":"121.197487ms","start":"2026-10-14T03:04:41.276248Z","end":"2026-10-14T03:04:41.397445Z","steps":["trace[1024078155] 'agreement among raft nodes before linearized reading' (duration: 120.99176ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:05:18.58368Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"328ca0518abde8c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T03:05:18.59311Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T03:05:18.59323Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T03:05:32.224081Z","caller":"traceutil/trace.go:171","msg":"trace[606323646] transaction","detail":"{read_only:false; response_revision:9747; number_of_response:1; }","duration":"239.178048ms","start":"2026-10-14T03:05:31.984858Z","end":"2026-10-14T03:05:32.224036Z","steps":["trace[606323646] 'process raft request' (duration: 239.013154ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:05:32.365055Z","caller":"traceutil/trace.go:171","msg":"trace[452927523] transaction","detail":"{read_only:false; response_revision:9748; number_of_response:1; }","duration":"352.134963ms","start":"2026-10-14T03:05:32.012892Z","end":"2026-10-14T03:05:32.365027Z","steps":["trace[452927523] 'process raft request' (duration: 316.224073ms)","trace[452927523] 'compare' (duration: 35.5474ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:05:32.365261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:05:32.012879Z","time spent":"352.273627ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:07:17.117191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8073} {"level":"info","ts":"2026-10-14T03:07:17.196249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8073,"took":"76.451875ms","hash":3593355106,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":28848128,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T03:07:17.196337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593355106,"revision":8073,"compact-revision":5906} {"level":"info","ts":"2026-10-14T03:08:58.341966Z","caller":"traceutil/trace.go:171","msg":"trace[168223877] transaction","detail":"{read_only:false; response_revision:11494; number_of_response:1; }","duration":"121.230213ms","start":"2026-10-14T03:08:58.220705Z","end":"2026-10-14T03:08:58.341935Z","steps":["trace[168223877] 'process raft request' (duration: 120.931204ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:09:16.601366Z","caller":"traceutil/trace.go:171","msg":"trace[492077506] transaction","detail":"{read_only:false; response_revision:11601; number_of_response:1; }","duration":"100.509227ms","start":"2026-10-14T03:09:16.500816Z","end":"2026-10-14T03:09:16.601325Z","steps":["trace[492077506] 'process raft request' (duration: 100.296531ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:12:17.126512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10504} {"level":"info","ts":"2026-10-14T03:12:17.216874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10504,"took":"87.354353ms","hash":2524074808,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":27856896,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T03:12:17.216954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2524074808,"revision":10504,"compact-revision":8073} {"level":"info","ts":"2026-10-14T03:16:00.893722Z","caller":"traceutil/trace.go:171","msg":"trace[400646351] linearizableReadLoop","detail":"{readStateIndex:14766; appliedIndex:14765; }","duration":"458.596675ms","start":"2026-10-14T03:16:00.4351Z","end":"2026-10-14T03:16:00.893697Z","steps":["trace[400646351] 'read index received' (duration: 458.342258ms)","trace[400646351] 'applied index is now lower than readState.Index' (duration: 253.507µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:16:00.893782Z","caller":"traceutil/trace.go:171","msg":"trace[1609415361] transaction","detail":"{read_only:false; response_revision:14287; number_of_response:1; }","duration":"461.503359ms","start":"2026-10-14T03:16:00.432242Z","end":"2026-10-14T03:16:00.893746Z","steps":["trace[1609415361] 'process raft request' (duration: 461.283923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:16:00.893926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.918794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.28\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-14T03:16:00.893947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:16:00.432195Z","time spent":"461.662403ms","remote":"127.0.0.1:58830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11811,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:16:00.893987Z","caller":"traceutil/trace.go:171","msg":"trace[1994332536] range","detail":"{range_begin:/registry/masterleases/162.253.55.28; range_end:; response_count:1; response_revision:14287; }","duration":"459.015067ms","start":"2026-10-14T03:16:00.434962Z","end":"2026-10-14T03:16:00.893977Z","steps":["trace[1994332536] 'agreement among raft nodes before linearized reading' (duration: 458.839232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:16:00.894021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:16:00.434951Z","time spent":"459.061778ms","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.28\" "} {"level":"info","ts":"2026-10-14T03:16:02.817877Z","caller":"traceutil/trace.go:171","msg":"trace[3276777] transaction","detail":"{read_only:false; response_revision:14308; number_of_response:1; }","duration":"103.67699ms","start":"2026-10-14T03:16:02.714173Z","end":"2026-10-14T03:16:02.81785Z","steps":["trace[3276777] 'process raft request' (duration: 98.475478ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:16:11.909633Z","caller":"traceutil/trace.go:171","msg":"trace[662775701] linearizableReadLoop","detail":"{readStateIndex:14945; appliedIndex:14944; }","duration":"106.269172ms","start":"2026-10-14T03:16:11.803329Z","end":"2026-10-14T03:16:11.909598Z","steps":["trace[662775701] 'read index received' (duration: 105.88558ms)","trace[662775701] 'applied index is now lower than readState.Index' (duration: 381.812µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:16:11.909694Z","caller":"traceutil/trace.go:171","msg":"trace[1133241394] transaction","detail":"{read_only:false; response_revision:14462; number_of_response:1; }","duration":"292.737152ms","start":"2026-10-14T03:16:11.616914Z","end":"2026-10-14T03:16:11.909651Z","steps":["trace[1133241394] 'process raft request' (duration: 292.409142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:16:11.909911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.56578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mc7gw\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-10-14T03:16:11.909989Z","caller":"traceutil/trace.go:171","msg":"trace[1773452926] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mc7gw; range_end:; response_count:1; response_revision:14462; }","duration":"106.711814ms","start":"2026-10-14T03:16:11.803259Z","end":"2026-10-14T03:16:11.90997Z","steps":["trace[1773452926] 'agreement among raft nodes before linearized reading' (duration: 106.468087ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:17.135874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12788} {"level":"info","ts":"2026-10-14T03:17:17.231275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12788,"took":"90.661815ms","hash":2867267080,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":30986240,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T03:17:17.231368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2867267080,"revision":12788,"compact-revision":10504} {"level":"info","ts":"2026-10-14T03:17:47.984328Z","caller":"traceutil/trace.go:171","msg":"trace[1699570081] transaction","detail":"{read_only:false; response_revision:15535; number_of_response:1; }","duration":"102.219413ms","start":"2026-10-14T03:17:47.882079Z","end":"2026-10-14T03:17:47.984298Z","steps":["trace[1699570081] 'process raft request' (duration: 102.017847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.947448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.150245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T03:17:48.947529Z","caller":"traceutil/trace.go:171","msg":"trace[861915637] linearizableReadLoop","detail":"{readStateIndex:16056; appliedIndex:16055; }","duration":"272.687934ms","start":"2026-10-14T03:17:48.674827Z","end":"2026-10-14T03:17:48.947515Z","steps":["trace[861915637] 'read index received' (duration: 148.333013ms)","trace[861915637] 'applied index is now lower than readState.Index' (duration: 124.354071ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:48.947802Z","caller":"traceutil/trace.go:171","msg":"trace[880763010] transaction","detail":"{read_only:false; response_revision:15540; number_of_response:1; }","duration":"283.778468ms","start":"2026-10-14T03:17:48.664013Z","end":"2026-10-14T03:17:48.947792Z","steps":["trace[880763010] 'process raft request' (duration: 159.216251ms)","trace[880763010] 'compare' (duration: 124.041932ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:17:48.948056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.608121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T03:17:48.948092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.26082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T03:17:48.948114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.875721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-14T03:17:48.94817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.539507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-10-14T03:17:48.948238Z","caller":"traceutil/trace.go:171","msg":"trace[916026053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15540; }","duration":"270.021025ms","start":"2026-10-14T03:17:48.678204Z","end":"2026-10-14T03:17:48.948225Z","steps":["trace[916026053] 'agreement among raft nodes before linearized reading' (duration: 269.720816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:48.948212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.699608ms","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":"warn","ts":"2026-10-14T03:17:48.94831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.14427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T03:17:48.948113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.424402ms","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-10-14T03:17:48.948367Z","caller":"traceutil/trace.go:171","msg":"trace[1399166748] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:15540; }","duration":"132.275344ms","start":"2026-10-14T03:17:48.816061Z","end":"2026-10-14T03:17:48.948336Z","steps":["trace[1399166748] 'agreement among raft nodes before linearized reading' (duration: 131.866171ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.948393Z","caller":"traceutil/trace.go:171","msg":"trace[490130846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15540; }","duration":"263.738863ms","start":"2026-10-14T03:17:48.684644Z","end":"2026-10-14T03:17:48.948383Z","steps":["trace[490130846] 'agreement among raft nodes before linearized reading' (duration: 263.33377ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.948261Z","caller":"traceutil/trace.go:171","msg":"trace[278167954] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15540; }","duration":"123.670452ms","start":"2026-10-14T03:17:48.824573Z","end":"2026-10-14T03:17:48.948243Z","steps":["trace[278167954] 'agreement among raft nodes before linearized reading' (duration: 123.412313ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.948369Z","caller":"traceutil/trace.go:171","msg":"trace[775263497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15540; }","duration":"252.845503ms","start":"2026-10-14T03:17:48.695473Z","end":"2026-10-14T03:17:48.948319Z","steps":["trace[775263497] 'agreement among raft nodes before linearized reading' (duration: 252.464361ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.948148Z","caller":"traceutil/trace.go:171","msg":"trace[259764212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15540; }","duration":"273.347423ms","start":"2026-10-14T03:17:48.674787Z","end":"2026-10-14T03:17:48.948134Z","steps":["trace[259764212] 'agreement among raft nodes before linearized reading' (duration: 273.125616ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:48.948095Z","caller":"traceutil/trace.go:171","msg":"trace[22002660] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:15540; }","duration":"118.705964ms","start":"2026-10-14T03:17:48.829377Z","end":"2026-10-14T03:17:48.948083Z","steps":["trace[22002660] 'agreement among raft nodes before linearized reading' (duration: 118.578641ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:49.08718Z","caller":"traceutil/trace.go:171","msg":"trace[264038898] transaction","detail":"{read_only:false; response_revision:15541; number_of_response:1; }","duration":"134.643822ms","start":"2026-10-14T03:17:48.952509Z","end":"2026-10-14T03:17:49.087153Z","steps":["trace[264038898] 'process raft request' (duration: 116.850701ms)","trace[264038898] 'compare' (duration: 17.458081ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:49.087891Z","caller":"traceutil/trace.go:171","msg":"trace[1544980111] transaction","detail":"{read_only:false; response_revision:15542; number_of_response:1; }","duration":"134.977541ms","start":"2026-10-14T03:17:48.952897Z","end":"2026-10-14T03:17:49.087874Z","steps":["trace[1544980111] 'process raft request' (duration: 134.181979ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:49.088358Z","caller":"traceutil/trace.go:171","msg":"trace[1626111716] linearizableReadLoop","detail":"{readStateIndex:16060; appliedIndex:16056; }","duration":"108.846727ms","start":"2026-10-14T03:17:48.979498Z","end":"2026-10-14T03:17:49.088345Z","steps":["trace[1626111716] 'read index received' (duration: 89.83717ms)","trace[1626111716] 'applied index is now lower than readState.Index' (duration: 19.008547ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:17:49.088461Z","caller":"traceutil/trace.go:171","msg":"trace[679121237] transaction","detail":"{read_only:false; response_revision:15543; number_of_response:1; }","duration":"135.146318ms","start":"2026-10-14T03:17:48.953293Z","end":"2026-10-14T03:17:49.088439Z","steps":["trace[679121237] 'process raft request' (duration: 134.472157ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:17:49.088547Z","caller":"traceutil/trace.go:171","msg":"trace[94564014] transaction","detail":"{read_only:false; response_revision:15544; number_of_response:1; }","duration":"134.876609ms","start":"2026-10-14T03:17:48.953658Z","end":"2026-10-14T03:17:49.088535Z","steps":["trace[94564014] 'process raft request' (duration: 134.634362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:17:49.088855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.338871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-xlmb6\" ","response":"range_response_count:1 size:9711"} {"level":"info","ts":"2026-10-14T03:17:49.088899Z","caller":"traceutil/trace.go:171","msg":"trace[2040376597] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-xlmb6; range_end:; response_count:1; response_revision:15544; }","duration":"109.416423ms","start":"2026-10-14T03:17:48.979472Z","end":"2026-10-14T03:17:49.088888Z","steps":["trace[2040376597] 'agreement among raft nodes before linearized reading' (duration: 109.2832ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:22:17.145018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15374} {"level":"info","ts":"2026-10-14T03:22:17.248168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15374,"took":"98.327395ms","hash":835857021,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":31973376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T03:22:17.248235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":835857021,"revision":15374,"compact-revision":12788} {"level":"info","ts":"2026-10-14T03:23:28.874321Z","caller":"traceutil/trace.go:171","msg":"trace[287992280] linearizableReadLoop","detail":"{readStateIndex:18658; appliedIndex:18657; }","duration":"147.785661ms","start":"2026-10-14T03:23:28.726511Z","end":"2026-10-14T03:23:28.874296Z","steps":["trace[287992280] 'read index received' (duration: 147.569975ms)","trace[287992280] 'applied index is now lower than readState.Index' (duration: 214.626µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:23:28.874649Z","caller":"traceutil/trace.go:171","msg":"trace[602804896] transaction","detail":"{read_only:false; response_revision:18059; number_of_response:1; }","duration":"149.061089ms","start":"2026-10-14T03:23:28.725575Z","end":"2026-10-14T03:23:28.874636Z","steps":["trace[602804896] 'process raft request' (duration: 148.572205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:28.874966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.434691ms","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-10-14T03:23:28.875008Z","caller":"traceutil/trace.go:171","msg":"trace[1061231328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18059; }","duration":"148.516683ms","start":"2026-10-14T03:23:28.72648Z","end":"2026-10-14T03:23:28.874996Z","steps":["trace[1061231328] 'agreement among raft nodes before linearized reading' (duration: 148.350058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:28.875211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.525124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-jr5h2\" ","response":"range_response_count:1 size:15805"} {"level":"info","ts":"2026-10-14T03:23:28.875248Z","caller":"traceutil/trace.go:171","msg":"trace[909682618] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-jr5h2; range_end:; response_count:1; response_revision:18059; }","duration":"148.586395ms","start":"2026-10-14T03:23:28.726649Z","end":"2026-10-14T03:23:28.875236Z","steps":["trace[909682618] 'agreement among raft nodes before linearized reading' (duration: 148.483132ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:35.259961Z","caller":"traceutil/trace.go:171","msg":"trace[1205362044] transaction","detail":"{read_only:false; response_revision:18157; number_of_response:1; }","duration":"227.341308ms","start":"2026-10-14T03:23:35.032582Z","end":"2026-10-14T03:23:35.259923Z","steps":["trace[1205362044] 'process raft request' (duration: 227.06477ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:35.269101Z","caller":"traceutil/trace.go:171","msg":"trace[615216334] linearizableReadLoop","detail":"{readStateIndex:18759; appliedIndex:18756; }","duration":"229.058188ms","start":"2026-10-14T03:23:35.040021Z","end":"2026-10-14T03:23:35.269079Z","steps":["trace[615216334] 'read index received' (duration: 219.735686ms)","trace[615216334] 'applied index is now lower than readState.Index' (duration: 9.321612ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:23:35.269183Z","caller":"traceutil/trace.go:171","msg":"trace[823403563] transaction","detail":"{read_only:false; response_revision:18158; number_of_response:1; }","duration":"236.379422ms","start":"2026-10-14T03:23:35.032743Z","end":"2026-10-14T03:23:35.269122Z","steps":["trace[823403563] 'process raft request' (duration: 236.090764ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:35.269224Z","caller":"traceutil/trace.go:171","msg":"trace[261147501] transaction","detail":"{read_only:false; response_revision:18159; number_of_response:1; }","duration":"236.419445ms","start":"2026-10-14T03:23:35.032749Z","end":"2026-10-14T03:23:35.269168Z","steps":["trace[261147501] 'process raft request' (duration: 236.27844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:35.269377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.346408ms","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-10-14T03:23:35.269428Z","caller":"traceutil/trace.go:171","msg":"trace[1865852276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18159; }","duration":"229.453021ms","start":"2026-10-14T03:23:35.039963Z","end":"2026-10-14T03:23:35.269416Z","steps":["trace[1865852276] 'agreement among raft nodes before linearized reading' (duration: 229.275886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:36.353139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.623316ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-14T03:23:36.38649Z","caller":"traceutil/trace.go:171","msg":"trace[811622472] linearizableReadLoop","detail":"{readStateIndex:18764; appliedIndex:18761; }","duration":"495.938924ms","start":"2026-10-14T03:23:35.890511Z","end":"2026-10-14T03:23:36.38645Z","steps":["trace[811622472] 'read index received' (duration: 141.015084ms)","trace[811622472] 'applied index is now lower than readState.Index' (duration: 354.92187ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:23:36.386548Z","caller":"traceutil/trace.go:171","msg":"trace[572713379] transaction","detail":"{read_only:false; response_revision:18163; number_of_response:1; }","duration":"514.285791ms","start":"2026-10-14T03:23:35.872217Z","end":"2026-10-14T03:23:36.386503Z","steps":["trace[572713379] 'process raft request' (duration: 514.094084ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:36.386508Z","caller":"traceutil/trace.go:171","msg":"trace[328086887] transaction","detail":"{read_only:false; response_revision:18162; number_of_response:1; }","duration":"525.461687ms","start":"2026-10-14T03:23:35.86099Z","end":"2026-10-14T03:23:36.386452Z","steps":["trace[328086887] 'process raft request' (duration: 524.793247ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:36.38666Z","caller":"traceutil/trace.go:171","msg":"trace[1293381704] transaction","detail":"{read_only:false; response_revision:18164; number_of_response:1; }","duration":"381.676553ms","start":"2026-10-14T03:23:36.004922Z","end":"2026-10-14T03:23:36.386599Z","steps":["trace[1293381704] 'process raft request' (duration: 381.473046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:36.386754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:23:35.87218Z","time spent":"514.442245ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:23:36.38678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:23:35.860956Z","time spent":"525.680284ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:23:36.38682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.308495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:23:36.386861Z","caller":"traceutil/trace.go:171","msg":"trace[890284078] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18164; }","duration":"496.390178ms","start":"2026-10-14T03:23:35.890458Z","end":"2026-10-14T03:23:36.386848Z","steps":["trace[890284078] 'agreement among raft nodes before linearized reading' (duration: 496.172851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:36.386915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:23:35.890443Z","time spent":"496.464329ms","remote":"127.0.0.1:58830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T03:23:36.386937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:23:36.004883Z","time spent":"381.891699ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:23:36.414272Z","caller":"traceutil/trace.go:171","msg":"trace[876116592] transaction","detail":"{read_only:false; response_revision:18165; number_of_response:1; }","duration":"190.839611ms","start":"2026-10-14T03:23:36.223394Z","end":"2026-10-14T03:23:36.414233Z","steps":["trace[876116592] 'process raft request' (duration: 175.332748ms)","trace[876116592] 'compare' (duration: 15.291237ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:23:36.414418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.146131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:23:36.414463Z","caller":"traceutil/trace.go:171","msg":"trace[1778977775] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:18165; }","duration":"242.234875ms","start":"2026-10-14T03:23:36.172213Z","end":"2026-10-14T03:23:36.414448Z","steps":["trace[1778977775] 'agreement among raft nodes before linearized reading' (duration: 242.138761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:36.414289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.136537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:23:36.414583Z","caller":"traceutil/trace.go:171","msg":"trace[654509699] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:18165; }","duration":"194.469868ms","start":"2026-10-14T03:23:36.220073Z","end":"2026-10-14T03:23:36.414542Z","steps":["trace[654509699] 'agreement among raft nodes before linearized reading' (duration: 194.128867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:23:36.419355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.873454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3299"} {"level":"info","ts":"2026-10-14T03:23:36.419509Z","caller":"traceutil/trace.go:171","msg":"trace[568331089] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18165; }","duration":"135.038949ms","start":"2026-10-14T03:23:36.284423Z","end":"2026-10-14T03:23:36.419462Z","steps":["trace[568331089] 'agreement among raft nodes before linearized reading' (duration: 130.331042ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:43.42353Z","caller":"traceutil/trace.go:171","msg":"trace[654708578] transaction","detail":"{read_only:false; response_revision:18239; number_of_response:1; }","duration":"102.505107ms","start":"2026-10-14T03:23:43.320997Z","end":"2026-10-14T03:23:43.423502Z","steps":["trace[654708578] 'process raft request' (duration: 96.262975ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:23:43.423786Z","caller":"traceutil/trace.go:171","msg":"trace[2035332498] transaction","detail":"{read_only:false; response_revision:18240; number_of_response:1; }","duration":"102.685914ms","start":"2026-10-14T03:23:43.321091Z","end":"2026-10-14T03:23:43.423777Z","steps":["trace[2035332498] 'process raft request' (duration: 102.363274ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:25:54.067344Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"328ca0518abde8c2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T03:25:54.07532Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T03:25:54.075417Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T03:27:17.152901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17529} {"level":"info","ts":"2026-10-14T03:27:17.2499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17529,"took":"93.999418ms","hash":4071346337,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":34668544,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T03:27:17.250058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4071346337,"revision":17529,"compact-revision":15374} {"level":"info","ts":"2026-10-14T03:29:30.545748Z","caller":"traceutil/trace.go:171","msg":"trace[1445215422] linearizableReadLoop","detail":"{readStateIndex:21506; appliedIndex:21505; }","duration":"126.348793ms","start":"2026-10-14T03:29:30.419364Z","end":"2026-10-14T03:29:30.545713Z","steps":["trace[1445215422] 'read index received' (duration: 66.73661ms)","trace[1445215422] 'applied index is now lower than readState.Index' (duration: 59.611043ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:29:30.545923Z","caller":"traceutil/trace.go:171","msg":"trace[18080261] transaction","detail":"{read_only:false; response_revision:20819; number_of_response:1; }","duration":"129.558587ms","start":"2026-10-14T03:29:30.41635Z","end":"2026-10-14T03:29:30.545909Z","steps":["trace[18080261] 'process raft request' (duration: 69.81039ms)","trace[18080261] 'compare' (duration: 59.410277ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:29:30.546261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.866648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T03:29:30.546366Z","caller":"traceutil/trace.go:171","msg":"trace[289450468] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:20819; }","duration":"127.036222ms","start":"2026-10-14T03:29:30.419307Z","end":"2026-10-14T03:29:30.546343Z","steps":["trace[289450468] 'agreement among raft nodes before linearized reading' (duration: 126.702343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:29:30.83969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.839212ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-14T03:29:30.839877Z","caller":"traceutil/trace.go:171","msg":"trace[614106754] linearizableReadLoop","detail":"{readStateIndex:21507; appliedIndex:21506; }","duration":"264.54691ms","start":"2026-10-14T03:29:30.575302Z","end":"2026-10-14T03:29:30.839849Z","steps":["trace[614106754] 'read index received' (duration: 143.491802ms)","trace[614106754] 'applied index is now lower than readState.Index' (duration: 121.052518ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:29:30.840031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.684785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:29:30.84008Z","caller":"traceutil/trace.go:171","msg":"trace[2131101148] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:20819; }","duration":"264.813359ms","start":"2026-10-14T03:29:30.575253Z","end":"2026-10-14T03:29:30.840066Z","steps":["trace[2131101148] 'agreement among raft nodes before linearized reading' (duration: 264.671425ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:29:31.185809Z","caller":"traceutil/trace.go:171","msg":"trace[1865198070] linearizableReadLoop","detail":"{readStateIndex:21509; appliedIndex:21508; }","duration":"176.281692ms","start":"2026-10-14T03:29:31.009478Z","end":"2026-10-14T03:29:31.18576Z","steps":["trace[1865198070] 'read index received' (duration: 176.061275ms)","trace[1865198070] 'applied index is now lower than readState.Index' (duration: 218.727µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:29:31.185898Z","caller":"traceutil/trace.go:171","msg":"trace[1479612041] transaction","detail":"{read_only:false; response_revision:20821; number_of_response:1; }","duration":"249.723087ms","start":"2026-10-14T03:29:30.936127Z","end":"2026-10-14T03:29:31.18585Z","steps":["trace[1479612041] 'process raft request' (duration: 249.421839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:29:31.186053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.553119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:29:31.186098Z","caller":"traceutil/trace.go:171","msg":"trace[1174834878] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:20821; }","duration":"176.674753ms","start":"2026-10-14T03:29:31.009407Z","end":"2026-10-14T03:29:31.186082Z","steps":["trace[1174834878] 'agreement among raft nodes before linearized reading' (duration: 176.517998ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:29:31.656746Z","caller":"traceutil/trace.go:171","msg":"trace[773604787] linearizableReadLoop","detail":"{readStateIndex:21512; appliedIndex:21509; }","duration":"270.423712ms","start":"2026-10-14T03:29:31.386293Z","end":"2026-10-14T03:29:31.656717Z","steps":["trace[773604787] 'read index received' (duration: 14.909086ms)","trace[773604787] 'applied index is now lower than readState.Index' (duration: 255.513646ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:29:31.656885Z","caller":"traceutil/trace.go:171","msg":"trace[457781827] transaction","detail":"{read_only:false; response_revision:20824; number_of_response:1; }","duration":"279.244569ms","start":"2026-10-14T03:29:31.377578Z","end":"2026-10-14T03:29:31.656823Z","steps":["trace[457781827] 'process raft request' (duration: 239.54465ms)","trace[457781827] 'compare' (duration: 39.452382ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:29:31.657045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.822158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:29:31.657118Z","caller":"traceutil/trace.go:171","msg":"trace[492039355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20824; }","duration":"149.977913ms","start":"2026-10-14T03:29:31.507102Z","end":"2026-10-14T03:29:31.65708Z","steps":["trace[492039355] 'agreement among raft nodes before linearized reading' (duration: 149.839499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:29:31.657354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.00821ms","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-10-14T03:29:31.65743Z","caller":"traceutil/trace.go:171","msg":"trace[895349891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20824; }","duration":"271.147924ms","start":"2026-10-14T03:29:31.386263Z","end":"2026-10-14T03:29:31.657411Z","steps":["trace[895349891] 'agreement among raft nodes before linearized reading' (duration: 270.938288ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:32:17.163609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19934} {"level":"info","ts":"2026-10-14T03:32:17.295515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19934,"took":"128.0179ms","hash":3437976707,"current-db-size-bytes":56299520,"current-db-size":"56 MB","current-db-size-in-use-bytes":30158848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T03:32:17.2956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3437976707,"revision":19934,"compact-revision":17529} {"level":"info","ts":"2026-10-14T03:33:37.708068Z","caller":"traceutil/trace.go:171","msg":"trace[1658378852] transaction","detail":"{read_only:false; response_revision:23347; number_of_response:1; }","duration":"135.828299ms","start":"2026-10-14T03:33:37.572195Z","end":"2026-10-14T03:33:37.708023Z","steps":["trace[1658378852] 'process raft request' (duration: 135.558331ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:33:38.076927Z","caller":"traceutil/trace.go:171","msg":"trace[231757888] transaction","detail":"{read_only:false; response_revision:23348; number_of_response:1; }","duration":"311.407079ms","start":"2026-10-14T03:33:37.765452Z","end":"2026-10-14T03:33:38.076859Z","steps":["trace[231757888] 'process raft request' (duration: 269.192976ms)","trace[231757888] 'compare' (duration: 41.589095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:33:38.077148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:33:37.765432Z","time spent":"311.599785ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:35:03.251989Z","caller":"traceutil/trace.go:171","msg":"trace[2015316953] transaction","detail":"{read_only:false; response_revision:24274; number_of_response:1; }","duration":"114.780834ms","start":"2026-10-14T03:35:03.137183Z","end":"2026-10-14T03:35:03.251964Z","steps":["trace[2015316953] 'process raft request' (duration: 114.606709ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:35:03.343337Z","caller":"traceutil/trace.go:171","msg":"trace[1182796568] transaction","detail":"{read_only:false; response_revision:24275; number_of_response:1; }","duration":"183.633476ms","start":"2026-10-14T03:35:03.159672Z","end":"2026-10-14T03:35:03.343305Z","steps":["trace[1182796568] 'process raft request' (duration: 183.43509ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:35:03.343565Z","caller":"traceutil/trace.go:171","msg":"trace[2005542758] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"119.850161ms","start":"2026-10-14T03:35:03.223687Z","end":"2026-10-14T03:35:03.343537Z","steps":["trace[2005542758] 'process raft request' (duration: 119.569273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:08.772541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.341339ms","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-10-14T03:37:08.772681Z","caller":"traceutil/trace.go:171","msg":"trace[1202164385] 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:25469; }","duration":"104.561715ms","start":"2026-10-14T03:37:08.668088Z","end":"2026-10-14T03:37:08.77265Z","steps":["trace[1202164385] 'count revisions from in-memory index tree' (duration: 104.215175ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:09.08193Z","caller":"traceutil/trace.go:171","msg":"trace[1667031030] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"172.723327ms","start":"2026-10-14T03:37:08.90917Z","end":"2026-10-14T03:37:09.081894Z","steps":["trace[1667031030] 'process raft request' (duration: 172.414448ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:13.710883Z","caller":"traceutil/trace.go:171","msg":"trace[546537742] linearizableReadLoop","detail":"{readStateIndex:26324; appliedIndex:26323; }","duration":"204.600559ms","start":"2026-10-14T03:37:13.506258Z","end":"2026-10-14T03:37:13.710858Z","steps":["trace[546537742] 'read index received' (duration: 204.471625ms)","trace[546537742] 'applied index is now lower than readState.Index' (duration: 127.744µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:13.711032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.767023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:13.711067Z","caller":"traceutil/trace.go:171","msg":"trace[1211866593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25506; }","duration":"204.847946ms","start":"2026-10-14T03:37:13.506205Z","end":"2026-10-14T03:37:13.711053Z","steps":["trace[1211866593] 'agreement among raft nodes before linearized reading' (duration: 204.773673ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:13.71107Z","caller":"traceutil/trace.go:171","msg":"trace[1953619254] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"267.835527ms","start":"2026-10-14T03:37:13.443121Z","end":"2026-10-14T03:37:13.710956Z","steps":["trace[1953619254] 'process raft request' (duration: 267.59075ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:13.792772Z","caller":"traceutil/trace.go:171","msg":"trace[1375406621] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"263.216051ms","start":"2026-10-14T03:37:13.529528Z","end":"2026-10-14T03:37:13.792744Z","steps":["trace[1375406621] 'process raft request' (duration: 262.985334ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:13.79289Z","caller":"traceutil/trace.go:171","msg":"trace[2116197487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25507; }","duration":"100.037234ms","start":"2026-10-14T03:37:13.692824Z","end":"2026-10-14T03:37:13.792861Z","steps":["trace[2116197487] 'agreement among raft nodes before linearized reading' (duration: 99.92108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:13.792916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.822248ms","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-10-14T03:37:13.79301Z","caller":"traceutil/trace.go:171","msg":"trace[860558282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25507; }","duration":"215.012883ms","start":"2026-10-14T03:37:13.577976Z","end":"2026-10-14T03:37:13.792989Z","steps":["trace[860558282] 'agreement among raft nodes before linearized reading' (duration: 214.813168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:13.81458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.128865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:13.814676Z","caller":"traceutil/trace.go:171","msg":"trace[743205556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25509; }","duration":"102.281649ms","start":"2026-10-14T03:37:13.712375Z","end":"2026-10-14T03:37:13.814657Z","steps":["trace[743205556] 'agreement among raft nodes before linearized reading' (duration: 102.114094ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:14.025657Z","caller":"traceutil/trace.go:171","msg":"trace[904163165] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"153.043762ms","start":"2026-10-14T03:37:13.872577Z","end":"2026-10-14T03:37:14.025621Z","steps":["trace[904163165] 'process raft request' (duration: 135.53103ms)","trace[904163165] 'compare' (duration: 17.371578ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:14.373472Z","caller":"traceutil/trace.go:171","msg":"trace[1022374041] linearizableReadLoop","detail":"{readStateIndex:26329; appliedIndex:26327; }","duration":"400.151862ms","start":"2026-10-14T03:37:13.973295Z","end":"2026-10-14T03:37:14.373447Z","steps":["trace[1022374041] 'read index received' (duration: 34.810686ms)","trace[1022374041] 'applied index is now lower than readState.Index' (duration: 365.340196ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:14.373506Z","caller":"traceutil/trace.go:171","msg":"trace[1006396287] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"496.185589ms","start":"2026-10-14T03:37:13.877291Z","end":"2026-10-14T03:37:14.373477Z","steps":["trace[1006396287] 'process raft request' (duration: 449.495505ms)","trace[1006396287] 'compare' (duration: 46.417976ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:14.373607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.307777ms","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:6"} {"level":"warn","ts":"2026-10-14T03:37:14.373659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:13.877252Z","time spent":"496.310293ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:14.373642Z","caller":"traceutil/trace.go:171","msg":"trace[1270286639] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25511; }","duration":"400.40608ms","start":"2026-10-14T03:37:13.973225Z","end":"2026-10-14T03:37:14.373631Z","steps":["trace[1270286639] 'agreement among raft nodes before linearized reading' (duration: 400.327268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:14.373911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:13.973195Z","time spent":"400.69961ms","remote":"127.0.0.1:57306","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-10-14T03:37:14.377012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.17034ms","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-10-14T03:37:14.377267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.804678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-14T03:37:14.377325Z","caller":"traceutil/trace.go:171","msg":"trace[471062613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25512; }","duration":"353.50783ms","start":"2026-10-14T03:37:14.023758Z","end":"2026-10-14T03:37:14.377266Z","steps":["trace[471062613] 'agreement among raft nodes before linearized reading' (duration: 353.051857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:14.377447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:14.023709Z","time spent":"353.721136ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T03:37:14.377348Z","caller":"traceutil/trace.go:171","msg":"trace[606286155] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25512; }","duration":"109.942122ms","start":"2026-10-14T03:37:14.267386Z","end":"2026-10-14T03:37:14.377328Z","steps":["trace[606286155] 'agreement among raft nodes before linearized reading' (duration: 109.668424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:14.377189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.431296ms","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-10-14T03:37:14.377648Z","caller":"traceutil/trace.go:171","msg":"trace[1196587382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25512; }","duration":"330.954201ms","start":"2026-10-14T03:37:14.04668Z","end":"2026-10-14T03:37:14.377634Z","steps":["trace[1196587382] 'agreement among raft nodes before linearized reading' (duration: 330.419135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:14.377037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.472411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-14T03:37:14.377733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:14.046659Z","time spent":"331.058454ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T03:37:14.377828Z","caller":"traceutil/trace.go:171","msg":"trace[736294559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25512; }","duration":"262.266654ms","start":"2026-10-14T03:37:14.115487Z","end":"2026-10-14T03:37:14.377754Z","steps":["trace[736294559] 'agreement among raft nodes before linearized reading' (duration: 261.405289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:14.620963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.775479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:14.621171Z","caller":"traceutil/trace.go:171","msg":"trace[299794668] linearizableReadLoop","detail":"{readStateIndex:26334; appliedIndex:26332; }","duration":"114.328821ms","start":"2026-10-14T03:37:14.506822Z","end":"2026-10-14T03:37:14.621151Z","steps":["trace[299794668] 'read index received' (duration: 5.157781ms)","trace[299794668] 'applied index is now lower than readState.Index' (duration: 109.1695ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:14.62125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.436134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:14.621213Z","caller":"traceutil/trace.go:171","msg":"trace[913690343] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"236.991565ms","start":"2026-10-14T03:37:14.384165Z","end":"2026-10-14T03:37:14.621157Z","steps":["trace[913690343] 'process raft request' (duration: 127.923888ms)","trace[913690343] 'compare' (duration: 108.45998ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:14.621234Z","caller":"traceutil/trace.go:171","msg":"trace[1363777016] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"235.821231ms","start":"2026-10-14T03:37:14.38536Z","end":"2026-10-14T03:37:14.621182Z","steps":["trace[1363777016] 'process raft request' (duration: 235.687417ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:14.621286Z","caller":"traceutil/trace.go:171","msg":"trace[324581452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25516; }","duration":"114.521426ms","start":"2026-10-14T03:37:14.506754Z","end":"2026-10-14T03:37:14.621276Z","steps":["trace[324581452] 'agreement among raft nodes before linearized reading' (duration: 114.454974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:15.032862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.569317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:15.032996Z","caller":"traceutil/trace.go:171","msg":"trace[1356123734] linearizableReadLoop","detail":"{readStateIndex:26335; appliedIndex:26334; }","duration":"342.356855ms","start":"2026-10-14T03:37:14.690616Z","end":"2026-10-14T03:37:15.032973Z","steps":["trace[1356123734] 'read index received' (duration: 123.369775ms)","trace[1356123734] 'applied index is now lower than readState.Index' (duration: 218.98549ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:15.033461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.824008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:15.033545Z","caller":"traceutil/trace.go:171","msg":"trace[1080027971] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25517; }","duration":"342.928621ms","start":"2026-10-14T03:37:14.690593Z","end":"2026-10-14T03:37:15.033521Z","steps":["trace[1080027971] 'agreement among raft nodes before linearized reading' (duration: 342.480997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:15.03359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:14.69049Z","time spent":"343.088076ms","remote":"127.0.0.1:32774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-10-14T03:37:15.033631Z","caller":"traceutil/trace.go:171","msg":"trace[2002133208] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"456.926582ms","start":"2026-10-14T03:37:14.576137Z","end":"2026-10-14T03:37:15.033063Z","steps":["trace[2002133208] 'process raft request' (duration: 237.976784ms)","trace[2002133208] 'compare' (duration: 218.31292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:15.033932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:14.576106Z","time spent":"457.675565ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:15.222735Z","caller":"traceutil/trace.go:171","msg":"trace[1280183801] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"108.849731ms","start":"2026-10-14T03:37:15.113859Z","end":"2026-10-14T03:37:15.222709Z","steps":["trace[1280183801] 'process raft request' (duration: 108.005376ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:15.838656Z","caller":"traceutil/trace.go:171","msg":"trace[206077452] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"119.670188ms","start":"2026-10-14T03:37:15.718957Z","end":"2026-10-14T03:37:15.838627Z","steps":["trace[206077452] 'process raft request' (duration: 119.434561ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:16.079301Z","caller":"traceutil/trace.go:171","msg":"trace[1174440545] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"280.504428ms","start":"2026-10-14T03:37:15.798762Z","end":"2026-10-14T03:37:16.079266Z","steps":["trace[1174440545] 'process raft request' (duration: 254.883458ms)","trace[1174440545] 'compare' (duration: 25.077603ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:16.079393Z","caller":"traceutil/trace.go:171","msg":"trace[325059194] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"270.470544ms","start":"2026-10-14T03:37:15.808913Z","end":"2026-10-14T03:37:16.079384Z","steps":["trace[325059194] 'process raft request' (duration: 270.262778ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:16.079489Z","caller":"traceutil/trace.go:171","msg":"trace[1068643172] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"257.878716ms","start":"2026-10-14T03:37:15.821601Z","end":"2026-10-14T03:37:16.07948Z","steps":["trace[1068643172] 'process raft request' (duration: 257.629259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:16.079323Z","caller":"traceutil/trace.go:171","msg":"trace[1669214332] linearizableReadLoop","detail":"{readStateIndex:26339; appliedIndex:26336; }","duration":"260.236545ms","start":"2026-10-14T03:37:15.819068Z","end":"2026-10-14T03:37:16.079305Z","steps":["trace[1669214332] 'read index received' (duration: 19.445868ms)","trace[1669214332] 'applied index is now lower than readState.Index' (duration: 240.789757ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:16.079652Z","caller":"traceutil/trace.go:171","msg":"trace[1360888309] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"195.578126ms","start":"2026-10-14T03:37:15.884043Z","end":"2026-10-14T03:37:16.079621Z","steps":["trace[1360888309] 'process raft request' (duration: 195.230556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:16.242588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.132047ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-10-14T03:37:16.242813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.713902ms","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-10-14T03:37:16.242911Z","caller":"traceutil/trace.go:171","msg":"trace[1349897771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25523; }","duration":"423.832286ms","start":"2026-10-14T03:37:15.819049Z","end":"2026-10-14T03:37:16.242882Z","steps":["trace[1349897771] 'agreement among raft nodes before linearized reading' (duration: 260.309297ms)","trace[1349897771] 'range keys from in-memory index tree' (duration: 163.26355ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:16.242955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:15.818983Z","time spent":"423.961579ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T03:37:16.243232Z","caller":"traceutil/trace.go:171","msg":"trace[1750454553] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"211.427499ms","start":"2026-10-14T03:37:16.031792Z","end":"2026-10-14T03:37:16.243219Z","steps":["trace[1750454553] 'process raft request' (duration: 210.891052ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:16.243229Z","caller":"traceutil/trace.go:171","msg":"trace[1534323633] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26341; }","duration":"163.630931ms","start":"2026-10-14T03:37:16.079572Z","end":"2026-10-14T03:37:16.243202Z","steps":["trace[1534323633] 'read index received' (duration: 61.091µs)","trace[1534323633] 'applied index is now lower than readState.Index' (duration: 163.56774ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:16.258921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.546666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:16.259027Z","caller":"traceutil/trace.go:171","msg":"trace[643213648] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25524; }","duration":"387.731551ms","start":"2026-10-14T03:37:15.871269Z","end":"2026-10-14T03:37:16.259Z","steps":["trace[643213648] 'agreement among raft nodes before linearized reading' (duration: 372.014932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:16.259024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.326463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-10-14T03:37:16.259093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:15.871249Z","time spent":"387.832193ms","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-10-14T03:37:16.25911Z","caller":"traceutil/trace.go:171","msg":"trace[2059797651] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25524; }","duration":"291.478397ms","start":"2026-10-14T03:37:15.967607Z","end":"2026-10-14T03:37:16.259086Z","steps":["trace[2059797651] 'agreement among raft nodes before linearized reading' (duration: 275.695326ms)","trace[2059797651] 'range keys from in-memory index tree' (duration: 15.539795ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:16.622376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.26593ms","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-10-14T03:37:16.622449Z","caller":"traceutil/trace.go:171","msg":"trace[1932597400] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25525; }","duration":"255.390514ms","start":"2026-10-14T03:37:16.367041Z","end":"2026-10-14T03:37:16.622432Z","steps":["trace[1932597400] 'range keys from in-memory index tree' (duration: 255.190998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:16.623427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.182209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:16.623497Z","caller":"traceutil/trace.go:171","msg":"trace[776168818] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"243.421544ms","start":"2026-10-14T03:37:16.380062Z","end":"2026-10-14T03:37:16.623483Z","steps":["trace[776168818] 'process raft request' (duration: 42.115432ms)","trace[776168818] 'compare' (duration: 200.692574ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:16.626459Z","caller":"traceutil/trace.go:171","msg":"trace[1598742747] linearizableReadLoop","detail":"{readStateIndex:26346; appliedIndex:26345; }","duration":"170.614305ms","start":"2026-10-14T03:37:16.455826Z","end":"2026-10-14T03:37:16.62644Z","steps":["trace[1598742747] 'read index received' (duration: 170.549603ms)","trace[1598742747] 'applied index is now lower than readState.Index' (duration: 63.422µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:16.62673Z","caller":"traceutil/trace.go:171","msg":"trace[222165920] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"243.088374ms","start":"2026-10-14T03:37:16.383629Z","end":"2026-10-14T03:37:16.626718Z","steps":["trace[222165920] 'process raft request' (duration: 242.692662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:16.626968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.136101ms","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-10-14T03:37:16.627Z","caller":"traceutil/trace.go:171","msg":"trace[1116329348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25527; }","duration":"171.218744ms","start":"2026-10-14T03:37:16.455771Z","end":"2026-10-14T03:37:16.626989Z","steps":["trace[1116329348] 'agreement among raft nodes before linearized reading' (duration: 171.10126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:16.627181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.773881ms","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-10-14T03:37:16.627211Z","caller":"traceutil/trace.go:171","msg":"trace[404106565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25527; }","duration":"169.842463ms","start":"2026-10-14T03:37:16.457356Z","end":"2026-10-14T03:37:16.627198Z","steps":["trace[404106565] 'agreement among raft nodes before linearized reading' (duration: 169.77143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:16.62765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.760229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:16.627712Z","caller":"traceutil/trace.go:171","msg":"trace[1940807659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25527; }","duration":"120.858071ms","start":"2026-10-14T03:37:16.506825Z","end":"2026-10-14T03:37:16.627683Z","steps":["trace[1940807659] 'agreement among raft nodes before linearized reading' (duration: 120.780129ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:17.182603Z","caller":"traceutil/trace.go:171","msg":"trace[1113628173] linearizableReadLoop","detail":"{readStateIndex:26351; appliedIndex:26350; }","duration":"161.048716ms","start":"2026-10-14T03:37:17.021527Z","end":"2026-10-14T03:37:17.182575Z","steps":["trace[1113628173] 'read index received' (duration: 160.866861ms)","trace[1113628173] 'applied index is now lower than readState.Index' (duration: 180.385µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:17.182785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.224941ms","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-10-14T03:37:17.182819Z","caller":"traceutil/trace.go:171","msg":"trace[1238252172] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25532; }","duration":"161.290943ms","start":"2026-10-14T03:37:17.021518Z","end":"2026-10-14T03:37:17.182809Z","steps":["trace[1238252172] 'agreement among raft nodes before linearized reading' (duration: 161.164699ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:17.183086Z","caller":"traceutil/trace.go:171","msg":"trace[468340262] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"163.014173ms","start":"2026-10-14T03:37:17.020029Z","end":"2026-10-14T03:37:17.183043Z","steps":["trace[468340262] 'process raft request' (duration: 162.347143ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:17.280648Z","caller":"traceutil/trace.go:171","msg":"trace[1047938012] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"114.688952ms","start":"2026-10-14T03:37:17.165934Z","end":"2026-10-14T03:37:17.280623Z","steps":["trace[1047938012] 'process raft request' (duration: 114.62006ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:17.280988Z","caller":"traceutil/trace.go:171","msg":"trace[196684634] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"235.475081ms","start":"2026-10-14T03:37:17.045499Z","end":"2026-10-14T03:37:17.280974Z","steps":["trace[196684634] 'process raft request' (duration: 234.521703ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:17.476163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22122} {"level":"warn","ts":"2026-10-14T03:37:17.476765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.829073ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-10-14T03:37:17.47687Z","caller":"traceutil/trace.go:171","msg":"trace[229317231] linearizableReadLoop","detail":"{readStateIndex:26355; appliedIndex:26354; }","duration":"180.466184ms","start":"2026-10-14T03:37:17.296385Z","end":"2026-10-14T03:37:17.476851Z","steps":["trace[229317231] 'read index received' (duration: 9.415015ms)","trace[229317231] 'applied index is now lower than readState.Index' (duration: 171.049219ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:17.476947Z","caller":"traceutil/trace.go:171","msg":"trace[1058691419] compact","detail":"{revision:22122; response_revision:25535; }","duration":"194.274498ms","start":"2026-10-14T03:37:17.282666Z","end":"2026-10-14T03:37:17.47694Z","steps":["trace[1058691419] 'check and update compact revision' (duration: 169.71541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:17.477259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.859745ms","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-10-14T03:37:17.477312Z","caller":"traceutil/trace.go:171","msg":"trace[1429560718] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25535; }","duration":"180.927107ms","start":"2026-10-14T03:37:17.296365Z","end":"2026-10-14T03:37:17.477292Z","steps":["trace[1429560718] 'agreement among raft nodes before linearized reading' (duration: 180.820224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:17.683607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.257818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:17.683743Z","caller":"traceutil/trace.go:171","msg":"trace[713743551] linearizableReadLoop","detail":"{readStateIndex:26356; appliedIndex:26355; }","duration":"206.808514ms","start":"2026-10-14T03:37:17.476899Z","end":"2026-10-14T03:37:17.683707Z","steps":["trace[713743551] 'read index received' (duration: 53.267267ms)","trace[713743551] 'applied index is now lower than readState.Index' (duration: 153.539977ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:17.683831Z","caller":"traceutil/trace.go:171","msg":"trace[1352936540] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"326.566524ms","start":"2026-10-14T03:37:17.357248Z","end":"2026-10-14T03:37:17.683814Z","steps":["trace[1352936540] 'process raft request' (duration: 172.992066ms)","trace[1352936540] 'compare' (duration: 153.134405ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:17.683884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:17.35722Z","time spent":"326.633606ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:17.684032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.963872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:37:17.684071Z","caller":"traceutil/trace.go:171","msg":"trace[216216829] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25536; }","duration":"266.053815ms","start":"2026-10-14T03:37:17.418006Z","end":"2026-10-14T03:37:17.684059Z","steps":["trace[216216829] 'agreement among raft nodes before linearized reading' (duration: 265.981583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.096509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.595235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:18.096594Z","caller":"traceutil/trace.go:171","msg":"trace[1182721133] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25537; }","duration":"106.702848ms","start":"2026-10-14T03:37:17.989868Z","end":"2026-10-14T03:37:18.096571Z","steps":["trace[1182721133] 'count revisions from in-memory index tree' (duration: 106.508953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.297496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.114528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:18.297823Z","caller":"traceutil/trace.go:171","msg":"trace[763316150] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"210.865852ms","start":"2026-10-14T03:37:18.086935Z","end":"2026-10-14T03:37:18.2978Z","steps":["trace[763316150] 'process raft request' (duration: 210.685727ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:18.297866Z","caller":"traceutil/trace.go:171","msg":"trace[1279653246] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"212.641124ms","start":"2026-10-14T03:37:18.085162Z","end":"2026-10-14T03:37:18.297803Z","steps":["trace[1279653246] 'process raft request' (duration: 24.085964ms)","trace[1279653246] 'compare' (duration: 173.224702ms)","trace[1279653246] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 14.758142ms)"],"step_count":3} {"level":"warn","ts":"2026-10-14T03:37:18.761352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.94757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:18.761433Z","caller":"traceutil/trace.go:171","msg":"trace[1813813294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25541; }","duration":"255.090604ms","start":"2026-10-14T03:37:18.506325Z","end":"2026-10-14T03:37:18.761415Z","steps":["trace[1813813294] 'range keys from in-memory index tree' (duration: 254.868298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.761465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.354385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:18.761576Z","caller":"traceutil/trace.go:171","msg":"trace[1930772118] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25541; }","duration":"198.523921ms","start":"2026-10-14T03:37:18.563025Z","end":"2026-10-14T03:37:18.761549Z","steps":["trace[1930772118] 'count revisions from in-memory index tree' (duration: 198.287464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.761952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.565799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:37:18.761997Z","caller":"traceutil/trace.go:171","msg":"trace[468385666] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25541; }","duration":"242.644881ms","start":"2026-10-14T03:37:18.519341Z","end":"2026-10-14T03:37:18.761985Z","steps":["trace[468385666] 'count revisions from in-memory index tree' (duration: 242.519667ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:18.796756Z","caller":"traceutil/trace.go:171","msg":"trace[1914544324] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"165.669671ms","start":"2026-10-14T03:37:18.631007Z","end":"2026-10-14T03:37:18.796676Z","steps":["trace[1914544324] 'process raft request' (duration: 88.574609ms)","trace[1914544324] 'compare' (duration: 42.147961ms)","trace[1914544324] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:507; } (duration: 34.749936ms)"],"step_count":3} {"level":"info","ts":"2026-10-14T03:37:18.944349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22122,"took":"1.463939501s","hash":4018587065,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":45109248,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-14T03:37:18.944422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4018587065,"revision":22122,"compact-revision":19934} {"level":"info","ts":"2026-10-14T03:37:18.953615Z","caller":"traceutil/trace.go:171","msg":"trace[848433019] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"319.850268ms","start":"2026-10-14T03:37:18.633739Z","end":"2026-10-14T03:37:18.953589Z","steps":["trace[848433019] 'process raft request' (duration: 319.61775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.953801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:18.633722Z","time spent":"319.95694ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:18.953603Z","caller":"traceutil/trace.go:171","msg":"trace[2049306281] linearizableReadLoop","detail":"{readStateIndex:26364; appliedIndex:26361; }","duration":"298.551404ms","start":"2026-10-14T03:37:18.655012Z","end":"2026-10-14T03:37:18.953564Z","steps":["trace[2049306281] 'read index received' (duration: 64.489744ms)","trace[2049306281] 'applied index is now lower than readState.Index' (duration: 234.06055ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:18.953845Z","caller":"traceutil/trace.go:171","msg":"trace[1504547827] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"299.979467ms","start":"2026-10-14T03:37:18.653794Z","end":"2026-10-14T03:37:18.953773Z","steps":["trace[1504547827] 'process raft request' (duration: 299.718958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.954057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:18.653761Z","time spent":"300.170452ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:18.954126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.117745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:18.954182Z","caller":"traceutil/trace.go:171","msg":"trace[396841885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25544; }","duration":"191.212738ms","start":"2026-10-14T03:37:18.762955Z","end":"2026-10-14T03:37:18.954168Z","steps":["trace[396841885] 'agreement among raft nodes before linearized reading' (duration: 191.112225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.954199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.018135ms","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-10-14T03:37:18.954245Z","caller":"traceutil/trace.go:171","msg":"trace[2119561183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25544; }","duration":"292.085386ms","start":"2026-10-14T03:37:18.662146Z","end":"2026-10-14T03:37:18.954231Z","steps":["trace[2119561183] 'agreement among raft nodes before linearized reading' (duration: 291.902631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.9544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.428397ms","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-10-14T03:37:18.954507Z","caller":"traceutil/trace.go:171","msg":"trace[557615518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25544; }","duration":"291.571351ms","start":"2026-10-14T03:37:18.662905Z","end":"2026-10-14T03:37:18.954476Z","steps":["trace[557615518] 'agreement among raft nodes before linearized reading' (duration: 291.151579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.95454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.120536ms","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-10-14T03:37:18.954666Z","caller":"traceutil/trace.go:171","msg":"trace[1793345802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25544; }","duration":"292.336403ms","start":"2026-10-14T03:37:18.6623Z","end":"2026-10-14T03:37:18.954636Z","steps":["trace[1793345802] 'agreement among raft nodes before linearized reading' (duration: 291.87024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.956679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.660816ms","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-10-14T03:37:18.956768Z","caller":"traceutil/trace.go:171","msg":"trace[1808172228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25544; }","duration":"301.771879ms","start":"2026-10-14T03:37:18.654981Z","end":"2026-10-14T03:37:18.956753Z","steps":["trace[1808172228] 'agreement among raft nodes before linearized reading' (duration: 298.679529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:18.956841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:18.654974Z","time spent":"301.820251ms","remote":"127.0.0.1:58924","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":"info","ts":"2026-10-14T03:37:19.160176Z","caller":"traceutil/trace.go:171","msg":"trace[1258172627] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"199.230372ms","start":"2026-10-14T03:37:18.960911Z","end":"2026-10-14T03:37:19.160142Z","steps":["trace[1258172627] 'process raft request' (duration: 199.17605ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:19.160469Z","caller":"traceutil/trace.go:171","msg":"trace[99667992] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"202.100746ms","start":"2026-10-14T03:37:18.958312Z","end":"2026-10-14T03:37:19.160413Z","steps":["trace[99667992] 'process raft request' (duration: 197.230584ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:19.160709Z","caller":"traceutil/trace.go:171","msg":"trace[2046405106] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"201.689864ms","start":"2026-10-14T03:37:18.958978Z","end":"2026-10-14T03:37:19.160668Z","steps":["trace[2046405106] 'process raft request' (duration: 201.019825ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:19.160795Z","caller":"traceutil/trace.go:171","msg":"trace[676728725] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"201.148007ms","start":"2026-10-14T03:37:18.959608Z","end":"2026-10-14T03:37:19.160756Z","steps":["trace[676728725] 'process raft request' (duration: 200.439517ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:19.449662Z","caller":"traceutil/trace.go:171","msg":"trace[1263696178] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"163.52089ms","start":"2026-10-14T03:37:19.286087Z","end":"2026-10-14T03:37:19.449608Z","steps":["trace[1263696178] 'process raft request' (duration: 163.256692ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:19.721281Z","caller":"traceutil/trace.go:171","msg":"trace[1902032275] linearizableReadLoop","detail":"{readStateIndex:26370; appliedIndex:26369; }","duration":"423.315611ms","start":"2026-10-14T03:37:19.297941Z","end":"2026-10-14T03:37:19.721256Z","steps":["trace[1902032275] 'read index received' (duration: 151.666913ms)","trace[1902032275] 'applied index is now lower than readState.Index' (duration: 271.647798ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:19.721355Z","caller":"traceutil/trace.go:171","msg":"trace[987816932] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"433.56389ms","start":"2026-10-14T03:37:19.287755Z","end":"2026-10-14T03:37:19.721319Z","steps":["trace[987816932] 'process raft request' (duration: 393.217031ms)","trace[987816932] 'compare' (duration: 40.172244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:19.721547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:19.28767Z","time spent":"433.793847ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:19.721541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.584159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:19.721808Z","caller":"traceutil/trace.go:171","msg":"trace[2014078234] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25550; }","duration":"423.870167ms","start":"2026-10-14T03:37:19.297884Z","end":"2026-10-14T03:37:19.721754Z","steps":["trace[2014078234] 'agreement among raft nodes before linearized reading' (duration: 423.534327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:19.721957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:19.297843Z","time spent":"424.052152ms","remote":"127.0.0.1:60904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-10-14T03:37:20.107555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.451198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:20.107801Z","caller":"traceutil/trace.go:171","msg":"trace[258953888] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"192.307761ms","start":"2026-10-14T03:37:19.915463Z","end":"2026-10-14T03:37:20.107771Z","steps":["trace[258953888] 'process raft request' (duration: 16.570315ms)","trace[258953888] 'compare' (duration: 175.134227ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:20.275914Z","caller":"traceutil/trace.go:171","msg":"trace[1468739098] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"159.557723ms","start":"2026-10-14T03:37:20.116322Z","end":"2026-10-14T03:37:20.275879Z","steps":["trace[1468739098] 'process raft request' (duration: 151.59068ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:20.27618Z","caller":"traceutil/trace.go:171","msg":"trace[1669867085] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"158.902383ms","start":"2026-10-14T03:37:20.117247Z","end":"2026-10-14T03:37:20.27615Z","steps":["trace[1669867085] 'process raft request' (duration: 158.701197ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:20.528672Z","caller":"traceutil/trace.go:171","msg":"trace[1176734858] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"245.096212ms","start":"2026-10-14T03:37:20.28355Z","end":"2026-10-14T03:37:20.528646Z","steps":["trace[1176734858] 'process raft request' (duration: 244.856355ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:20.545412Z","caller":"traceutil/trace.go:171","msg":"trace[1332709725] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"242.24128ms","start":"2026-10-14T03:37:20.303149Z","end":"2026-10-14T03:37:20.54539Z","steps":["trace[1332709725] 'process raft request' (duration: 242.166948ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:20.545531Z","caller":"traceutil/trace.go:171","msg":"trace[1335389065] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"241.651021ms","start":"2026-10-14T03:37:20.30387Z","end":"2026-10-14T03:37:20.545521Z","steps":["trace[1335389065] 'process raft request' (duration: 241.489507ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:20.545551Z","caller":"traceutil/trace.go:171","msg":"trace[590224986] linearizableReadLoop","detail":"{readStateIndex:26377; appliedIndex:26373; }","duration":"150.842847ms","start":"2026-10-14T03:37:20.394682Z","end":"2026-10-14T03:37:20.545525Z","steps":["trace[590224986] 'read index received' (duration: 133.771899ms)","trace[590224986] 'applied index is now lower than readState.Index' (duration: 17.068458ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:20.545459Z","caller":"traceutil/trace.go:171","msg":"trace[1677045373] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"242.422304ms","start":"2026-10-14T03:37:20.302993Z","end":"2026-10-14T03:37:20.545416Z","steps":["trace[1677045373] 'process raft request' (duration: 242.184608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:20.545716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.020633ms","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-10-14T03:37:20.545751Z","caller":"traceutil/trace.go:171","msg":"trace[1393536618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25557; }","duration":"151.107546ms","start":"2026-10-14T03:37:20.394633Z","end":"2026-10-14T03:37:20.545741Z","steps":["trace[1393536618] 'agreement among raft nodes before linearized reading' (duration: 150.990592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:20.545961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.654469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:37:20.545994Z","caller":"traceutil/trace.go:171","msg":"trace[1923564665] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25557; }","duration":"104.726321ms","start":"2026-10-14T03:37:20.441258Z","end":"2026-10-14T03:37:20.545984Z","steps":["trace[1923564665] 'agreement among raft nodes before linearized reading' (duration: 104.668869ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:28.788265Z","caller":"traceutil/trace.go:171","msg":"trace[908318153] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"208.656667ms","start":"2026-10-14T03:37:28.579583Z","end":"2026-10-14T03:37:28.78824Z","steps":["trace[908318153] 'process raft request' (duration: 208.494372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.467994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.49105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:29.468199Z","caller":"traceutil/trace.go:171","msg":"trace[1527043275] linearizableReadLoop","detail":"{readStateIndex:26438; appliedIndex:26435; }","duration":"874.071782ms","start":"2026-10-14T03:37:28.594108Z","end":"2026-10-14T03:37:29.46818Z","steps":["trace[1527043275] 'read index received' (duration: 194.06055ms)","trace[1527043275] 'applied index is now lower than readState.Index' (duration: 680.010412ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:29.468323Z","caller":"traceutil/trace.go:171","msg":"trace[962718806] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"886.222837ms","start":"2026-10-14T03:37:28.582065Z","end":"2026-10-14T03:37:29.468288Z","steps":["trace[962718806] 'process raft request' (duration: 886.023992ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:29.468387Z","caller":"traceutil/trace.go:171","msg":"trace[1605821574] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"886.29865ms","start":"2026-10-14T03:37:28.582053Z","end":"2026-10-14T03:37:29.468352Z","steps":["trace[1605821574] 'process raft request' (duration: 544.341918ms)","trace[1605821574] 'compare' (duration: 340.878181ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:29.468509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:28.582038Z","time spent":"886.371862ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:29.468533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:28.582021Z","time spent":"886.431614ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:29.468532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.390061ms","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-10-14T03:37:29.468662Z","caller":"traceutil/trace.go:171","msg":"trace[636599119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25616; }","duration":"874.588617ms","start":"2026-10-14T03:37:28.594045Z","end":"2026-10-14T03:37:29.468634Z","steps":["trace[636599119] 'agreement among raft nodes before linearized reading' (duration: 874.240987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.46879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:28.594018Z","time spent":"874.743753ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T03:37:29.823175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.485582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:29.823434Z","caller":"traceutil/trace.go:171","msg":"trace[1263047541] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"985.239252ms","start":"2026-10-14T03:37:28.838166Z","end":"2026-10-14T03:37:29.823405Z","steps":["trace[1263047541] 'process raft request' (duration: 762.434951ms)","trace[1263047541] 'compare' (duration: 222.365248ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:29.82349Z","caller":"traceutil/trace.go:171","msg":"trace[104077697] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"330.386255ms","start":"2026-10-14T03:37:29.493086Z","end":"2026-10-14T03:37:29.823472Z","steps":["trace[104077697] 'process raft request' (duration: 330.330544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.823532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:28.838135Z","time spent":"985.352035ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:29.823579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.493065Z","time spent":"330.464727ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:29.823653Z","caller":"traceutil/trace.go:171","msg":"trace[243596169] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"347.573707ms","start":"2026-10-14T03:37:29.476057Z","end":"2026-10-14T03:37:29.823631Z","steps":["trace[243596169] 'process raft request' (duration: 347.304559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.823835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.476035Z","time spent":"347.728612ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:37:29.823898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.246439ms","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-10-14T03:37:29.823956Z","caller":"traceutil/trace.go:171","msg":"trace[2114492217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25620; }","duration":"602.342382ms","start":"2026-10-14T03:37:29.221596Z","end":"2026-10-14T03:37:29.823938Z","steps":["trace[2114492217] 'agreement among raft nodes before linearized reading' (duration: 602.166567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.823998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.221574Z","time spent":"602.412124ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T03:37:29.824052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.981123ms","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-10-14T03:37:29.823963Z","caller":"traceutil/trace.go:171","msg":"trace[1743662345] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26438; }","duration":"355.416106ms","start":"2026-10-14T03:37:29.468224Z","end":"2026-10-14T03:37:29.82364Z","steps":["trace[1743662345] 'read index received' (duration: 132.457751ms)","trace[1743662345] 'applied index is now lower than readState.Index' (duration: 222.955085ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:29.824052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.007655ms","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-10-14T03:37:29.82416Z","caller":"traceutil/trace.go:171","msg":"trace[284009416] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"833.098746ms","start":"2026-10-14T03:37:28.990983Z","end":"2026-10-14T03:37:29.824081Z","steps":["trace[284009416] 'process raft request' (duration: 832.301283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.824309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.895385ms","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-10-14T03:37:29.824172Z","caller":"traceutil/trace.go:171","msg":"trace[750584296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25620; }","duration":"600.127776ms","start":"2026-10-14T03:37:29.224009Z","end":"2026-10-14T03:37:29.824136Z","steps":["trace[750584296] 'agreement among raft nodes before linearized reading' (duration: 599.769076ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:29.824354Z","caller":"traceutil/trace.go:171","msg":"trace[1706945284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25620; }","duration":"606.154633ms","start":"2026-10-14T03:37:29.218186Z","end":"2026-10-14T03:37:29.824341Z","steps":["trace[1706945284] 'agreement among raft nodes before linearized reading' (duration: 605.864074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.824387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.218161Z","time spent":"606.217175ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T03:37:29.824476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:28.99094Z","time spent":"833.392975ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:29.824541Z","caller":"traceutil/trace.go:171","msg":"trace[248651858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25620; }","duration":"599.520691ms","start":"2026-10-14T03:37:29.224987Z","end":"2026-10-14T03:37:29.824508Z","steps":["trace[248651858] 'agreement among raft nodes before linearized reading' (duration: 598.83671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.824624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.224977Z","time spent":"599.627744ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T03:37:29.824468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.223998Z","time spent":"600.386415ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T03:37:29.840188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.100417ms","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":"warn","ts":"2026-10-14T03:37:29.84025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.629501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:29.840276Z","caller":"traceutil/trace.go:171","msg":"trace[436539217] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25621; }","duration":"369.230951ms","start":"2026-10-14T03:37:29.471024Z","end":"2026-10-14T03:37:29.840254Z","steps":["trace[436539217] 'agreement among raft nodes before linearized reading' (duration: 369.072516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.840325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.471003Z","time spent":"369.311942ms","remote":"127.0.0.1:57342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-10-14T03:37:29.840325Z","caller":"traceutil/trace.go:171","msg":"trace[1119436307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25621; }","duration":"332.742234ms","start":"2026-10-14T03:37:29.507562Z","end":"2026-10-14T03:37:29.840304Z","steps":["trace[1119436307] 'agreement among raft nodes before linearized reading' (duration: 332.61398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:29.840372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:29.507546Z","time spent":"332.815266ms","remote":"127.0.0.1:58670","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-10-14T03:37:30.266599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.622365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:37:30.266711Z","caller":"traceutil/trace.go:171","msg":"trace[614293347] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25625; }","duration":"119.766849ms","start":"2026-10-14T03:37:30.146907Z","end":"2026-10-14T03:37:30.266674Z","steps":["trace[614293347] 'count revisions from in-memory index tree' (duration: 119.541043ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:30.267244Z","caller":"traceutil/trace.go:171","msg":"trace[1956233620] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"113.014203ms","start":"2026-10-14T03:37:30.154197Z","end":"2026-10-14T03:37:30.267211Z","steps":["trace[1956233620] 'process raft request' (duration: 110.777007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:30.695438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.036648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:30.695529Z","caller":"traceutil/trace.go:171","msg":"trace[1758642458] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25626; }","duration":"197.181752ms","start":"2026-10-14T03:37:30.498327Z","end":"2026-10-14T03:37:30.695508Z","steps":["trace[1758642458] 'count revisions from in-memory index tree' (duration: 196.915494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:30.695605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.131979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.28\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-14T03:37:30.69568Z","caller":"traceutil/trace.go:171","msg":"trace[1542517760] range","detail":"{range_begin:/registry/masterleases/162.253.55.28; range_end:; response_count:1; response_revision:25626; }","duration":"188.252502ms","start":"2026-10-14T03:37:30.50741Z","end":"2026-10-14T03:37:30.695663Z","steps":["trace[1542517760] 'range keys from in-memory index tree' (duration: 187.910982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.019979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:30.696819Z","time spent":"323.154553ms","remote":"127.0.0.1:58690","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-14T03:37:31.035496Z","caller":"traceutil/trace.go:171","msg":"trace[1768008461] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"242.634721ms","start":"2026-10-14T03:37:30.792831Z","end":"2026-10-14T03:37:31.035466Z","steps":["trace[1768008461] 'process raft request' (duration: 242.448445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.590511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.664523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:31.590615Z","caller":"traceutil/trace.go:171","msg":"trace[698973311] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"569.309618ms","start":"2026-10-14T03:37:31.021286Z","end":"2026-10-14T03:37:31.590595Z","steps":["trace[698973311] 'process raft request' (duration: 212.464369ms)","trace[698973311] 'compare' (duration: 355.695195ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:31.590674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:31.021266Z","time spent":"569.379719ms","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:31.885118Z","caller":"traceutil/trace.go:171","msg":"trace[1050006230] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"782.819386ms","start":"2026-10-14T03:37:31.102273Z","end":"2026-10-14T03:37:31.885093Z","steps":["trace[1050006230] 'process raft request' (duration: 782.737134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.885294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:31.102261Z","time spent":"782.942739ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:31.885311Z","caller":"traceutil/trace.go:171","msg":"trace[1464152927] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"791.608232ms","start":"2026-10-14T03:37:31.093635Z","end":"2026-10-14T03:37:31.885243Z","steps":["trace[1464152927] 'process raft request' (duration: 771.241538ms)","trace[1464152927] 'compare' (duration: 19.925202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:31.885573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:31.093611Z","time spent":"791.82496ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:31.890019Z","caller":"traceutil/trace.go:171","msg":"trace[664630757] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"414.246466ms","start":"2026-10-14T03:37:31.475753Z","end":"2026-10-14T03:37:31.89Z","steps":["trace[664630757] 'process raft request' (duration: 413.990779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.890136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:31.47568Z","time spent":"414.38734ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:31.890195Z","caller":"traceutil/trace.go:171","msg":"trace[390951281] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"412.33313ms","start":"2026-10-14T03:37:31.477835Z","end":"2026-10-14T03:37:31.890168Z","steps":["trace[390951281] 'process raft request' (duration: 412.00124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.890359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:31.477821Z","time spent":"412.446444ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T03:37:31.890831Z","caller":"traceutil/trace.go:171","msg":"trace[605505481] linearizableReadLoop","detail":"{readStateIndex:26455; appliedIndex:26451; }","duration":"385.343961ms","start":"2026-10-14T03:37:31.505451Z","end":"2026-10-14T03:37:31.890795Z","steps":["trace[605505481] 'read index received' (duration: 359.436684ms)","trace[605505481] 'applied index is now lower than readState.Index' (duration: 25.905627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:31.891003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.538207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:31.891235Z","caller":"traceutil/trace.go:171","msg":"trace[553115457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25636; }","duration":"385.806024ms","start":"2026-10-14T03:37:31.505404Z","end":"2026-10-14T03:37:31.89121Z","steps":["trace[553115457] 'agreement among raft nodes before linearized reading' (duration: 385.509335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.891292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:31.50539Z","time spent":"385.888267ms","remote":"127.0.0.1:58670","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-10-14T03:37:31.891951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.749396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:31.892005Z","caller":"traceutil/trace.go:171","msg":"trace[81924468] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25636; }","duration":"238.837839ms","start":"2026-10-14T03:37:31.653153Z","end":"2026-10-14T03:37:31.891991Z","steps":["trace[81924468] 'agreement among raft nodes before linearized reading' (duration: 238.744296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:31.892092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.776712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-14T03:37:31.892158Z","caller":"traceutil/trace.go:171","msg":"trace[738506454] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25636; }","duration":"298.580945ms","start":"2026-10-14T03:37:31.593558Z","end":"2026-10-14T03:37:31.892139Z","steps":["trace[738506454] 'agreement among raft nodes before linearized reading' (duration: 297.689939ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:34.313125Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006773.wal"} {"level":"info","ts":"2026-10-14T03:37:37.245107Z","caller":"traceutil/trace.go:171","msg":"trace[1471023249] linearizableReadLoop","detail":"{readStateIndex:26555; appliedIndex:26554; }","duration":"186.971483ms","start":"2026-10-14T03:37:37.058105Z","end":"2026-10-14T03:37:37.245076Z","steps":["trace[1471023249] 'read index received' (duration: 91.206736ms)","trace[1471023249] 'applied index is now lower than readState.Index' (duration: 95.763437ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:37.245262Z","caller":"traceutil/trace.go:171","msg":"trace[1280012445] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"190.646952ms","start":"2026-10-14T03:37:37.054597Z","end":"2026-10-14T03:37:37.245244Z","steps":["trace[1280012445] 'process raft request' (duration: 94.77866ms)","trace[1280012445] 'compare' (duration: 95.548672ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:37.245817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.687905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:37:37.2459Z","caller":"traceutil/trace.go:171","msg":"trace[1057906465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25731; }","duration":"187.818518ms","start":"2026-10-14T03:37:37.058062Z","end":"2026-10-14T03:37:37.245881Z","steps":["trace[1057906465] 'agreement among raft nodes before linearized reading' (duration: 187.52834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:37.566434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.505836ms","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-10-14T03:37:37.566535Z","caller":"traceutil/trace.go:171","msg":"trace[526414570] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25731; }","duration":"204.6638ms","start":"2026-10-14T03:37:37.361847Z","end":"2026-10-14T03:37:37.566511Z","steps":["trace[526414570] 'range keys from in-memory index tree' (duration: 204.412044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:37.566889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.80246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:37:37.566966Z","caller":"traceutil/trace.go:171","msg":"trace[1525840105] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25731; }","duration":"119.928824ms","start":"2026-10-14T03:37:37.447019Z","end":"2026-10-14T03:37:37.566948Z","steps":["trace[1525840105] 'count revisions from in-memory index tree' (duration: 119.684977ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:37.862424Z","caller":"traceutil/trace.go:171","msg":"trace[77212553] linearizableReadLoop","detail":"{readStateIndex:26556; appliedIndex:26555; }","duration":"288.077617ms","start":"2026-10-14T03:37:37.574318Z","end":"2026-10-14T03:37:37.862395Z","steps":["trace[77212553] 'read index received' (duration: 287.82506ms)","trace[77212553] 'applied index is now lower than readState.Index' (duration: 251.407µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:37.862482Z","caller":"traceutil/trace.go:171","msg":"trace[1429056938] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"288.17625ms","start":"2026-10-14T03:37:37.57428Z","end":"2026-10-14T03:37:37.862456Z","steps":["trace[1429056938] 'process raft request' (duration: 287.962144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:37.862617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.846776ms","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-10-14T03:37:37.862672Z","caller":"traceutil/trace.go:171","msg":"trace[690475017] 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:25732; }","duration":"257.97941ms","start":"2026-10-14T03:37:37.604675Z","end":"2026-10-14T03:37:37.862655Z","steps":["trace[690475017] 'agreement among raft nodes before linearized reading' (duration: 257.871306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:37.862667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.322855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-10-14T03:37:37.862727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.873986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:37:37.862764Z","caller":"traceutil/trace.go:171","msg":"trace[948746207] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25732; }","duration":"191.958399ms","start":"2026-10-14T03:37:37.670791Z","end":"2026-10-14T03:37:37.862749Z","steps":["trace[948746207] 'agreement among raft nodes before linearized reading' (duration: 191.887297ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:37.862762Z","caller":"traceutil/trace.go:171","msg":"trace[656972051] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25732; }","duration":"288.47314ms","start":"2026-10-14T03:37:37.574269Z","end":"2026-10-14T03:37:37.862742Z","steps":["trace[656972051] 'agreement among raft nodes before linearized reading' (duration: 288.235453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:37.979386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.900921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-10-14T03:37:37.979479Z","caller":"traceutil/trace.go:171","msg":"trace[2065398887] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25732; }","duration":"111.029365ms","start":"2026-10-14T03:37:37.868433Z","end":"2026-10-14T03:37:37.979462Z","steps":["trace[2065398887] 'range keys from in-memory index tree' (duration: 110.750287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:37.979539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.989701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-14T03:37:37.979627Z","caller":"traceutil/trace.go:171","msg":"trace[1670044855] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25732; }","duration":"102.125774ms","start":"2026-10-14T03:37:37.877479Z","end":"2026-10-14T03:37:37.979605Z","steps":["trace[1670044855] 'range keys from in-memory index tree' (duration: 101.733554ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:52.537004Z","caller":"traceutil/trace.go:171","msg":"trace[1565951120] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"145.555864ms","start":"2026-10-14T03:37:52.391419Z","end":"2026-10-14T03:37:52.536975Z","steps":["trace[1565951120] 'process raft request' (duration: 145.327387ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:37:53.306459Z","caller":"traceutil/trace.go:171","msg":"trace[1332021210] linearizableReadLoop","detail":"{readStateIndex:26708; appliedIndex:26707; }","duration":"180.556287ms","start":"2026-10-14T03:37:53.125879Z","end":"2026-10-14T03:37:53.306435Z","steps":["trace[1332021210] 'read index received' (duration: 180.397003ms)","trace[1332021210] 'applied index is now lower than readState.Index' (duration: 157.384µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:53.306527Z","caller":"traceutil/trace.go:171","msg":"trace[642100654] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"181.961948ms","start":"2026-10-14T03:37:53.124525Z","end":"2026-10-14T03:37:53.306487Z","steps":["trace[642100654] 'process raft request' (duration: 181.745481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:53.306663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.763993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-14T03:37:53.306736Z","caller":"traceutil/trace.go:171","msg":"trace[21637297] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25881; }","duration":"180.859125ms","start":"2026-10-14T03:37:53.125861Z","end":"2026-10-14T03:37:53.30672Z","steps":["trace[21637297] 'agreement among raft nodes before linearized reading' (duration: 180.651189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:53.584325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.25589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:37:53.584429Z","caller":"traceutil/trace.go:171","msg":"trace[1661225428] linearizableReadLoop","detail":"{readStateIndex:26709; appliedIndex:26708; }","duration":"277.887949ms","start":"2026-10-14T03:37:53.306523Z","end":"2026-10-14T03:37:53.584411Z","steps":["trace[1661225428] 'read index received' (duration: 127.435403ms)","trace[1661225428] 'applied index is now lower than readState.Index' (duration: 150.451366ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:37:53.584453Z","caller":"traceutil/trace.go:171","msg":"trace[1088514013] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"281.931658ms","start":"2026-10-14T03:37:53.3025Z","end":"2026-10-14T03:37:53.584432Z","steps":["trace[1088514013] 'process raft request' (duration: 131.481732ms)","trace[1088514013] 'compare' (duration: 150.034784ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:37:53.585401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.655666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:37:53.585484Z","caller":"traceutil/trace.go:171","msg":"trace[846263062] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25882; }","duration":"416.806501ms","start":"2026-10-14T03:37:53.168643Z","end":"2026-10-14T03:37:53.58545Z","steps":["trace[846263062] 'agreement among raft nodes before linearized reading' (duration: 415.828542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:37:53.585524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:37:53.168613Z","time spent":"416.901713ms","remote":"127.0.0.1:58830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T03:37:53.596177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.155567ms","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-10-14T03:37:53.596232Z","caller":"traceutil/trace.go:171","msg":"trace[50718737] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25883; }","duration":"280.208648ms","start":"2026-10-14T03:37:53.316006Z","end":"2026-10-14T03:37:53.596215Z","steps":["trace[50718737] 'agreement among raft nodes before linearized reading' (duration: 280.062654ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:00.34455Z","caller":"traceutil/trace.go:171","msg":"trace[844303240] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"244.406042ms","start":"2026-10-14T03:38:00.100103Z","end":"2026-10-14T03:38:00.344509Z","steps":["trace[844303240] 'process raft request' (duration: 204.157636ms)","trace[844303240] 'compare' (duration: 39.947197ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:00.344992Z","caller":"traceutil/trace.go:171","msg":"trace[1533587105] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"243.654231ms","start":"2026-10-14T03:38:00.101273Z","end":"2026-10-14T03:38:00.344927Z","steps":["trace[1533587105] 'process raft request' (duration: 243.198897ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:00.345082Z","caller":"traceutil/trace.go:171","msg":"trace[772001436] linearizableReadLoop","detail":"{readStateIndex:26760; appliedIndex:26754; }","duration":"212.17782ms","start":"2026-10-14T03:38:00.132876Z","end":"2026-10-14T03:38:00.345053Z","steps":["trace[772001436] 'read index received' (duration: 36.476345ms)","trace[772001436] 'applied index is now lower than readState.Index' (duration: 175.699935ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:00.345247Z","caller":"traceutil/trace.go:171","msg":"trace[1706346436] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"235.77837ms","start":"2026-10-14T03:38:00.109451Z","end":"2026-10-14T03:38:00.34523Z","steps":["trace[1706346436] 'process raft request' (duration: 235.472782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:00.345486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.591702ms","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-10-14T03:38:00.345921Z","caller":"traceutil/trace.go:171","msg":"trace[27314979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25932; }","duration":"213.044645ms","start":"2026-10-14T03:38:00.132806Z","end":"2026-10-14T03:38:00.345851Z","steps":["trace[27314979] 'agreement among raft nodes before linearized reading' (duration: 212.490599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:00.345918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.320279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-14T03:38:00.345985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.065937ms","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-10-14T03:38:00.345715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.527903ms","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-10-14T03:38:00.346127Z","caller":"traceutil/trace.go:171","msg":"trace[1161794226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25932; }","duration":"193.557206ms","start":"2026-10-14T03:38:00.152535Z","end":"2026-10-14T03:38:00.346092Z","steps":["trace[1161794226] 'agreement among raft nodes before linearized reading' (duration: 193.172105ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:00.346175Z","caller":"traceutil/trace.go:171","msg":"trace[2095266540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25932; }","duration":"200.261502ms","start":"2026-10-14T03:38:00.145839Z","end":"2026-10-14T03:38:00.346101Z","steps":["trace[2095266540] 'agreement among raft nodes before linearized reading' (duration: 199.83896ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:00.346238Z","caller":"traceutil/trace.go:171","msg":"trace[143908460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25932; }","duration":"209.07404ms","start":"2026-10-14T03:38:00.137121Z","end":"2026-10-14T03:38:00.346195Z","steps":["trace[143908460] 'agreement among raft nodes before linearized reading' (duration: 208.381699ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:00.345738Z","caller":"traceutil/trace.go:171","msg":"trace[2118067809] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"236.721597ms","start":"2026-10-14T03:38:00.108956Z","end":"2026-10-14T03:38:00.345677Z","steps":["trace[2118067809] 'process raft request' (duration: 235.790181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:00.345753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.801148ms","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-10-14T03:38:00.346407Z","caller":"traceutil/trace.go:171","msg":"trace[479636096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25932; }","duration":"200.475588ms","start":"2026-10-14T03:38:00.145915Z","end":"2026-10-14T03:38:00.346391Z","steps":["trace[479636096] 'agreement among raft nodes before linearized reading' (duration: 199.660984ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:00.345612Z","caller":"traceutil/trace.go:171","msg":"trace[960039379] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"234.252795ms","start":"2026-10-14T03:38:00.111308Z","end":"2026-10-14T03:38:00.345561Z","steps":["trace[960039379] 'process raft request' (duration: 233.685089ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:08.822052Z","caller":"traceutil/trace.go:171","msg":"trace[1906460115] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"277.505669ms","start":"2026-10-14T03:38:08.544521Z","end":"2026-10-14T03:38:08.822027Z","steps":["trace[1906460115] 'process raft request' (duration: 185.247423ms)","trace[1906460115] 'compare' (duration: 91.862245ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:08.831478Z","caller":"traceutil/trace.go:171","msg":"trace[1356595639] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"284.548215ms","start":"2026-10-14T03:38:08.546838Z","end":"2026-10-14T03:38:08.831386Z","steps":["trace[1356595639] 'process raft request' (duration: 284.305888ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:08.831498Z","caller":"traceutil/trace.go:171","msg":"trace[1240994800] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"284.551296ms","start":"2026-10-14T03:38:08.546923Z","end":"2026-10-14T03:38:08.831474Z","steps":["trace[1240994800] 'process raft request' (duration: 284.36022ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:08.831777Z","caller":"traceutil/trace.go:171","msg":"trace[1947116606] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"284.773391ms","start":"2026-10-14T03:38:08.546976Z","end":"2026-10-14T03:38:08.83175Z","steps":["trace[1947116606] 'process raft request' (duration: 284.414561ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:08.83184Z","caller":"traceutil/trace.go:171","msg":"trace[847963613] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26834; }","duration":"225.19509ms","start":"2026-10-14T03:38:08.606621Z","end":"2026-10-14T03:38:08.831816Z","steps":["trace[847963613] 'read index received' (duration: 123.230151ms)","trace[847963613] 'applied index is now lower than readState.Index' (duration: 101.963659ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:08.831919Z","caller":"traceutil/trace.go:171","msg":"trace[1501717586] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"212.146589ms","start":"2026-10-14T03:38:08.619763Z","end":"2026-10-14T03:38:08.831909Z","steps":["trace[1501717586] 'process raft request' (duration: 211.680796ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:08.831982Z","caller":"traceutil/trace.go:171","msg":"trace[1529301900] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"192.639479ms","start":"2026-10-14T03:38:08.639331Z","end":"2026-10-14T03:38:08.83197Z","steps":["trace[1529301900] 'process raft request' (duration: 192.429353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:08.832249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.618593ms","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-10-14T03:38:08.832298Z","caller":"traceutil/trace.go:171","msg":"trace[1972120072] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26009; }","duration":"225.678075ms","start":"2026-10-14T03:38:08.606604Z","end":"2026-10-14T03:38:08.832282Z","steps":["trace[1972120072] 'agreement among raft nodes before linearized reading' (duration: 225.52083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:09.135541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.402029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:38:09.135631Z","caller":"traceutil/trace.go:171","msg":"trace[921031374] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26009; }","duration":"146.574913ms","start":"2026-10-14T03:38:08.989035Z","end":"2026-10-14T03:38:09.13561Z","steps":["trace[921031374] 'range keys from in-memory index tree' (duration: 146.158552ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:10.677489Z","caller":"traceutil/trace.go:171","msg":"trace[2049141307] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"113.061394ms","start":"2026-10-14T03:38:10.564406Z","end":"2026-10-14T03:38:10.677467Z","steps":["trace[2049141307] 'process raft request' (duration: 101.143476ms)","trace[2049141307] 'compare' (duration: 11.740633ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:12.577438Z","caller":"traceutil/trace.go:171","msg":"trace[706083390] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"179.751963ms","start":"2026-10-14T03:38:12.397664Z","end":"2026-10-14T03:38:12.577416Z","steps":["trace[706083390] 'process raft request' (duration: 179.074994ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:12.578016Z","caller":"traceutil/trace.go:171","msg":"trace[1500327035] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"180.235758ms","start":"2026-10-14T03:38:12.397765Z","end":"2026-10-14T03:38:12.578001Z","steps":["trace[1500327035] 'process raft request' (duration: 179.190577ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:12.578211Z","caller":"traceutil/trace.go:171","msg":"trace[1840163305] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"180.387161ms","start":"2026-10-14T03:38:12.397813Z","end":"2026-10-14T03:38:12.5782Z","steps":["trace[1840163305] 'process raft request' (duration: 179.203106ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:12.578338Z","caller":"traceutil/trace.go:171","msg":"trace[177205548] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"178.712453ms","start":"2026-10-14T03:38:12.399615Z","end":"2026-10-14T03:38:12.578327Z","steps":["trace[177205548] 'process raft request' (duration: 177.439376ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.078473Z","caller":"traceutil/trace.go:171","msg":"trace[355459973] linearizableReadLoop","detail":"{readStateIndex:26946; appliedIndex:26945; }","duration":"111.708633ms","start":"2026-10-14T03:38:23.966742Z","end":"2026-10-14T03:38:24.078451Z","steps":["trace[355459973] 'read index received' (duration: 111.556349ms)","trace[355459973] 'applied index is now lower than readState.Index' (duration: 151.244µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:24.078505Z","caller":"traceutil/trace.go:171","msg":"trace[1972423255] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"327.383576ms","start":"2026-10-14T03:38:23.751097Z","end":"2026-10-14T03:38:24.07848Z","steps":["trace[1972423255] 'process raft request' (duration: 327.189081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:24.078659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:38:23.751069Z","time spent":"327.50275ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:38:24.078772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.017534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T03:38:24.078864Z","caller":"traceutil/trace.go:171","msg":"trace[794682460] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26112; }","duration":"112.193559ms","start":"2026-10-14T03:38:23.966655Z","end":"2026-10-14T03:38:24.078849Z","steps":["trace[794682460] 'agreement among raft nodes before linearized reading' (duration: 111.903939ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.234345Z","caller":"traceutil/trace.go:171","msg":"trace[1294155441] linearizableReadLoop","detail":"{readStateIndex:26947; appliedIndex:26946; }","duration":"155.784293ms","start":"2026-10-14T03:38:24.078534Z","end":"2026-10-14T03:38:24.234319Z","steps":["trace[1294155441] 'read index received' (duration: 130.03657ms)","trace[1294155441] 'applied index is now lower than readState.Index' (duration: 25.746823ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:24.234506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.247386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:24.234543Z","caller":"traceutil/trace.go:171","msg":"trace[256114764] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:26113; }","duration":"157.334518ms","start":"2026-10-14T03:38:24.077198Z","end":"2026-10-14T03:38:24.234532Z","steps":["trace[256114764] 'agreement among raft nodes before linearized reading' (duration: 157.211965ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.234779Z","caller":"traceutil/trace.go:171","msg":"trace[1725651453] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"159.026738ms","start":"2026-10-14T03:38:24.075707Z","end":"2026-10-14T03:38:24.234734Z","steps":["trace[1725651453] 'process raft request' (duration: 133.044178ms)","trace[1725651453] 'compare' (duration: 25.442094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:24.550602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.930124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:38:24.550725Z","caller":"traceutil/trace.go:171","msg":"trace[570277151] linearizableReadLoop","detail":"{readStateIndex:26948; appliedIndex:26947; }","duration":"257.365061ms","start":"2026-10-14T03:38:24.293342Z","end":"2026-10-14T03:38:24.550707Z","steps":["trace[570277151] 'read index received' (duration: 98.112177ms)","trace[570277151] 'applied index is now lower than readState.Index' (duration: 159.251624ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:24.55081Z","caller":"traceutil/trace.go:171","msg":"trace[109703448] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"299.518773ms","start":"2026-10-14T03:38:24.25126Z","end":"2026-10-14T03:38:24.550779Z","steps":["trace[109703448] 'process raft request' (duration: 140.332261ms)","trace[109703448] 'compare' (duration: 158.674077ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:24.550871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.536537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-14T03:38:24.550923Z","caller":"traceutil/trace.go:171","msg":"trace[933260941] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26114; }","duration":"257.604488ms","start":"2026-10-14T03:38:24.293286Z","end":"2026-10-14T03:38:24.550891Z","steps":["trace[933260941] 'agreement among raft nodes before linearized reading' (duration: 257.486665ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.553322Z","caller":"traceutil/trace.go:171","msg":"trace[353930517] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"112.778646ms","start":"2026-10-14T03:38:24.440518Z","end":"2026-10-14T03:38:24.553297Z","steps":["trace[353930517] 'process raft request' (duration: 112.62475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:24.63097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.066266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:24.63105Z","caller":"traceutil/trace.go:171","msg":"trace[857484483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26116; }","duration":"124.185459ms","start":"2026-10-14T03:38:24.506847Z","end":"2026-10-14T03:38:24.631033Z","steps":["trace[857484483] 'agreement among raft nodes before linearized reading' (duration: 124.020964ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.631221Z","caller":"traceutil/trace.go:171","msg":"trace[811620632] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"178.142366ms","start":"2026-10-14T03:38:24.453051Z","end":"2026-10-14T03:38:24.631194Z","steps":["trace[811620632] 'process raft request' (duration: 177.647422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:24.631226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.324926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:38:24.631381Z","caller":"traceutil/trace.go:171","msg":"trace[981028938] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26116; }","duration":"158.540692ms","start":"2026-10-14T03:38:24.472821Z","end":"2026-10-14T03:38:24.631362Z","steps":["trace[981028938] 'agreement among raft nodes before linearized reading' (duration: 158.09146ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.752571Z","caller":"traceutil/trace.go:171","msg":"trace[1038515873] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"133.505733ms","start":"2026-10-14T03:38:24.619046Z","end":"2026-10-14T03:38:24.752552Z","steps":["trace[1038515873] 'process raft request' (duration: 106.73002ms)","trace[1038515873] 'compare' (duration: 26.640719ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:24.752919Z","caller":"traceutil/trace.go:171","msg":"trace[1835021085] linearizableReadLoop","detail":"{readStateIndex:26954; appliedIndex:26950; }","duration":"122.057956ms","start":"2026-10-14T03:38:24.630839Z","end":"2026-10-14T03:38:24.752897Z","steps":["trace[1835021085] 'read index received' (duration: 94.878672ms)","trace[1835021085] 'applied index is now lower than readState.Index' (duration: 27.175064ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:24.752928Z","caller":"traceutil/trace.go:171","msg":"trace[1923439392] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"130.177074ms","start":"2026-10-14T03:38:24.622721Z","end":"2026-10-14T03:38:24.752898Z","steps":["trace[1923439392] 'process raft request' (duration: 130.02624ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.752972Z","caller":"traceutil/trace.go:171","msg":"trace[1961520216] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"128.588488ms","start":"2026-10-14T03:38:24.624362Z","end":"2026-10-14T03:38:24.75295Z","steps":["trace[1961520216] 'process raft request' (duration: 128.486395ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.753082Z","caller":"traceutil/trace.go:171","msg":"trace[1690735348] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"130.970817ms","start":"2026-10-14T03:38:24.6221Z","end":"2026-10-14T03:38:24.753071Z","steps":["trace[1690735348] 'process raft request' (duration: 130.414441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:24.753171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.905533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:24.75321Z","caller":"traceutil/trace.go:171","msg":"trace[1782232638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26120; }","duration":"121.004356ms","start":"2026-10-14T03:38:24.632196Z","end":"2026-10-14T03:38:24.7532Z","steps":["trace[1782232638] 'agreement among raft nodes before linearized reading' (duration: 120.860862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:24.753298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.349766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3ead67c9-7c71-412b-94b4-a67cc847d71b\" ","response":"range_response_count:1 size:1324"} {"level":"warn","ts":"2026-10-14T03:38:24.753299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.241265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:38:24.753348Z","caller":"traceutil/trace.go:171","msg":"trace[1399977208] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3ead67c9-7c71-412b-94b4-a67cc847d71b; range_end:; response_count:1; response_revision:26120; }","duration":"198.439119ms","start":"2026-10-14T03:38:24.554893Z","end":"2026-10-14T03:38:24.753332Z","steps":["trace[1399977208] 'agreement among raft nodes before linearized reading' (duration: 198.284404ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.753351Z","caller":"traceutil/trace.go:171","msg":"trace[1873315213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26120; }","duration":"118.334358ms","start":"2026-10-14T03:38:24.635004Z","end":"2026-10-14T03:38:24.753338Z","steps":["trace[1873315213] 'agreement among raft nodes before linearized reading' (duration: 118.191644ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:24.92796Z","caller":"traceutil/trace.go:171","msg":"trace[1682800545] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"170.346788ms","start":"2026-10-14T03:38:24.757583Z","end":"2026-10-14T03:38:24.92793Z","steps":["trace[1682800545] 'process raft request' (duration: 106.775151ms)","trace[1682800545] 'compare' (duration: 62.756213ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:24.927549Z","caller":"traceutil/trace.go:171","msg":"trace[1974602464] linearizableReadLoop","detail":"{readStateIndex:26955; appliedIndex:26954; }","duration":"101.077163ms","start":"2026-10-14T03:38:24.826451Z","end":"2026-10-14T03:38:24.927528Z","steps":["trace[1974602464] 'read index received' (duration: 37.833786ms)","trace[1974602464] 'applied index is now lower than readState.Index' (duration: 63.242307ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:24.928173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.711641ms","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-10-14T03:38:24.928238Z","caller":"traceutil/trace.go:171","msg":"trace[79214776] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26122; }","duration":"101.787413ms","start":"2026-10-14T03:38:24.826434Z","end":"2026-10-14T03:38:24.928221Z","steps":["trace[79214776] 'agreement among raft nodes before linearized reading' (duration: 101.613808ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:28.92516Z","caller":"traceutil/trace.go:171","msg":"trace[1131264597] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"152.699942ms","start":"2026-10-14T03:38:28.772428Z","end":"2026-10-14T03:38:28.925128Z","steps":["trace[1131264597] 'process raft request' (duration: 152.456905ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:28.925214Z","caller":"traceutil/trace.go:171","msg":"trace[1587248609] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"152.044594ms","start":"2026-10-14T03:38:28.773139Z","end":"2026-10-14T03:38:28.925184Z","steps":["trace[1587248609] 'process raft request' (duration: 151.881249ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:28.925172Z","caller":"traceutil/trace.go:171","msg":"trace[1014269795] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"151.065845ms","start":"2026-10-14T03:38:28.774041Z","end":"2026-10-14T03:38:28.925107Z","steps":["trace[1014269795] 'process raft request' (duration: 151.022993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:34.667421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.313967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:38:34.667541Z","caller":"traceutil/trace.go:171","msg":"trace[181833945] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"288.691266ms","start":"2026-10-14T03:38:34.37883Z","end":"2026-10-14T03:38:34.667522Z","steps":["trace[181833945] 'process raft request' (duration: 130.189545ms)","trace[181833945] 'compare' (duration: 158.071149ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:34.751787Z","caller":"traceutil/trace.go:171","msg":"trace[392755064] linearizableReadLoop","detail":"{readStateIndex:27027; appliedIndex:27025; }","duration":"246.031379ms","start":"2026-10-14T03:38:34.50573Z","end":"2026-10-14T03:38:34.751761Z","steps":["trace[392755064] 'read index received' (duration: 3.208843ms)","trace[392755064] 'applied index is now lower than readState.Index' (duration: 242.819295ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:34.751858Z","caller":"traceutil/trace.go:171","msg":"trace[1224604680] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"257.511245ms","start":"2026-10-14T03:38:34.494303Z","end":"2026-10-14T03:38:34.751814Z","steps":["trace[1224604680] 'process raft request' (duration: 257.267368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:34.751941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.195704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:34.751981Z","caller":"traceutil/trace.go:171","msg":"trace[443912767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26191; }","duration":"246.301348ms","start":"2026-10-14T03:38:34.50567Z","end":"2026-10-14T03:38:34.751971Z","steps":["trace[443912767] 'agreement among raft nodes before linearized reading' (duration: 246.212234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:34.96292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.898468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:38:34.963182Z","caller":"traceutil/trace.go:171","msg":"trace[1710392708] linearizableReadLoop","detail":"{readStateIndex:27029; appliedIndex:27027; }","duration":"211.300455ms","start":"2026-10-14T03:38:34.751856Z","end":"2026-10-14T03:38:34.963156Z","steps":["trace[1710392708] 'read index received' (duration: 18.943154ms)","trace[1710392708] 'applied index is now lower than readState.Index' (duration: 192.355681ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:34.963295Z","caller":"traceutil/trace.go:171","msg":"trace[799882047] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"255.681252ms","start":"2026-10-14T03:38:34.707595Z","end":"2026-10-14T03:38:34.963276Z","steps":["trace[799882047] 'process raft request' (duration: 255.481366ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:34.963311Z","caller":"traceutil/trace.go:171","msg":"trace[1097701433] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"314.829209ms","start":"2026-10-14T03:38:34.648444Z","end":"2026-10-14T03:38:34.963273Z","steps":["trace[1097701433] 'process raft request' (duration: 122.418297ms)","trace[1097701433] 'compare' (duration: 191.63314ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:34.963489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:38:34.648411Z","time spent":"314.978115ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T03:38:34.963622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.325353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-jlgv9\" ","response":"range_response_count:1 size:3396"} {"level":"info","ts":"2026-10-14T03:38:34.963663Z","caller":"traceutil/trace.go:171","msg":"trace[872188274] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-jlgv9; range_end:; response_count:1; response_revision:26193; }","duration":"240.402915ms","start":"2026-10-14T03:38:34.723248Z","end":"2026-10-14T03:38:34.963651Z","steps":["trace[872188274] 'agreement among raft nodes before linearized reading' (duration: 240.22225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:34.963701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.891683ms","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-10-14T03:38:34.963776Z","caller":"traceutil/trace.go:171","msg":"trace[1775091099] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26193; }","duration":"416.092879ms","start":"2026-10-14T03:38:34.547667Z","end":"2026-10-14T03:38:34.96376Z","steps":["trace[1775091099] 'agreement among raft nodes before linearized reading' (duration: 415.961646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:34.963831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T03:38:34.547634Z","time spent":"416.182822ms","remote":"127.0.0.1:57758","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-10-14T03:38:35.039157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.843985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:35.039236Z","caller":"traceutil/trace.go:171","msg":"trace[32144519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26197; }","duration":"284.961228ms","start":"2026-10-14T03:38:34.754257Z","end":"2026-10-14T03:38:35.039219Z","steps":["trace[32144519] 'agreement among raft nodes before linearized reading' (duration: 284.72755ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.154505Z","caller":"traceutil/trace.go:171","msg":"trace[1969431814] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"182.897395ms","start":"2026-10-14T03:38:34.971568Z","end":"2026-10-14T03:38:35.154466Z","steps":["trace[1969431814] 'process raft request' (duration: 137.020124ms)","trace[1969431814] 'compare' (duration: 45.296045ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:35.396472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.860588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:38:35.396631Z","caller":"traceutil/trace.go:171","msg":"trace[1481785331] linearizableReadLoop","detail":"{readStateIndex:27036; appliedIndex:27035; }","duration":"235.857093ms","start":"2026-10-14T03:38:35.160749Z","end":"2026-10-14T03:38:35.396606Z","steps":["trace[1481785331] 'read index received' (duration: 107.621505ms)","trace[1481785331] 'applied index is now lower than readState.Index' (duration: 128.233388ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:35.396759Z","caller":"traceutil/trace.go:171","msg":"trace[1606755475] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"237.16713ms","start":"2026-10-14T03:38:35.159575Z","end":"2026-10-14T03:38:35.396743Z","steps":["trace[1606755475] 'process raft request' (duration: 108.913322ms)","trace[1606755475] 'compare' (duration: 127.646561ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:35.396921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.160441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-14T03:38:35.397014Z","caller":"traceutil/trace.go:171","msg":"trace[2069589471] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26200; }","duration":"236.321066ms","start":"2026-10-14T03:38:35.160671Z","end":"2026-10-14T03:38:35.396992Z","steps":["trace[2069589471] 'agreement among raft nodes before linearized reading' (duration: 236.080579ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.446635Z","caller":"traceutil/trace.go:171","msg":"trace[1402072639] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"285.308228ms","start":"2026-10-14T03:38:35.161294Z","end":"2026-10-14T03:38:35.446603Z","steps":["trace[1402072639] 'process raft request' (duration: 285.08704ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.447016Z","caller":"traceutil/trace.go:171","msg":"trace[680446735] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"285.680699ms","start":"2026-10-14T03:38:35.161312Z","end":"2026-10-14T03:38:35.446993Z","steps":["trace[680446735] 'process raft request' (duration: 285.234406ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.447432Z","caller":"traceutil/trace.go:171","msg":"trace[1588985757] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"285.778761ms","start":"2026-10-14T03:38:35.161631Z","end":"2026-10-14T03:38:35.44741Z","steps":["trace[1588985757] 'process raft request' (duration: 285.608176ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.447481Z","caller":"traceutil/trace.go:171","msg":"trace[1741518155] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"285.456672ms","start":"2026-10-14T03:38:35.162008Z","end":"2026-10-14T03:38:35.447464Z","steps":["trace[1741518155] 'process raft request' (duration: 285.305628ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.447561Z","caller":"traceutil/trace.go:171","msg":"trace[313546496] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"286.105421ms","start":"2026-10-14T03:38:35.161444Z","end":"2026-10-14T03:38:35.44755Z","steps":["trace[313546496] 'process raft request' (duration: 285.476603ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.448029Z","caller":"traceutil/trace.go:171","msg":"trace[1660869042] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"285.805232ms","start":"2026-10-14T03:38:35.162211Z","end":"2026-10-14T03:38:35.448016Z","steps":["trace[1660869042] 'process raft request' (duration: 285.178133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:35.713525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.57359ms","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-10-14T03:38:35.713618Z","caller":"traceutil/trace.go:171","msg":"trace[776452045] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26206; }","duration":"190.709183ms","start":"2026-10-14T03:38:35.522882Z","end":"2026-10-14T03:38:35.713591Z","steps":["trace[776452045] 'range keys from in-memory index tree' (duration: 190.461316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:35.71352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.875656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:35.713739Z","caller":"traceutil/trace.go:171","msg":"trace[1783190625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26206; }","duration":"206.165795ms","start":"2026-10-14T03:38:35.507543Z","end":"2026-10-14T03:38:35.713709Z","steps":["trace[1783190625] 'range keys from in-memory index tree' (duration: 205.747512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:35.896197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.015974ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-14T03:38:35.896347Z","caller":"traceutil/trace.go:171","msg":"trace[384883442] linearizableReadLoop","detail":"{readStateIndex:27043; appliedIndex:27042; }","duration":"178.408964ms","start":"2026-10-14T03:38:35.717913Z","end":"2026-10-14T03:38:35.896322Z","steps":["trace[384883442] 'read index received' (duration: 55.621µs)","trace[384883442] 'applied index is now lower than readState.Index' (duration: 178.351103ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:35.896532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.60285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:35.896583Z","caller":"traceutil/trace.go:171","msg":"trace[2000696433] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26206; }","duration":"178.697623ms","start":"2026-10-14T03:38:35.717871Z","end":"2026-10-14T03:38:35.896568Z","steps":["trace[2000696433] 'agreement among raft nodes before linearized reading' (duration: 178.59559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:35.896626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.051679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-14T03:38:35.896667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.191421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T03:38:35.896741Z","caller":"traceutil/trace.go:171","msg":"trace[1956203400] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26206; }","duration":"170.211744ms","start":"2026-10-14T03:38:35.72651Z","end":"2026-10-14T03:38:35.896721Z","steps":["trace[1956203400] 'agreement among raft nodes before linearized reading' (duration: 170.006098ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:35.896766Z","caller":"traceutil/trace.go:171","msg":"trace[2117024135] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26206; }","duration":"161.320104ms","start":"2026-10-14T03:38:35.735426Z","end":"2026-10-14T03:38:35.896746Z","steps":["trace[2117024135] 'agreement among raft nodes before linearized reading' (duration: 161.144619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:35.896862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.071088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-jlgv9\" ","response":"range_response_count:1 size:3716"} {"level":"info","ts":"2026-10-14T03:38:35.896976Z","caller":"traceutil/trace.go:171","msg":"trace[56295322] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-jlgv9; range_end:; response_count:1; response_revision:26206; }","duration":"147.288984ms","start":"2026-10-14T03:38:35.74966Z","end":"2026-10-14T03:38:35.896949Z","steps":["trace[56295322] 'agreement among raft nodes before linearized reading' (duration: 146.916213ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:36.109204Z","caller":"traceutil/trace.go:171","msg":"trace[935450357] linearizableReadLoop","detail":"{readStateIndex:27044; appliedIndex:27043; }","duration":"193.192116ms","start":"2026-10-14T03:38:35.915979Z","end":"2026-10-14T03:38:36.109171Z","steps":["trace[935450357] 'read index received' (duration: 192.909168ms)","trace[935450357] 'applied index is now lower than readState.Index' (duration: 281.428µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:36.109263Z","caller":"traceutil/trace.go:171","msg":"trace[55358276] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"204.323461ms","start":"2026-10-14T03:38:35.904901Z","end":"2026-10-14T03:38:36.109224Z","steps":["trace[55358276] 'process raft request' (duration: 204.020903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:36.109495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.491184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-14T03:38:36.109552Z","caller":"traceutil/trace.go:171","msg":"trace[37500486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26207; }","duration":"193.603097ms","start":"2026-10-14T03:38:35.915931Z","end":"2026-10-14T03:38:36.109534Z","steps":["trace[37500486] 'agreement among raft nodes before linearized reading' (duration: 193.37017ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:36.373151Z","caller":"traceutil/trace.go:171","msg":"trace[1262261439] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"242.100275ms","start":"2026-10-14T03:38:36.131022Z","end":"2026-10-14T03:38:36.373122Z","steps":["trace[1262261439] 'process raft request' (duration: 242.036353ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:38:36.373152Z","caller":"traceutil/trace.go:171","msg":"trace[551790734] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"256.64684ms","start":"2026-10-14T03:38:36.116462Z","end":"2026-10-14T03:38:36.373109Z","steps":["trace[551790734] 'process raft request' (duration: 156.501874ms)","trace[551790734] 'compare' (duration: 99.682623ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:36.373548Z","caller":"traceutil/trace.go:171","msg":"trace[1062673871] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"255.289791ms","start":"2026-10-14T03:38:36.118247Z","end":"2026-10-14T03:38:36.373536Z","steps":["trace[1062673871] 'process raft request' (duration: 254.660012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:36.658819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.274607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T03:38:36.658995Z","caller":"traceutil/trace.go:171","msg":"trace[1174287492] linearizableReadLoop","detail":"{readStateIndex:27049; appliedIndex:27048; }","duration":"270.065922ms","start":"2026-10-14T03:38:36.388905Z","end":"2026-10-14T03:38:36.658971Z","steps":["trace[1174287492] 'read index received' (duration: 140.585298ms)","trace[1174287492] 'applied index is now lower than readState.Index' (duration: 129.413181ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T03:38:36.659092Z","caller":"traceutil/trace.go:171","msg":"trace[382676161] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"276.520211ms","start":"2026-10-14T03:38:36.382559Z","end":"2026-10-14T03:38:36.659079Z","steps":["trace[382676161] 'process raft request' (duration: 146.887982ms)","trace[382676161] 'compare' (duration: 129.122903ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T03:38:36.659203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.776485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:36.659289Z","caller":"traceutil/trace.go:171","msg":"trace[1189931134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26212; }","duration":"152.918488ms","start":"2026-10-14T03:38:36.506347Z","end":"2026-10-14T03:38:36.659266Z","steps":["trace[1189931134] 'agreement among raft nodes before linearized reading' (duration: 152.784204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T03:38:36.659395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.425372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T03:38:36.659485Z","caller":"traceutil/trace.go:171","msg":"trace[1341384360] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26212; }","duration":"270.611618ms","start":"2026-10-14T03:38:36.388851Z","end":"2026-10-14T03:38:36.659462Z","steps":["trace[1341384360] 'agreement among raft nodes before linearized reading' (duration: 270.388031ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T03:42:17.498449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25534} {"level":"info","ts":"2026-10-14T03:42:17.678394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25534,"took":"175.111197ms","hash":3903043324,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":36126720,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T03:42:17.678497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3903043324,"revision":25534,"compact-revision":22122} {"level":"info","ts":"2026-10-14T03:43:37.246018Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"328ca0518abde8c2","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T03:43:37.252912Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T03:43:37.253016Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T03:47:17.508208Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28453} {"level":"info","ts":"2026-10-14T03:47:17.662427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28453,"took":"148.905052ms","hash":1143353611,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T03:47:17.662516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1143353611,"revision":28453,"compact-revision":25534} {"level":"info","ts":"2026-10-14T03:52:17.519011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30737} {"level":"info","ts":"2026-10-14T03:52:17.661614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30737,"took":"138.285ms","hash":2352502705,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T03:52:17.661686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2352502705,"revision":30737,"compact-revision":28453} {"level":"info","ts":"2026-10-14T03:57:17.526845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32989} {"level":"info","ts":"2026-10-14T03:57:17.687369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32989,"took":"155.876895ms","hash":3761327136,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T03:57:17.687507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3761327136,"revision":32989,"compact-revision":30737} {"level":"info","ts":"2026-10-14T04:02:17.539123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35218} {"level":"info","ts":"2026-10-14T04:02:17.692274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35218,"took":"149.717815ms","hash":1916375225,"current-db-size-bytes":60416000,"current-db-size":"60 MB","current-db-size-in-use-bytes":32706560,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T04:02:17.692341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1916375225,"revision":35218,"compact-revision":32989} {"level":"info","ts":"2026-10-14T04:05:00.12703Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"328ca0518abde8c2","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T04:05:00.139419Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T04:05:00.139558Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T04:07:17.546615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37578} {"level":"info","ts":"2026-10-14T04:07:17.683152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37578,"took":"132.431461ms","hash":2716131659,"current-db-size-bytes":60841984,"current-db-size":"61 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T04:07:17.683241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2716131659,"revision":37578,"compact-revision":35218} {"level":"info","ts":"2026-10-14T04:12:17.555772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39884} {"level":"info","ts":"2026-10-14T04:12:17.702167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39884,"took":"141.483996ms","hash":1223018673,"current-db-size-bytes":61263872,"current-db-size":"61 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T04:12:17.702277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1223018673,"revision":39884,"compact-revision":37578}