{"level":"warn","ts":"2026-07-02T03:23:23.517036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T03:23:23.517142Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-07-02T03:23:23.517216Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T03:23:23.517228Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-07-02T03:23:23.517244Z","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-07-02T03:23:23.517949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-07-02T03:23:23.518025Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-07-02T03:23:23.522105Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.857136ms"} {"level":"info","ts":"2026-07-02T03:23:23.527985Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-07-02T03:23:23.528092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T03:23:23.528128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-07-02T03:23:23.528149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T03:23:23.528174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-07-02T03:23:23.528228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-07-02T03:23:23.534441Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T03:23:23.53586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T03:23:23.538123Z","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-07-02T03:23:23.540383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T03:23:23.540586Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T03:23:23.540619Z","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-07-02T03:23:23.540764Z","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-07-02T03:23:23.540781Z","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-07-02T03:23:23.543587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-07-02T03:23:23.543776Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-07-02T03:23:23.545914Z","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-07-02T03:23:23.546067Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-07-02T03:23:23.546125Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-07-02T03:23:23.546395Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T03:23:23.546488Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T03:23:24.429238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T03:23:24.429414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T03:23:24.429465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-07-02T03:23:24.429502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-07-02T03:23:24.429531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-07-02T03:23:24.429565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-07-02T03:23:24.429591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-07-02T03:23:24.430577Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:23:24.431536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T03:23:24.43154Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T03:23:24.431683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T03:23:24.431851Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T03:23:24.431898Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T03:23:24.433963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-07-02T03:23:24.434544Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:23:24.434667Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:23:24.434725Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:23:24.435039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T03:24:00.460019Z","caller":"traceutil/trace.go:171","msg":"trace[962476541] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"104.496901ms","start":"2026-07-02T03:24:00.355492Z","end":"2026-07-02T03:24:00.459989Z","steps":["trace[962476541] 'process raft request' (duration: 30.079826ms)","trace[962476541] 'compare' (duration: 74.288692ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:24:01.386656Z","caller":"traceutil/trace.go:171","msg":"trace[2084230326] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"159.869265ms","start":"2026-07-02T03:24:01.226749Z","end":"2026-07-02T03:24:01.386619Z","steps":["trace[2084230326] 'process raft request' (duration: 159.787914ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.386797Z","caller":"traceutil/trace.go:171","msg":"trace[2070874615] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"163.903583ms","start":"2026-07-02T03:24:01.222846Z","end":"2026-07-02T03:24:01.38675Z","steps":["trace[2070874615] 'process raft request' (duration: 124.088782ms)","trace[2070874615] 'compare' (duration: 39.383753ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:24:01.468169Z","caller":"traceutil/trace.go:171","msg":"trace[892742947] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"234.35292ms","start":"2026-07-02T03:24:01.233764Z","end":"2026-07-02T03:24:01.468117Z","steps":["trace[892742947] 'process raft request' (duration: 234.123266ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.468308Z","caller":"traceutil/trace.go:171","msg":"trace[1361655013] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"234.107316ms","start":"2026-07-02T03:24:01.234155Z","end":"2026-07-02T03:24:01.468262Z","steps":["trace[1361655013] 'process raft request' (duration: 233.869792ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.468428Z","caller":"traceutil/trace.go:171","msg":"trace[1381261205] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"234.028235ms","start":"2026-07-02T03:24:01.234365Z","end":"2026-07-02T03:24:01.468394Z","steps":["trace[1381261205] 'process raft request' (duration: 233.880542ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.468557Z","caller":"traceutil/trace.go:171","msg":"trace[1675902257] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"231.377585ms","start":"2026-07-02T03:24:01.237148Z","end":"2026-07-02T03:24:01.468525Z","steps":["trace[1675902257] 'process raft request' (duration: 231.278703ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.468574Z","caller":"traceutil/trace.go:171","msg":"trace[1634986133] transaction","detail":"{read_only:false; number_of_response:1; response_revision:493; }","duration":"232.896383ms","start":"2026-07-02T03:24:01.235612Z","end":"2026-07-02T03:24:01.468508Z","steps":["trace[1634986133] 'process raft request' (duration: 232.75925ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.468888Z","caller":"traceutil/trace.go:171","msg":"trace[1532197151] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"231.624529ms","start":"2026-07-02T03:24:01.237244Z","end":"2026-07-02T03:24:01.468868Z","steps":["trace[1532197151] 'process raft request' (duration: 231.247042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.468912Z","caller":"traceutil/trace.go:171","msg":"trace[1144322852] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"233.501445ms","start":"2026-07-02T03:24:01.234947Z","end":"2026-07-02T03:24:01.468449Z","steps":["trace[1144322852] 'process raft request' (duration: 233.377852ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.469048Z","caller":"traceutil/trace.go:171","msg":"trace[2061587012] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"234.793379ms","start":"2026-07-02T03:24:01.23419Z","end":"2026-07-02T03:24:01.468984Z","steps":["trace[2061587012] 'process raft request' (duration: 233.996695ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.469098Z","caller":"traceutil/trace.go:171","msg":"trace[226202649] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"222.442565ms","start":"2026-07-02T03:24:01.246642Z","end":"2026-07-02T03:24:01.469085Z","steps":["trace[226202649] 'process raft request' (duration: 222.198001ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.469154Z","caller":"traceutil/trace.go:171","msg":"trace[1531560175] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"187.380504ms","start":"2026-07-02T03:24:01.28176Z","end":"2026-07-02T03:24:01.469141Z","steps":["trace[1531560175] 'process raft request' (duration: 187.319233ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:01.469181Z","caller":"traceutil/trace.go:171","msg":"trace[537205627] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"207.896982ms","start":"2026-07-02T03:24:01.261272Z","end":"2026-07-02T03:24:01.469169Z","steps":["trace[537205627] 'process raft request' (duration: 207.716129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:24:07.576672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.523239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T03:24:07.576811Z","caller":"traceutil/trace.go:171","msg":"trace[111199781] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:581; }","duration":"167.746813ms","start":"2026-07-02T03:24:07.409034Z","end":"2026-07-02T03:24:07.576781Z","steps":["trace[111199781] 'range keys from in-memory index tree' (duration: 167.396587ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:24:07.577085Z","caller":"traceutil/trace.go:171","msg":"trace[300568916] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"137.361042ms","start":"2026-07-02T03:24:07.439683Z","end":"2026-07-02T03:24:07.577044Z","steps":["trace[300568916] 'process raft request' (duration: 101.881382ms)","trace[300568916] 'compare' (duration: 35.043431ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:24:08.1067Z","caller":"traceutil/trace.go:171","msg":"trace[1515974839] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"121.135945ms","start":"2026-07-02T03:24:07.985525Z","end":"2026-07-02T03:24:08.106661Z","steps":["trace[1515974839] 'process raft request' (duration: 119.169998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:24:23.450751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.610916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T03:24:23.45088Z","caller":"traceutil/trace.go:171","msg":"trace[1948390750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"119.906602ms","start":"2026-07-02T03:24:23.330946Z","end":"2026-07-02T03:24:23.450852Z","steps":["trace[1948390750] 'range keys from in-memory index tree' (duration: 119.344701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:39.134839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.796381ms","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-07-02T03:25:39.134972Z","caller":"traceutil/trace.go:171","msg":"trace[449595106] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"284.953545ms","start":"2026-07-02T03:25:38.849986Z","end":"2026-07-02T03:25:39.13494Z","steps":["trace[449595106] 'range keys from in-memory index tree' (duration: 284.611118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:39.134985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.743094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T03:25:39.135101Z","caller":"traceutil/trace.go:171","msg":"trace[314925567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1833; }","duration":"271.908467ms","start":"2026-07-02T03:25:38.863167Z","end":"2026-07-02T03:25:39.135075Z","steps":["trace[314925567] 'range keys from in-memory index tree' (duration: 271.53585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:39.517719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.158311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T03:25:39.517856Z","caller":"traceutil/trace.go:171","msg":"trace[593776051] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"108.539755ms","start":"2026-07-02T03:25:39.409296Z","end":"2026-07-02T03:25:39.517835Z","steps":["trace[593776051] 'read index received' (duration: 63.501µs)","trace[593776051] 'applied index is now lower than readState.Index' (duration: 108.474844ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:25:39.517953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.660797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:25:39.518003Z","caller":"traceutil/trace.go:171","msg":"trace[347589399] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1836; }","duration":"108.769139ms","start":"2026-07-02T03:25:39.409219Z","end":"2026-07-02T03:25:39.517988Z","steps":["trace[347589399] 'agreement among raft nodes before linearized reading' (duration: 108.682317ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:25:39.518522Z","caller":"traceutil/trace.go:171","msg":"trace[1339643039] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"291.05928ms","start":"2026-07-02T03:25:39.227049Z","end":"2026-07-02T03:25:39.518108Z","steps":["trace[1339643039] 'process raft request' (duration: 35.563623ms)","trace[1339643039] 'compare' (duration: 253.968198ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:25:52.092497Z","caller":"traceutil/trace.go:171","msg":"trace[1992577993] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"142.522519ms","start":"2026-07-02T03:25:51.949948Z","end":"2026-07-02T03:25:52.092471Z","steps":["trace[1992577993] 'process raft request' (duration: 109.066015ms)","trace[1992577993] 'compare' (duration: 33.028356ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:25:52.092901Z","caller":"traceutil/trace.go:171","msg":"trace[145363256] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2022; }","duration":"126.23062ms","start":"2026-07-02T03:25:51.96664Z","end":"2026-07-02T03:25:52.092871Z","steps":["trace[145363256] 'read index received' (duration: 92.240966ms)","trace[145363256] 'applied index is now lower than readState.Index' (duration: 33.986344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:25:52.09315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.481826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T03:25:52.093264Z","caller":"traceutil/trace.go:171","msg":"trace[186339738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1949; }","duration":"126.654999ms","start":"2026-07-02T03:25:51.966583Z","end":"2026-07-02T03:25:52.093238Z","steps":["trace[186339738] 'agreement among raft nodes before linearized reading' (duration: 126.432345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:52.346417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.765734ms","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-07-02T03:25:52.346531Z","caller":"traceutil/trace.go:171","msg":"trace[599969763] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1950; }","duration":"112.925617ms","start":"2026-07-02T03:25:52.233579Z","end":"2026-07-02T03:25:52.346505Z","steps":["trace[599969763] 'range keys from in-memory index tree' (duration: 112.514579ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:26:35.225531Z","caller":"traceutil/trace.go:171","msg":"trace[1581408204] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"218.38539ms","start":"2026-07-02T03:26:35.007117Z","end":"2026-07-02T03:26:35.225502Z","steps":["trace[1581408204] 'process raft request' (duration: 218.231117ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:26:35.226671Z","caller":"traceutil/trace.go:171","msg":"trace[417440426] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"215.294714ms","start":"2026-07-02T03:26:35.011309Z","end":"2026-07-02T03:26:35.226603Z","steps":["trace[417440426] 'process raft request' (duration: 215.238983ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:26:35.226722Z","caller":"traceutil/trace.go:171","msg":"trace[259347871] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"219.222357ms","start":"2026-07-02T03:26:35.007453Z","end":"2026-07-02T03:26:35.226675Z","steps":["trace[259347871] 'process raft request' (duration: 218.907981ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:27:43.770568Z","caller":"traceutil/trace.go:171","msg":"trace[1554279345] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"119.362181ms","start":"2026-07-02T03:27:43.651174Z","end":"2026-07-02T03:27:43.770536Z","steps":["trace[1554279345] 'read index received' (duration: 119.214978ms)","trace[1554279345] 'applied index is now lower than readState.Index' (duration: 145.522µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:27:43.770672Z","caller":"traceutil/trace.go:171","msg":"trace[313886920] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"121.48326ms","start":"2026-07-02T03:27:43.649138Z","end":"2026-07-02T03:27:43.770622Z","steps":["trace[313886920] 'process raft request' (duration: 121.156744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:27:43.770843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.650806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T03:27:43.770892Z","caller":"traceutil/trace.go:171","msg":"trace[578872222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2752; }","duration":"119.761898ms","start":"2026-07-02T03:27:43.651117Z","end":"2026-07-02T03:27:43.770879Z","steps":["trace[578872222] 'agreement among raft nodes before linearized reading' (duration: 119.548844ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:27:43.783092Z","caller":"traceutil/trace.go:171","msg":"trace[1071849158] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"129.640444ms","start":"2026-07-02T03:27:43.653433Z","end":"2026-07-02T03:27:43.783073Z","steps":["trace[1071849158] 'process raft request' (duration: 129.513182ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:30:25.27685Z","caller":"traceutil/trace.go:171","msg":"trace[369521202] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"123.616038ms","start":"2026-07-02T03:30:25.153206Z","end":"2026-07-02T03:30:25.276822Z","steps":["trace[369521202] 'process raft request' (duration: 123.340533ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.435567Z","caller":"traceutil/trace.go:171","msg":"trace[1921260015] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5035; }","duration":"125.025751ms","start":"2026-07-02T03:31:47.310514Z","end":"2026-07-02T03:31:47.435539Z","steps":["trace[1921260015] 'read index received' (duration: 11.921346ms)","trace[1921260015] 'applied index is now lower than readState.Index' (duration: 113.103395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:31:47.435827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.296736ms","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-07-02T03:31:47.435871Z","caller":"traceutil/trace.go:171","msg":"trace[838095737] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4857; }","duration":"125.409848ms","start":"2026-07-02T03:31:47.310448Z","end":"2026-07-02T03:31:47.435858Z","steps":["trace[838095737] 'agreement among raft nodes before linearized reading' (duration: 125.210274ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.436175Z","caller":"traceutil/trace.go:171","msg":"trace[856973563] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"130.368161ms","start":"2026-07-02T03:31:47.305765Z","end":"2026-07-02T03:31:47.436133Z","steps":["trace[856973563] 'process raft request' (duration: 129.651388ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.436389Z","caller":"traceutil/trace.go:171","msg":"trace[1781581895] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"129.348232ms","start":"2026-07-02T03:31:47.307014Z","end":"2026-07-02T03:31:47.436362Z","steps":["trace[1781581895] 'process raft request' (duration: 128.429625ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.43646Z","caller":"traceutil/trace.go:171","msg":"trace[1501322693] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"130.876642ms","start":"2026-07-02T03:31:47.305572Z","end":"2026-07-02T03:31:47.436448Z","steps":["trace[1501322693] 'process raft request' (duration: 129.68912ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.436252Z","caller":"traceutil/trace.go:171","msg":"trace[1484621790] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"120.370833ms","start":"2026-07-02T03:31:47.315863Z","end":"2026-07-02T03:31:47.436234Z","steps":["trace[1484621790] 'process raft request' (duration: 119.607629ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.436612Z","caller":"traceutil/trace.go:171","msg":"trace[165475728] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"130.930222ms","start":"2026-07-02T03:31:47.305661Z","end":"2026-07-02T03:31:47.436592Z","steps":["trace[165475728] 'process raft request' (duration: 129.693079ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.436835Z","caller":"traceutil/trace.go:171","msg":"trace[1948821834] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"131.878351ms","start":"2026-07-02T03:31:47.304789Z","end":"2026-07-02T03:31:47.436668Z","steps":["trace[1948821834] 'process raft request' (duration: 48.025621ms)","trace[1948821834] 'compare' (duration: 82.197979ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:31:56.998114Z","caller":"traceutil/trace.go:171","msg":"trace[676316606] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"119.337343ms","start":"2026-07-02T03:31:56.878723Z","end":"2026-07-02T03:31:56.99806Z","steps":["trace[676316606] 'process raft request' (duration: 54.32219ms)","trace[676316606] 'compare' (duration: 64.611875ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:32:05.875786Z","caller":"traceutil/trace.go:171","msg":"trace[1706058573] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"108.207431ms","start":"2026-07-02T03:32:05.767558Z","end":"2026-07-02T03:32:05.875766Z","steps":["trace[1706058573] 'process raft request' (duration: 108.088909ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.511996Z","caller":"traceutil/trace.go:171","msg":"trace[672742696] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5641; }","duration":"194.081289ms","start":"2026-07-02T03:32:13.317885Z","end":"2026-07-02T03:32:13.511966Z","steps":["trace[672742696] 'read index received' (duration: 193.813414ms)","trace[672742696] 'applied index is now lower than readState.Index' (duration: 266.665µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:32:13.512306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.410176ms","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-07-02T03:32:13.512324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.992408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-02T03:32:13.512364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.052069ms","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-07-02T03:32:13.512381Z","caller":"traceutil/trace.go:171","msg":"trace[1931030634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5438; }","duration":"194.09399ms","start":"2026-07-02T03:32:13.318271Z","end":"2026-07-02T03:32:13.512365Z","steps":["trace[1931030634] 'agreement among raft nodes before linearized reading' (duration: 193.955287ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.512389Z","caller":"traceutil/trace.go:171","msg":"trace[570979883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5438; }","duration":"194.545298ms","start":"2026-07-02T03:32:13.317806Z","end":"2026-07-02T03:32:13.512351Z","steps":["trace[570979883] 'agreement among raft nodes before linearized reading' (duration: 194.329304ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.512403Z","caller":"traceutil/trace.go:171","msg":"trace[956055787] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5438; }","duration":"136.146371ms","start":"2026-07-02T03:32:13.376244Z","end":"2026-07-02T03:32:13.51239Z","steps":["trace[956055787] 'agreement among raft nodes before linearized reading' (duration: 136.07076ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.512373Z","caller":"traceutil/trace.go:171","msg":"trace[675052759] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"220.023241ms","start":"2026-07-02T03:32:13.292311Z","end":"2026-07-02T03:32:13.512334Z","steps":["trace[675052759] 'process raft request' (duration: 219.492161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:32:13.512611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.252046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T03:32:13.512699Z","caller":"traceutil/trace.go:171","msg":"trace[775560581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5438; }","duration":"192.365588ms","start":"2026-07-02T03:32:13.32032Z","end":"2026-07-02T03:32:13.512685Z","steps":["trace[775560581] 'agreement among raft nodes before linearized reading' (duration: 192.182074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:32:13.718959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.412137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-033cb350-101e-4b3f-bc71-6289782318e6\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-07-02T03:32:13.719078Z","caller":"traceutil/trace.go:171","msg":"trace[896026967] range","detail":"{range_begin:/registry/persistentvolumes/pvc-033cb350-101e-4b3f-bc71-6289782318e6; range_end:; response_count:1; response_revision:5438; }","duration":"203.537429ms","start":"2026-07-02T03:32:13.515478Z","end":"2026-07-02T03:32:13.719016Z","steps":["trace[896026967] 'range keys from in-memory index tree' (duration: 203.269684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:32:13.719113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.831896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T03:32:13.719614Z","caller":"traceutil/trace.go:171","msg":"trace[316828223] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"200.615604ms","start":"2026-07-02T03:32:13.518987Z","end":"2026-07-02T03:32:13.719602Z","steps":["trace[316828223] 'process raft request' (duration: 200.292037ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.719577Z","caller":"traceutil/trace.go:171","msg":"trace[653936481] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"201.148553ms","start":"2026-07-02T03:32:13.518388Z","end":"2026-07-02T03:32:13.719536Z","steps":["trace[653936481] 'process raft request' (duration: 200.815727ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.719606Z","caller":"traceutil/trace.go:171","msg":"trace[585536346] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"197.993504ms","start":"2026-07-02T03:32:13.521582Z","end":"2026-07-02T03:32:13.719576Z","steps":["trace[585536346] 'process raft request' (duration: 197.740099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.719805Z","caller":"traceutil/trace.go:171","msg":"trace[1910563694] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"201.790027ms","start":"2026-07-02T03:32:13.518003Z","end":"2026-07-02T03:32:13.719794Z","steps":["trace[1910563694] 'compare' (duration: 193.582131ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.896707Z","caller":"traceutil/trace.go:171","msg":"trace[2091750769] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"169.515955ms","start":"2026-07-02T03:32:13.727149Z","end":"2026-07-02T03:32:13.896665Z","steps":["trace[2091750769] 'process raft request' (duration: 149.832821ms)","trace[2091750769] 'compare' (duration: 19.358758ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:32:13.896965Z","caller":"traceutil/trace.go:171","msg":"trace[1116695761] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"165.789143ms","start":"2026-07-02T03:32:13.731132Z","end":"2026-07-02T03:32:13.896921Z","steps":["trace[1116695761] 'process raft request' (duration: 165.696492ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.897211Z","caller":"traceutil/trace.go:171","msg":"trace[1538020918] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"169.28538ms","start":"2026-07-02T03:32:13.727907Z","end":"2026-07-02T03:32:13.897192Z","steps":["trace[1538020918] 'process raft request' (duration: 168.808242ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.897285Z","caller":"traceutil/trace.go:171","msg":"trace[683782865] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"168.724559ms","start":"2026-07-02T03:32:13.728549Z","end":"2026-07-02T03:32:13.897274Z","steps":["trace[683782865] 'process raft request' (duration: 168.199579ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.897315Z","caller":"traceutil/trace.go:171","msg":"trace[1262355510] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"170.129576ms","start":"2026-07-02T03:32:13.727172Z","end":"2026-07-02T03:32:13.897302Z","steps":["trace[1262355510] 'process raft request' (duration: 169.328892ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:32:13.897434Z","caller":"traceutil/trace.go:171","msg":"trace[1299836545] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"170.234099ms","start":"2026-07-02T03:32:13.727182Z","end":"2026-07-02T03:32:13.897416Z","steps":["trace[1299836545] 'process raft request' (duration: 169.478075ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:33:24.455662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-07-02T03:33:24.528506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"70.914383ms","hash":2167515728,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":34152448,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T03:33:24.528612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2167515728,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-07-02T03:38:24.719454Z","caller":"traceutil/trace.go:171","msg":"trace[635892435] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"305.60164ms","start":"2026-07-02T03:38:24.413825Z","end":"2026-07-02T03:38:24.719427Z","steps":["trace[635892435] 'process raft request' (duration: 305.424676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:38:24.719635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T03:38:24.413788Z","time spent":"305.750753ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T03:38:24.887849Z","caller":"traceutil/trace.go:171","msg":"trace[1241874966] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"430.359947ms","start":"2026-07-02T03:38:24.457452Z","end":"2026-07-02T03:38:24.887812Z","steps":["trace[1241874966] 'process raft request' (duration: 428.691214ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:24.887962Z","caller":"traceutil/trace.go:171","msg":"trace[1236754397] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"281.465151ms","start":"2026-07-02T03:38:24.606448Z","end":"2026-07-02T03:38:24.887914Z","steps":["trace[1236754397] 'process raft request' (duration: 281.272077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:38:24.888018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T03:38:24.457424Z","time spent":"430.509999ms","remote":"127.0.0.1:52496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T03:38:24.888275Z","caller":"traceutil/trace.go:171","msg":"trace[148187505] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"281.239956ms","start":"2026-07-02T03:38:24.60698Z","end":"2026-07-02T03:38:24.88822Z","steps":["trace[148187505] 'process raft request' (duration: 280.822929ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:24.8883Z","caller":"traceutil/trace.go:171","msg":"trace[1001986046] transaction","detail":"{read_only:false; response_revision:8293; number_of_response:1; }","duration":"281.240016ms","start":"2026-07-02T03:38:24.607025Z","end":"2026-07-02T03:38:24.888265Z","steps":["trace[1001986046] 'process raft request' (duration: 280.89323ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:24.888145Z","caller":"traceutil/trace.go:171","msg":"trace[1062254175] linearizableReadLoop","detail":"{readStateIndex:8587; appliedIndex:8582; }","duration":"228.187096ms","start":"2026-07-02T03:38:24.659899Z","end":"2026-07-02T03:38:24.888086Z","steps":["trace[1062254175] 'read index received' (duration: 59.396541ms)","trace[1062254175] 'applied index is now lower than readState.Index' (duration: 168.789285ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:38:24.888813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.885849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kxxwg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-02T03:38:24.888878Z","caller":"traceutil/trace.go:171","msg":"trace[1580406576] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kxxwg; range_end:; response_count:1; response_revision:8293; }","duration":"229.00838ms","start":"2026-07-02T03:38:24.659853Z","end":"2026-07-02T03:38:24.888862Z","steps":["trace[1580406576] 'agreement among raft nodes before linearized reading' (duration: 228.722595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:38:24.889041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.487173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T03:38:24.889083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.272708ms","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:5"} {"level":"info","ts":"2026-07-02T03:38:24.88914Z","caller":"traceutil/trace.go:171","msg":"trace[94197322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8293; }","duration":"216.612397ms","start":"2026-07-02T03:38:24.672482Z","end":"2026-07-02T03:38:24.889095Z","steps":["trace[94197322] 'agreement among raft nodes before linearized reading' (duration: 216.417142ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:24.889165Z","caller":"traceutil/trace.go:171","msg":"trace[590214401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8293; }","duration":"206.414742ms","start":"2026-07-02T03:38:24.682727Z","end":"2026-07-02T03:38:24.889142Z","steps":["trace[590214401] 'agreement among raft nodes before linearized reading' (duration: 206.261038ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:24.89374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6092} {"level":"info","ts":"2026-07-02T03:38:24.992197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6092,"took":"95.03387ms","hash":3484289276,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":23863296,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-02T03:38:24.992274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3484289276,"revision":6092,"compact-revision":2978} {"level":"info","ts":"2026-07-02T03:38:32.481994Z","caller":"traceutil/trace.go:171","msg":"trace[1737076539] linearizableReadLoop","detail":"{readStateIndex:8629; appliedIndex:8628; }","duration":"157.790055ms","start":"2026-07-02T03:38:32.324174Z","end":"2026-07-02T03:38:32.481964Z","steps":["trace[1737076539] 'read index received' (duration: 157.52592ms)","trace[1737076539] 'applied index is now lower than readState.Index' (duration: 262.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:38:32.482144Z","caller":"traceutil/trace.go:171","msg":"trace[1228397803] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"169.202512ms","start":"2026-07-02T03:38:32.312893Z","end":"2026-07-02T03:38:32.482095Z","steps":["trace[1228397803] 'process raft request' (duration: 168.874386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:38:32.482217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.006089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T03:38:32.482264Z","caller":"traceutil/trace.go:171","msg":"trace[1534727947] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8332; }","duration":"158.09449ms","start":"2026-07-02T03:38:32.324155Z","end":"2026-07-02T03:38:32.482249Z","steps":["trace[1534727947] 'agreement among raft nodes before linearized reading' (duration: 157.919197ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:33.478367Z","caller":"traceutil/trace.go:171","msg":"trace[1955826060] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"160.10461ms","start":"2026-07-02T03:38:33.318231Z","end":"2026-07-02T03:38:33.478336Z","steps":["trace[1955826060] 'process raft request' (duration: 159.896476ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:38:49.17467Z","caller":"traceutil/trace.go:171","msg":"trace[811638530] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"181.186791ms","start":"2026-07-02T03:38:48.993442Z","end":"2026-07-02T03:38:49.174629Z","steps":["trace[811638530] 'process raft request' (duration: 118.771812ms)","trace[811638530] 'compare' (duration: 61.521492ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:38:49.174833Z","caller":"traceutil/trace.go:171","msg":"trace[846891791] linearizableReadLoop","detail":"{readStateIndex:8727; appliedIndex:8725; }","duration":"113.748996ms","start":"2026-07-02T03:38:49.06105Z","end":"2026-07-02T03:38:49.174799Z","steps":["trace[846891791] 'read index received' (duration: 51.178474ms)","trace[846891791] 'applied index is now lower than readState.Index' (duration: 62.567612ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:38:49.174848Z","caller":"traceutil/trace.go:171","msg":"trace[1347744190] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"175.439952ms","start":"2026-07-02T03:38:48.999375Z","end":"2026-07-02T03:38:49.174815Z","steps":["trace[1347744190] 'process raft request' (duration: 175.189647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:38:49.175496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.204856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kxxwg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-02T03:38:49.175627Z","caller":"traceutil/trace.go:171","msg":"trace[1272997561] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kxxwg; range_end:; response_count:1; response_revision:8426; }","duration":"114.607392ms","start":"2026-07-02T03:38:49.060989Z","end":"2026-07-02T03:38:49.175596Z","steps":["trace[1272997561] 'agreement among raft nodes before linearized reading' (duration: 113.94446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:02.128797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.786231ms","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-07-02T03:39:02.128995Z","caller":"traceutil/trace.go:171","msg":"trace[491517345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8540; }","duration":"341.049216ms","start":"2026-07-02T03:39:01.787922Z","end":"2026-07-02T03:39:02.128971Z","steps":["trace[491517345] 'range keys from in-memory index tree' (duration: 340.579737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:02.129072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T03:39:01.787894Z","time spent":"341.164428ms","remote":"127.0.0.1:52752","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-07-02T03:39:02.129847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.905071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T03:39:02.129977Z","caller":"traceutil/trace.go:171","msg":"trace[327897780] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8845; }","duration":"242.373335ms","start":"2026-07-02T03:39:01.887584Z","end":"2026-07-02T03:39:02.129958Z","steps":["trace[327897780] 'read index received' (duration: 56.291302ms)","trace[327897780] 'applied index is now lower than readState.Index' (duration: 186.080263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:39:02.13036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.125207ms","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":"warn","ts":"2026-07-02T03:39:02.130439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.862315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T03:39:02.130491Z","caller":"traceutil/trace.go:171","msg":"trace[707900360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8541; }","duration":"242.963317ms","start":"2026-07-02T03:39:01.887515Z","end":"2026-07-02T03:39:02.130478Z","steps":["trace[707900360] 'agreement among raft nodes before linearized reading' (duration: 242.779703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:02.130465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.569595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T03:39:02.13047Z","caller":"traceutil/trace.go:171","msg":"trace[1692899310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8541; }","duration":"100.296701ms","start":"2026-07-02T03:39:02.030158Z","end":"2026-07-02T03:39:02.130455Z","steps":["trace[1692899310] 'agreement among raft nodes before linearized reading' (duration: 100.138047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:02.130558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.331396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T03:39:02.130347Z","caller":"traceutil/trace.go:171","msg":"trace[2120110753] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"245.156609ms","start":"2026-07-02T03:39:01.885146Z","end":"2026-07-02T03:39:02.130302Z","steps":["trace[2120110753] 'process raft request' (duration: 58.672428ms)","trace[2120110753] 'compare' (duration: 185.768618ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:39:02.13068Z","caller":"traceutil/trace.go:171","msg":"trace[486111687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8541; }","duration":"242.499189ms","start":"2026-07-02T03:39:01.888158Z","end":"2026-07-02T03:39:02.130657Z","steps":["trace[486111687] 'agreement among raft nodes before linearized reading' (duration: 242.147441ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:02.130558Z","caller":"traceutil/trace.go:171","msg":"trace[1487913825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8541; }","duration":"237.682897ms","start":"2026-07-02T03:39:01.892857Z","end":"2026-07-02T03:39:02.13054Z","steps":["trace[1487913825] 'agreement among raft nodes before linearized reading' (duration: 237.441862ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:05.437442Z","caller":"traceutil/trace.go:171","msg":"trace[1549211938] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"205.061605ms","start":"2026-07-02T03:39:05.232334Z","end":"2026-07-02T03:39:05.437396Z","steps":["trace[1549211938] 'process raft request' (duration: 204.774619ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:05.523754Z","caller":"traceutil/trace.go:171","msg":"trace[1629773824] linearizableReadLoop","detail":"{readStateIndex:8864; appliedIndex:8862; }","duration":"239.433411ms","start":"2026-07-02T03:39:05.284281Z","end":"2026-07-02T03:39:05.523715Z","steps":["trace[1629773824] 'read index received' (duration: 152.856212ms)","trace[1629773824] 'applied index is now lower than readState.Index' (duration: 86.575849ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:39:05.524104Z","caller":"traceutil/trace.go:171","msg":"trace[991825268] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"290.933151ms","start":"2026-07-02T03:39:05.233109Z","end":"2026-07-02T03:39:05.524042Z","steps":["trace[991825268] 'process raft request' (duration: 249.43747ms)","trace[991825268] 'compare' (duration: 41.037342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:39:05.524225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.90677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kxxwg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-02T03:39:05.524498Z","caller":"traceutil/trace.go:171","msg":"trace[443381494] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kxxwg; range_end:; response_count:1; response_revision:8558; }","duration":"240.245666ms","start":"2026-07-02T03:39:05.284229Z","end":"2026-07-02T03:39:05.524475Z","steps":["trace[443381494] 'agreement among raft nodes before linearized reading' (duration: 239.795268ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:14.311371Z","caller":"traceutil/trace.go:171","msg":"trace[517684200] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8954; }","duration":"129.394964ms","start":"2026-07-02T03:39:14.181953Z","end":"2026-07-02T03:39:14.311348Z","steps":["trace[517684200] 'read index received' (duration: 129.17895ms)","trace[517684200] 'applied index is now lower than readState.Index' (duration: 214.804µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:39:14.311417Z","caller":"traceutil/trace.go:171","msg":"trace[1603929298] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"129.502796ms","start":"2026-07-02T03:39:14.181885Z","end":"2026-07-02T03:39:14.311388Z","steps":["trace[1603929298] 'process raft request' (duration: 129.291162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:14.311688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.558234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T03:39:14.311753Z","caller":"traceutil/trace.go:171","msg":"trace[945611389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8645; }","duration":"112.658436ms","start":"2026-07-02T03:39:14.199081Z","end":"2026-07-02T03:39:14.31174Z","steps":["trace[945611389] 'agreement among raft nodes before linearized reading' (duration: 112.422161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:14.311677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.69948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bx5tv\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-07-02T03:39:14.311885Z","caller":"traceutil/trace.go:171","msg":"trace[582101775] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bx5tv; range_end:; response_count:1; response_revision:8645; }","duration":"129.969115ms","start":"2026-07-02T03:39:14.181899Z","end":"2026-07-02T03:39:14.311868Z","steps":["trace[582101775] 'agreement among raft nodes before linearized reading' (duration: 129.578958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:14.3119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.810341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T03:39:14.311965Z","caller":"traceutil/trace.go:171","msg":"trace[1375251598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8645; }","duration":"109.914304ms","start":"2026-07-02T03:39:14.202038Z","end":"2026-07-02T03:39:14.311952Z","steps":["trace[1375251598] 'agreement among raft nodes before linearized reading' (duration: 109.675029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:14.311822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.37495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T03:39:14.312503Z","caller":"traceutil/trace.go:171","msg":"trace[1966444045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8645; }","duration":"102.081224ms","start":"2026-07-02T03:39:14.210396Z","end":"2026-07-02T03:39:14.312477Z","steps":["trace[1966444045] 'agreement among raft nodes before linearized reading' (duration: 101.202778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:14.312952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.539963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T03:39:14.31385Z","caller":"traceutil/trace.go:171","msg":"trace[2147264142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8645; }","duration":"103.46906ms","start":"2026-07-02T03:39:14.210356Z","end":"2026-07-02T03:39:14.313825Z","steps":["trace[2147264142] 'agreement among raft nodes before linearized reading' (duration: 101.323859ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:14.457503Z","caller":"traceutil/trace.go:171","msg":"trace[1290165646] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"140.275532ms","start":"2026-07-02T03:39:14.317204Z","end":"2026-07-02T03:39:14.45748Z","steps":["trace[1290165646] 'process raft request' (duration: 131.075957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:14.501786Z","caller":"traceutil/trace.go:171","msg":"trace[1874947866] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"184.493834ms","start":"2026-07-02T03:39:14.317271Z","end":"2026-07-02T03:39:14.501765Z","steps":["trace[1874947866] 'process raft request' (duration: 184.357221ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:14.502196Z","caller":"traceutil/trace.go:171","msg":"trace[974847958] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"183.543916ms","start":"2026-07-02T03:39:14.318625Z","end":"2026-07-02T03:39:14.502169Z","steps":["trace[974847958] 'process raft request' (duration: 183.088908ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:14.502409Z","caller":"traceutil/trace.go:171","msg":"trace[618790294] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"183.268132ms","start":"2026-07-02T03:39:14.319127Z","end":"2026-07-02T03:39:14.502395Z","steps":["trace[618790294] 'process raft request' (duration: 182.902885ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:14.502426Z","caller":"traceutil/trace.go:171","msg":"trace[1390480344] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8955; }","duration":"173.987114ms","start":"2026-07-02T03:39:14.328421Z","end":"2026-07-02T03:39:14.502408Z","steps":["trace[1390480344] 'read index received' (duration: 119.935665ms)","trace[1390480344] 'applied index is now lower than readState.Index' (duration: 54.050379ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:39:14.502496Z","caller":"traceutil/trace.go:171","msg":"trace[340367397] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"182.739021ms","start":"2026-07-02T03:39:14.319725Z","end":"2026-07-02T03:39:14.502464Z","steps":["trace[340367397] 'process raft request' (duration: 182.594788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:39:14.502661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.231678ms","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-07-02T03:39:14.502704Z","caller":"traceutil/trace.go:171","msg":"trace[1441785854] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8650; }","duration":"174.811069ms","start":"2026-07-02T03:39:14.327882Z","end":"2026-07-02T03:39:14.502693Z","steps":["trace[1441785854] 'agreement among raft nodes before linearized reading' (duration: 174.619876ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:39:15.333719Z","caller":"traceutil/trace.go:171","msg":"trace[1673001701] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"224.050488ms","start":"2026-07-02T03:39:15.109632Z","end":"2026-07-02T03:39:15.333683Z","steps":["trace[1673001701] 'process raft request' (duration: 197.926709ms)","trace[1673001701] 'compare' (duration: 25.442785ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:39:31.071925Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000239a.wal"} {"level":"info","ts":"2026-07-02T03:41:09.138073Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T03:41:09.144073Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T03:41:09.144218Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T03:43:24.902202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8290} {"level":"info","ts":"2026-07-02T03:43:24.989966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8290,"took":"84.146199ms","hash":1111459204,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":24301568,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-02T03:43:24.990051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1111459204,"revision":8290,"compact-revision":6092} {"level":"info","ts":"2026-07-02T03:46:10.210407Z","caller":"traceutil/trace.go:171","msg":"trace[118976186] linearizableReadLoop","detail":"{readStateIndex:12241; appliedIndex:12240; }","duration":"106.559572ms","start":"2026-07-02T03:46:10.103816Z","end":"2026-07-02T03:46:10.210376Z","steps":["trace[118976186] 'read index received' (duration: 106.201265ms)","trace[118976186] 'applied index is now lower than readState.Index' (duration: 356.967µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:46:10.210515Z","caller":"traceutil/trace.go:171","msg":"trace[1662017549] transaction","detail":"{read_only:false; response_revision:11831; number_of_response:1; }","duration":"117.611773ms","start":"2026-07-02T03:46:10.092867Z","end":"2026-07-02T03:46:10.210478Z","steps":["trace[1662017549] 'process raft request' (duration: 117.287647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:46:10.210581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.720564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:46:10.210633Z","caller":"traceutil/trace.go:171","msg":"trace[1293812973] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:11831; }","duration":"106.808717ms","start":"2026-07-02T03:46:10.103811Z","end":"2026-07-02T03:46:10.21062Z","steps":["trace[1293812973] 'agreement among raft nodes before linearized reading' (duration: 106.686455ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:46:10.845692Z","caller":"traceutil/trace.go:171","msg":"trace[174944905] transaction","detail":"{read_only:false; response_revision:11833; number_of_response:1; }","duration":"103.351141ms","start":"2026-07-02T03:46:10.742317Z","end":"2026-07-02T03:46:10.845668Z","steps":["trace[174944905] 'process raft request' (duration: 103.003634ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:48:24.910637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10513} {"level":"info","ts":"2026-07-02T03:48:24.988285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10513,"took":"73.993887ms","hash":3403304085,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":25792512,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-02T03:48:24.988381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3403304085,"revision":10513,"compact-revision":8290} {"level":"info","ts":"2026-07-02T03:52:42.797391Z","caller":"traceutil/trace.go:171","msg":"trace[492856012] linearizableReadLoop","detail":"{readStateIndex:14965; appliedIndex:14964; }","duration":"121.40936ms","start":"2026-07-02T03:52:42.675939Z","end":"2026-07-02T03:52:42.797349Z","steps":["trace[492856012] 'read index received' (duration: 101.45952ms)","trace[492856012] 'applied index is now lower than readState.Index' (duration: 19.947549ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:52:42.797576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.611063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:52:42.79768Z","caller":"traceutil/trace.go:171","msg":"trace[951902756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14461; }","duration":"121.752076ms","start":"2026-07-02T03:52:42.675903Z","end":"2026-07-02T03:52:42.797655Z","steps":["trace[951902756] 'agreement among raft nodes before linearized reading' (duration: 121.594133ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:53:24.917417Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12753} {"level":"info","ts":"2026-07-02T03:53:24.998476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12753,"took":"78.138408ms","hash":1576965876,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":28442624,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-02T03:53:24.998537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1576965876,"revision":12753,"compact-revision":10513} {"level":"info","ts":"2026-07-02T03:53:58.967858Z","caller":"traceutil/trace.go:171","msg":"trace[1429122058] transaction","detail":"{read_only:false; response_revision:15413; number_of_response:1; }","duration":"112.494081ms","start":"2026-07-02T03:53:58.855335Z","end":"2026-07-02T03:53:58.967829Z","steps":["trace[1429122058] 'process raft request' (duration: 112.232916ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:33.090318Z","caller":"traceutil/trace.go:171","msg":"trace[1094578651] linearizableReadLoop","detail":"{readStateIndex:16128; appliedIndex:16127; }","duration":"128.365213ms","start":"2026-07-02T03:54:32.961925Z","end":"2026-07-02T03:54:33.09029Z","steps":["trace[1094578651] 'read index received' (duration: 86.271792ms)","trace[1094578651] 'applied index is now lower than readState.Index' (duration: 42.092151ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:54:33.090371Z","caller":"traceutil/trace.go:171","msg":"trace[112535809] transaction","detail":"{read_only:false; response_revision:15588; number_of_response:1; }","duration":"170.717619ms","start":"2026-07-02T03:54:32.919627Z","end":"2026-07-02T03:54:33.090345Z","steps":["trace[112535809] 'process raft request' (duration: 128.621238ms)","trace[112535809] 'compare' (duration: 41.908527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:54:33.090611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.669079ms","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-07-02T03:54:33.090659Z","caller":"traceutil/trace.go:171","msg":"trace[320852696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15588; }","duration":"128.779212ms","start":"2026-07-02T03:54:32.961865Z","end":"2026-07-02T03:54:33.090644Z","steps":["trace[320852696] 'agreement among raft nodes before linearized reading' (duration: 128.563937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:33.176893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.99487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T03:54:33.177063Z","caller":"traceutil/trace.go:171","msg":"trace[1593851676] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15589; }","duration":"124.119992ms","start":"2026-07-02T03:54:33.052842Z","end":"2026-07-02T03:54:33.176962Z","steps":["trace[1593851676] 'agreement among raft nodes before linearized reading' (duration: 123.905718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:33.177302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.714092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:54:33.177349Z","caller":"traceutil/trace.go:171","msg":"trace[1696755542] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15589; }","duration":"125.778684ms","start":"2026-07-02T03:54:33.051558Z","end":"2026-07-02T03:54:33.177337Z","steps":["trace[1696755542] 'agreement among raft nodes before linearized reading' (duration: 125.711712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:33.17774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.443337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:54:33.177819Z","caller":"traceutil/trace.go:171","msg":"trace[1579933239] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15589; }","duration":"126.552169ms","start":"2026-07-02T03:54:33.051248Z","end":"2026-07-02T03:54:33.177801Z","steps":["trace[1579933239] 'agreement among raft nodes before linearized reading' (duration: 126.423066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:33.177947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.901996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:54:33.177977Z","caller":"traceutil/trace.go:171","msg":"trace[601916294] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15589; }","duration":"126.977848ms","start":"2026-07-02T03:54:33.05099Z","end":"2026-07-02T03:54:33.177968Z","steps":["trace[601916294] 'agreement among raft nodes before linearized reading' (duration: 126.932347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:33.178352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.286213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-02T03:54:33.17848Z","caller":"traceutil/trace.go:171","msg":"trace[878043373] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15589; }","duration":"127.420946ms","start":"2026-07-02T03:54:33.051005Z","end":"2026-07-02T03:54:33.178426Z","steps":["trace[878043373] 'agreement among raft nodes before linearized reading' (duration: 127.129541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:33.179634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.616794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kxxwg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-02T03:54:33.179943Z","caller":"traceutil/trace.go:171","msg":"trace[149860531] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kxxwg; range_end:; response_count:1; response_revision:15589; }","duration":"163.981311ms","start":"2026-07-02T03:54:33.015942Z","end":"2026-07-02T03:54:33.179924Z","steps":["trace[149860531] 'agreement among raft nodes before linearized reading' (duration: 162.137017ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:33.597227Z","caller":"traceutil/trace.go:171","msg":"trace[1328219059] linearizableReadLoop","detail":"{readStateIndex:16135; appliedIndex:16133; }","duration":"154.598203ms","start":"2026-07-02T03:54:33.442604Z","end":"2026-07-02T03:54:33.597202Z","steps":["trace[1328219059] 'read index received' (duration: 108.883273ms)","trace[1328219059] 'applied index is now lower than readState.Index' (duration: 45.71336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:54:33.597359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.739395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T03:54:33.597389Z","caller":"traceutil/trace.go:171","msg":"trace[601282987] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:15595; }","duration":"154.812877ms","start":"2026-07-02T03:54:33.442565Z","end":"2026-07-02T03:54:33.597378Z","steps":["trace[601282987] 'agreement among raft nodes before linearized reading' (duration: 154.733246ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:33.597527Z","caller":"traceutil/trace.go:171","msg":"trace[313235570] transaction","detail":"{read_only:false; response_revision:15594; number_of_response:1; }","duration":"169.524167ms","start":"2026-07-02T03:54:33.427959Z","end":"2026-07-02T03:54:33.597484Z","steps":["trace[313235570] 'process raft request' (duration: 123.43622ms)","trace[313235570] 'compare' (duration: 45.621418ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:54:33.597571Z","caller":"traceutil/trace.go:171","msg":"trace[902468873] transaction","detail":"{read_only:false; response_revision:15595; number_of_response:1; }","duration":"168.55991ms","start":"2026-07-02T03:54:33.428967Z","end":"2026-07-02T03:54:33.597526Z","steps":["trace[902468873] 'process raft request' (duration: 168.190822ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:39.279094Z","caller":"traceutil/trace.go:171","msg":"trace[672859306] transaction","detail":"{read_only:false; response_revision:15620; number_of_response:1; }","duration":"105.00933ms","start":"2026-07-02T03:54:39.174052Z","end":"2026-07-02T03:54:39.279061Z","steps":["trace[672859306] 'process raft request' (duration: 86.469586ms)","trace[672859306] 'compare' (duration: 18.33858ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:58:24.926575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14906} {"level":"info","ts":"2026-07-02T03:58:25.021658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14906,"took":"91.836767ms","hash":2724249214,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T03:58:25.02174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2724249214,"revision":14906,"compact-revision":12753} {"level":"info","ts":"2026-07-02T04:00:28.993405Z","caller":"traceutil/trace.go:171","msg":"trace[137049525] transaction","detail":"{read_only:false; response_revision:18264; number_of_response:1; }","duration":"217.037649ms","start":"2026-07-02T04:00:28.776337Z","end":"2026-07-02T04:00:28.993375Z","steps":["trace[137049525] 'process raft request' (duration: 215.368707ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:00:28.995821Z","caller":"traceutil/trace.go:171","msg":"trace[1098412121] transaction","detail":"{read_only:false; response_revision:18265; number_of_response:1; }","duration":"209.473905ms","start":"2026-07-02T04:00:28.786313Z","end":"2026-07-02T04:00:28.995786Z","steps":["trace[1098412121] 'process raft request' (duration: 208.885874ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:00:35.135227Z","caller":"traceutil/trace.go:171","msg":"trace[515219580] transaction","detail":"{read_only:false; response_revision:18299; number_of_response:1; }","duration":"118.167057ms","start":"2026-07-02T04:00:35.017026Z","end":"2026-07-02T04:00:35.135194Z","steps":["trace[515219580] 'process raft request' (duration: 117.958953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:35.648501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.655664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29716080-jnxq6\" ","response":"range_response_count:1 size:7039"} {"level":"info","ts":"2026-07-02T04:00:35.648698Z","caller":"traceutil/trace.go:171","msg":"trace[962092204] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29716080-jnxq6; range_end:; response_count:1; response_revision:18301; }","duration":"101.908499ms","start":"2026-07-02T04:00:35.546759Z","end":"2026-07-02T04:00:35.648667Z","steps":["trace[962092204] 'range keys from in-memory index tree' (duration: 101.442279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:35.648695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.961821ms","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-07-02T04:00:35.648821Z","caller":"traceutil/trace.go:171","msg":"trace[2053806815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18301; }","duration":"101.173664ms","start":"2026-07-02T04:00:35.547621Z","end":"2026-07-02T04:00:35.648795Z","steps":["trace[2053806815] 'range keys from in-memory index tree' (duration: 100.809187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:36.006034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.668137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:00:36.006154Z","caller":"traceutil/trace.go:171","msg":"trace[1495345331] transaction","detail":"{read_only:false; response_revision:18304; number_of_response:1; }","duration":"297.674552ms","start":"2026-07-02T04:00:35.708459Z","end":"2026-07-02T04:00:36.006133Z","steps":["trace[1495345331] 'process raft request' (duration: 46.816881ms)","trace[1495345331] 'compare' (duration: 250.531565ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:00:36.202159Z","caller":"traceutil/trace.go:171","msg":"trace[1177694780] linearizableReadLoop","detail":"{readStateIndex:18933; appliedIndex:18931; }","duration":"461.134501ms","start":"2026-07-02T04:00:35.740985Z","end":"2026-07-02T04:00:36.202119Z","steps":["trace[1177694780] 'read index received' (duration: 14.311712ms)","trace[1177694780] 'applied index is now lower than readState.Index' (duration: 446.821399ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:00:36.202368Z","caller":"traceutil/trace.go:171","msg":"trace[1282353860] transaction","detail":"{read_only:false; response_revision:18305; number_of_response:1; }","duration":"472.838044ms","start":"2026-07-02T04:00:35.729511Z","end":"2026-07-02T04:00:36.202349Z","steps":["trace[1282353860] 'process raft request' (duration: 428.95447ms)","trace[1282353860] 'compare' (duration: 43.463376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:00:36.202531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:35.72947Z","time spent":"472.934896ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:00:36.202664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.519598ms","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-07-02T04:00:36.202793Z","caller":"traceutil/trace.go:171","msg":"trace[921268780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18305; }","duration":"390.736342ms","start":"2026-07-02T04:00:35.812024Z","end":"2026-07-02T04:00:36.20276Z","steps":["trace[921268780] 'agreement among raft nodes before linearized reading' (duration: 390.448896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:36.202852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:35.811995Z","time spent":"390.846624ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T04:00:36.203073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.629954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-02T04:00:36.203112Z","caller":"traceutil/trace.go:171","msg":"trace[213139757] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18305; }","duration":"355.688306ms","start":"2026-07-02T04:00:35.847413Z","end":"2026-07-02T04:00:36.203101Z","steps":["trace[213139757] 'agreement among raft nodes before linearized reading' (duration: 355.560313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:36.203148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:35.847401Z","time spent":"355.739847ms","remote":"127.0.0.1:52660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-02T04:00:36.203212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.222223ms","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-07-02T04:00:36.203277Z","caller":"traceutil/trace.go:171","msg":"trace[2116900581] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18305; }","duration":"462.351505ms","start":"2026-07-02T04:00:35.740908Z","end":"2026-07-02T04:00:36.203259Z","steps":["trace[2116900581] 'agreement among raft nodes before linearized reading' (duration: 462.256683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:36.203321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:35.74089Z","time spent":"462.421246ms","remote":"127.0.0.1:40020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-02T04:02:20.900892Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T04:02:20.910031Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T04:02:20.910231Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-02T04:03:24.970187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17359} {"level":"info","ts":"2026-07-02T04:03:25.07295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17359,"took":"98.798039ms","hash":1953885008,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":34906112,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-02T04:03:25.073018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1953885008,"revision":17359,"compact-revision":14906} {"level":"warn","ts":"2026-07-02T04:06:08.767422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.0708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:06:08.767564Z","caller":"traceutil/trace.go:171","msg":"trace[1875728571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:20973; }","duration":"165.263244ms","start":"2026-07-02T04:06:08.602272Z","end":"2026-07-02T04:06:08.767536Z","steps":["trace[1875728571] 'count revisions from in-memory index tree' (duration: 164.962428ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:06:29.750698Z","caller":"traceutil/trace.go:171","msg":"trace[339025980] transaction","detail":"{read_only:false; response_revision:21081; number_of_response:1; }","duration":"144.56576ms","start":"2026-07-02T04:06:29.606096Z","end":"2026-07-02T04:06:29.750662Z","steps":["trace[339025980] 'process raft request' (duration: 144.343706ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:06:29.764276Z","caller":"traceutil/trace.go:171","msg":"trace[140251594] linearizableReadLoop","detail":"{readStateIndex:21801; appliedIndex:21799; }","duration":"113.954338ms","start":"2026-07-02T04:06:29.650293Z","end":"2026-07-02T04:06:29.764247Z","steps":["trace[140251594] 'read index received' (duration: 100.172226ms)","trace[140251594] 'applied index is now lower than readState.Index' (duration: 13.781012ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:06:29.764465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.154532ms","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-07-02T04:06:29.764516Z","caller":"traceutil/trace.go:171","msg":"trace[1967871398] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:21083; }","duration":"114.298854ms","start":"2026-07-02T04:06:29.650199Z","end":"2026-07-02T04:06:29.764498Z","steps":["trace[1967871398] 'agreement among raft nodes before linearized reading' (duration: 114.170982ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:06:29.764899Z","caller":"traceutil/trace.go:171","msg":"trace[179890948] transaction","detail":"{read_only:false; response_revision:21082; number_of_response:1; }","duration":"156.451526ms","start":"2026-07-02T04:06:29.608435Z","end":"2026-07-02T04:06:29.764887Z","steps":["trace[179890948] 'process raft request' (duration: 155.63358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:06:30.199265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.823843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:06:30.199421Z","caller":"traceutil/trace.go:171","msg":"trace[557386255] linearizableReadLoop","detail":"{readStateIndex:21804; appliedIndex:21802; }","duration":"301.26209ms","start":"2026-07-02T04:06:29.898115Z","end":"2026-07-02T04:06:30.199377Z","steps":["trace[557386255] 'read index received' (duration: 13.333313ms)","trace[557386255] 'applied index is now lower than readState.Index' (duration: 287.927137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:06:30.199522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.484834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:06:30.199504Z","caller":"traceutil/trace.go:171","msg":"trace[242263889] transaction","detail":"{read_only:false; response_revision:21085; number_of_response:1; }","duration":"304.090054ms","start":"2026-07-02T04:06:29.895355Z","end":"2026-07-02T04:06:30.199445Z","steps":["trace[242263889] 'process raft request' (duration: 186.971466ms)","trace[242263889] 'compare' (duration: 116.65278ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:06:30.199566Z","caller":"traceutil/trace.go:171","msg":"trace[2052090033] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:21085; }","duration":"301.581236ms","start":"2026-07-02T04:06:29.89797Z","end":"2026-07-02T04:06:30.199551Z","steps":["trace[2052090033] 'agreement among raft nodes before linearized reading' (duration: 301.485425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:06:30.199621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:06:29.897921Z","time spent":"301.688958ms","remote":"127.0.0.1:34604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:06:30.199751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:06:29.895341Z","time spent":"304.270347ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:06:30.46498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.231226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:06:30.465119Z","caller":"traceutil/trace.go:171","msg":"trace[292285507] linearizableReadLoop","detail":"{readStateIndex:21806; appliedIndex:21805; }","duration":"165.872535ms","start":"2026-07-02T04:06:30.299224Z","end":"2026-07-02T04:06:30.465097Z","steps":["trace[292285507] 'read index received' (duration: 54.442195ms)","trace[292285507] 'applied index is now lower than readState.Index' (duration: 111.42852ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:06:30.465213Z","caller":"traceutil/trace.go:171","msg":"trace[427826973] transaction","detail":"{read_only:false; response_revision:21087; number_of_response:1; }","duration":"194.289716ms","start":"2026-07-02T04:06:30.270911Z","end":"2026-07-02T04:06:30.465201Z","steps":["trace[427826973] 'process raft request' (duration: 82.735674ms)","trace[427826973] 'compare' (duration: 110.785128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:06:30.465543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.315093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:06:30.46558Z","caller":"traceutil/trace.go:171","msg":"trace[1513147057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21087; }","duration":"166.408755ms","start":"2026-07-02T04:06:30.29916Z","end":"2026-07-02T04:06:30.465568Z","steps":["trace[1513147057] 'agreement among raft nodes before linearized reading' (duration: 166.330463ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:06:30.813202Z","caller":"traceutil/trace.go:171","msg":"trace[1624000904] linearizableReadLoop","detail":"{readStateIndex:21807; appliedIndex:21806; }","duration":"140.176146ms","start":"2026-07-02T04:06:30.672981Z","end":"2026-07-02T04:06:30.813157Z","steps":["trace[1624000904] 'read index received' (duration: 140.024963ms)","trace[1624000904] 'applied index is now lower than readState.Index' (duration: 149.333µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:06:30.813285Z","caller":"traceutil/trace.go:171","msg":"trace[863052184] transaction","detail":"{read_only:false; response_revision:21088; number_of_response:1; }","duration":"151.638925ms","start":"2026-07-02T04:06:30.661603Z","end":"2026-07-02T04:06:30.813242Z","steps":["trace[863052184] 'process raft request' (duration: 151.328039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:06:30.813379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.392811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:06:30.813424Z","caller":"traceutil/trace.go:171","msg":"trace[2078053502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21088; }","duration":"140.484452ms","start":"2026-07-02T04:06:30.67293Z","end":"2026-07-02T04:06:30.813414Z","steps":["trace[2078053502] 'agreement among raft nodes before linearized reading' (duration: 140.355669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:06:31.101591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.631538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:06:31.101689Z","caller":"traceutil/trace.go:171","msg":"trace[690177629] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:21088; }","duration":"157.816441ms","start":"2026-07-02T04:06:30.943853Z","end":"2026-07-02T04:06:31.101669Z","steps":["trace[690177629] 'count revisions from in-memory index tree' (duration: 157.528166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:08:24.97628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19766} {"level":"info","ts":"2026-07-02T04:08:25.085822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19766,"took":"105.480745ms","hash":1321821566,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":30613504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T04:08:25.085878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1321821566,"revision":19766,"compact-revision":17359} {"level":"info","ts":"2026-07-02T04:10:27.17413Z","caller":"traceutil/trace.go:171","msg":"trace[63446525] transaction","detail":"{read_only:false; response_revision:23573; number_of_response:1; }","duration":"110.496882ms","start":"2026-07-02T04:10:27.063581Z","end":"2026-07-02T04:10:27.174078Z","steps":["trace[63446525] 'process raft request' (duration: 96.74257ms)","trace[63446525] 'compare' (duration: 13.62465ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:10:27.477442Z","caller":"traceutil/trace.go:171","msg":"trace[1649445549] transaction","detail":"{read_only:false; response_revision:23575; number_of_response:1; }","duration":"372.614937ms","start":"2026-07-02T04:10:27.104797Z","end":"2026-07-02T04:10:27.477412Z","steps":["trace[1649445549] 'process raft request' (duration: 372.516015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:10:27.477643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:10:27.104775Z","time spent":"372.77027ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:10:27.47785Z","caller":"traceutil/trace.go:171","msg":"trace[258016409] transaction","detail":"{read_only:false; response_revision:23574; number_of_response:1; }","duration":"412.803061ms","start":"2026-07-02T04:10:27.064977Z","end":"2026-07-02T04:10:27.47778Z","steps":["trace[258016409] 'process raft request' (duration: 390.064369ms)","trace[258016409] 'compare' (duration: 21.980798ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:10:27.477953Z","caller":"traceutil/trace.go:171","msg":"trace[780777154] transaction","detail":"{read_only:false; response_revision:23577; number_of_response:1; }","duration":"189.513274ms","start":"2026-07-02T04:10:27.288429Z","end":"2026-07-02T04:10:27.477942Z","steps":["trace[780777154] 'process raft request' (duration: 189.246289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:10:27.478046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:10:27.064963Z","time spent":"412.982414ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:10:27.477837Z","caller":"traceutil/trace.go:171","msg":"trace[1750922628] transaction","detail":"{read_only:false; response_revision:23576; number_of_response:1; }","duration":"333.127966ms","start":"2026-07-02T04:10:27.144673Z","end":"2026-07-02T04:10:27.477801Z","steps":["trace[1750922628] 'process raft request' (duration: 332.696078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:10:27.478335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:10:27.144607Z","time spent":"333.589245ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:24.983402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22034} {"level":"info","ts":"2026-07-02T04:13:25.105037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22034,"took":"116.953734ms","hash":3969484020,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":44933120,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-02T04:13:25.106022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3969484020,"revision":22034,"compact-revision":19766} {"level":"info","ts":"2026-07-02T04:13:43.523428Z","caller":"traceutil/trace.go:171","msg":"trace[150986576] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"115.773492ms","start":"2026-07-02T04:13:43.407624Z","end":"2026-07-02T04:13:43.523398Z","steps":["trace[150986576] 'process raft request' (duration: 115.562317ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:43.524202Z","caller":"traceutil/trace.go:171","msg":"trace[371258194] transaction","detail":"{read_only:false; response_revision:25568; number_of_response:1; }","duration":"103.01494ms","start":"2026-07-02T04:13:43.421156Z","end":"2026-07-02T04:13:43.524171Z","steps":["trace[371258194] 'process raft request' (duration: 102.757435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:48.520462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.652739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:48.520671Z","caller":"traceutil/trace.go:171","msg":"trace[718499266] linearizableReadLoop","detail":"{readStateIndex:26450; appliedIndex:26448; }","duration":"239.706249ms","start":"2026-07-02T04:13:48.280946Z","end":"2026-07-02T04:13:48.520652Z","steps":["trace[718499266] 'read index received' (duration: 71.786665ms)","trace[718499266] 'applied index is now lower than readState.Index' (duration: 167.918564ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:48.520931Z","caller":"traceutil/trace.go:171","msg":"trace[409367042] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"259.16961ms","start":"2026-07-02T04:13:48.261748Z","end":"2026-07-02T04:13:48.520917Z","steps":["trace[409367042] 'process raft request' (duration: 90.96746ms)","trace[409367042] 'compare' (duration: 167.21143ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:48.521116Z","caller":"traceutil/trace.go:171","msg":"trace[640469676] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"249.279691ms","start":"2026-07-02T04:13:48.271821Z","end":"2026-07-02T04:13:48.521101Z","steps":["trace[640469676] 'process raft request' (duration: 248.753141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:48.521274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.328701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:48.521312Z","caller":"traceutil/trace.go:171","msg":"trace[1045874810] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25604; }","duration":"240.394363ms","start":"2026-07-02T04:13:48.280908Z","end":"2026-07-02T04:13:48.521303Z","steps":["trace[1045874810] 'agreement among raft nodes before linearized reading' (duration: 240.326932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:48.521551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.111013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-02T04:13:48.521586Z","caller":"traceutil/trace.go:171","msg":"trace[760969392] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25604; }","duration":"194.149953ms","start":"2026-07-02T04:13:48.327428Z","end":"2026-07-02T04:13:48.521578Z","steps":["trace[760969392] 'agreement among raft nodes before linearized reading' (duration: 194.032491ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:48.751141Z","caller":"traceutil/trace.go:171","msg":"trace[464614785] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"137.898154ms","start":"2026-07-02T04:13:48.613197Z","end":"2026-07-02T04:13:48.751096Z","steps":["trace[464614785] 'process raft request' (duration: 110.585464ms)","trace[464614785] 'compare' (duration: 27.065305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:48.75132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.956524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-07-02T04:13:48.751141Z","caller":"traceutil/trace.go:171","msg":"trace[2034799922] linearizableReadLoop","detail":"{readStateIndex:26452; appliedIndex:26451; }","duration":"116.759211ms","start":"2026-07-02T04:13:48.634335Z","end":"2026-07-02T04:13:48.751094Z","steps":["trace[2034799922] 'read index received' (duration: 89.39705ms)","trace[2034799922] 'applied index is now lower than readState.Index' (duration: 27.359841ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:48.75138Z","caller":"traceutil/trace.go:171","msg":"trace[1870741541] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:25606; }","duration":"117.058827ms","start":"2026-07-02T04:13:48.63431Z","end":"2026-07-02T04:13:48.751368Z","steps":["trace[1870741541] 'agreement among raft nodes before linearized reading' (duration: 116.903644ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:49.007762Z","caller":"traceutil/trace.go:171","msg":"trace[2147030844] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"181.361999ms","start":"2026-07-02T04:13:48.826364Z","end":"2026-07-02T04:13:49.007726Z","steps":["trace[2147030844] 'process raft request' (duration: 123.188164ms)","trace[2147030844] 'compare' (duration: 57.859589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:49.256524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.175456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:49.256679Z","caller":"traceutil/trace.go:171","msg":"trace[498948610] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"159.340211ms","start":"2026-07-02T04:13:49.097316Z","end":"2026-07-02T04:13:49.256656Z","steps":["trace[498948610] 'process raft request' (duration: 29.9169ms)","trace[498948610] 'compare' (duration: 129.026384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:49.547412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.416229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:49.547545Z","caller":"traceutil/trace.go:171","msg":"trace[1012259761] linearizableReadLoop","detail":"{readStateIndex:26455; appliedIndex:26454; }","duration":"218.107309ms","start":"2026-07-02T04:13:49.329417Z","end":"2026-07-02T04:13:49.547525Z","steps":["trace[1012259761] 'read index received' (duration: 75.468386ms)","trace[1012259761] 'applied index is now lower than readState.Index' (duration: 142.637833ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:49.547634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.21168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:49.547667Z","caller":"traceutil/trace.go:171","msg":"trace[1901057906] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25609; }","duration":"218.282112ms","start":"2026-07-02T04:13:49.329372Z","end":"2026-07-02T04:13:49.547654Z","steps":["trace[1901057906] 'agreement among raft nodes before linearized reading' (duration: 218.206781ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:49.547654Z","caller":"traceutil/trace.go:171","msg":"trace[1592076834] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"318.692372ms","start":"2026-07-02T04:13:49.228898Z","end":"2026-07-02T04:13:49.54759Z","steps":["trace[1592076834] 'process raft request' (duration: 175.968427ms)","trace[1592076834] 'compare' (duration: 142.253766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:49.547893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.228872Z","time spent":"318.909256ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:49.735605Z","caller":"traceutil/trace.go:171","msg":"trace[1366099489] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"398.613171ms","start":"2026-07-02T04:13:49.336961Z","end":"2026-07-02T04:13:49.735575Z","steps":["trace[1366099489] 'process raft request' (duration: 335.864598ms)","trace[1366099489] 'compare' (duration: 62.546019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:49.735797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.336924Z","time spent":"398.768354ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:49.735973Z","caller":"traceutil/trace.go:171","msg":"trace[323024029] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"334.837868ms","start":"2026-07-02T04:13:49.401099Z","end":"2026-07-02T04:13:49.735937Z","steps":["trace[323024029] 'process raft request' (duration: 334.42679ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:49.736086Z","caller":"traceutil/trace.go:171","msg":"trace[424713790] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"191.642445ms","start":"2026-07-02T04:13:49.544416Z","end":"2026-07-02T04:13:49.736059Z","steps":["trace[424713790] 'process raft request' (duration: 191.405281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:49.736135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.401065Z","time spent":"334.986901ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:49.736159Z","caller":"traceutil/trace.go:171","msg":"trace[1139680519] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"189.153717ms","start":"2026-07-02T04:13:49.546996Z","end":"2026-07-02T04:13:49.73615Z","steps":["trace[1139680519] 'process raft request' (duration: 188.926813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:49.736125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.947788ms","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-07-02T04:13:49.73626Z","caller":"traceutil/trace.go:171","msg":"trace[771553032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25613; }","duration":"185.12445ms","start":"2026-07-02T04:13:49.551115Z","end":"2026-07-02T04:13:49.736239Z","steps":["trace[771553032] 'agreement among raft nodes before linearized reading' (duration: 184.908696ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:49.735986Z","caller":"traceutil/trace.go:171","msg":"trace[1438373218] linearizableReadLoop","detail":"{readStateIndex:26459; appliedIndex:26455; }","duration":"184.803174ms","start":"2026-07-02T04:13:49.551161Z","end":"2026-07-02T04:13:49.735964Z","steps":["trace[1438373218] 'read index received' (duration: 121.686024ms)","trace[1438373218] 'applied index is now lower than readState.Index' (duration: 63.11575ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:49.736462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.526413ms","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-07-02T04:13:49.736486Z","caller":"traceutil/trace.go:171","msg":"trace[2030492704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25613; }","duration":"129.570624ms","start":"2026-07-02T04:13:49.60691Z","end":"2026-07-02T04:13:49.736481Z","steps":["trace[2030492704] 'agreement among raft nodes before linearized reading' (duration: 129.505333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:49.736544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.81184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1042"} {"level":"info","ts":"2026-07-02T04:13:49.73659Z","caller":"traceutil/trace.go:171","msg":"trace[1178466776] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:25613; }","duration":"179.914812ms","start":"2026-07-02T04:13:49.556662Z","end":"2026-07-02T04:13:49.736577Z","steps":["trace[1178466776] 'agreement among raft nodes before linearized reading' (duration: 179.756479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:50.054723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.967501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:50.054844Z","caller":"traceutil/trace.go:171","msg":"trace[1368842654] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"313.867469ms","start":"2026-07-02T04:13:49.740955Z","end":"2026-07-02T04:13:50.054822Z","steps":["trace[1368842654] 'process raft request' (duration: 151.689245ms)","trace[1368842654] 'compare' (duration: 161.837728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:50.054904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.740933Z","time spent":"313.944331ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:50.197024Z","caller":"traceutil/trace.go:171","msg":"trace[1032332076] linearizableReadLoop","detail":"{readStateIndex:26463; appliedIndex:26460; }","duration":"210.230099ms","start":"2026-07-02T04:13:49.986754Z","end":"2026-07-02T04:13:50.196984Z","steps":["trace[1032332076] 'read index received' (duration: 168.054056ms)","trace[1032332076] 'applied index is now lower than readState.Index' (duration: 42.174543ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:50.197219Z","caller":"traceutil/trace.go:171","msg":"trace[1936980279] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"349.443367ms","start":"2026-07-02T04:13:49.847728Z","end":"2026-07-02T04:13:50.197171Z","steps":["trace[1936980279] 'process raft request' (duration: 349.143832ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:50.197246Z","caller":"traceutil/trace.go:171","msg":"trace[1948746601] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"316.48765ms","start":"2026-07-02T04:13:49.880726Z","end":"2026-07-02T04:13:50.197213Z","steps":["trace[1948746601] 'process raft request' (duration: 316.204775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:50.197346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.576655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:50.197417Z","caller":"traceutil/trace.go:171","msg":"trace[1133537647] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25617; }","duration":"210.681407ms","start":"2026-07-02T04:13:49.986714Z","end":"2026-07-02T04:13:50.197396Z","steps":["trace[1133537647] 'agreement among raft nodes before linearized reading' (duration: 210.487104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:50.19741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.847701Z","time spent":"349.61894ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:50.197426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.880716Z","time spent":"316.612792ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:50.197804Z","caller":"traceutil/trace.go:171","msg":"trace[739910635] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"456.577164ms","start":"2026-07-02T04:13:49.741033Z","end":"2026-07-02T04:13:50.197611Z","steps":["trace[739910635] 'process raft request' (duration: 413.77352ms)","trace[739910635] 'compare' (duration: 41.861667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:50.197882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:49.741011Z","time spent":"456.831949ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:50.615527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.255542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:50.615643Z","caller":"traceutil/trace.go:171","msg":"trace[2147117007] linearizableReadLoop","detail":"{readStateIndex:26464; appliedIndex:26463; }","duration":"318.238542ms","start":"2026-07-02T04:13:50.297387Z","end":"2026-07-02T04:13:50.615626Z","steps":["trace[2147117007] 'read index received' (duration: 182.855287ms)","trace[2147117007] 'applied index is now lower than readState.Index' (duration: 135.381835ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:50.615717Z","caller":"traceutil/trace.go:171","msg":"trace[2071160385] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"371.716399ms","start":"2026-07-02T04:13:50.243989Z","end":"2026-07-02T04:13:50.615705Z","steps":["trace[2071160385] 'process raft request' (duration: 236.191712ms)","trace[2071160385] 'compare' (duration: 134.746293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:50.615782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.24395Z","time spent":"371.798301ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:50.616128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.494277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:50.616211Z","caller":"traceutil/trace.go:171","msg":"trace[241866955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25618; }","duration":"318.878884ms","start":"2026-07-02T04:13:50.297315Z","end":"2026-07-02T04:13:50.616194Z","steps":["trace[241866955] 'agreement among raft nodes before linearized reading' (duration: 318.486847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:50.616251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.297283Z","time spent":"318.961646ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T04:13:51.009207Z","caller":"traceutil/trace.go:171","msg":"trace[1755671670] linearizableReadLoop","detail":"{readStateIndex:26467; appliedIndex:26466; }","duration":"334.767878ms","start":"2026-07-02T04:13:50.674403Z","end":"2026-07-02T04:13:51.009171Z","steps":["trace[1755671670] 'read index received' (duration: 248.624319ms)","trace[1755671670] 'applied index is now lower than readState.Index' (duration: 86.142529ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:51.009294Z","caller":"traceutil/trace.go:171","msg":"trace[1350587930] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"370.068259ms","start":"2026-07-02T04:13:50.639151Z","end":"2026-07-02T04:13:51.009219Z","steps":["trace[1350587930] 'process raft request' (duration: 283.859848ms)","trace[1350587930] 'compare' (duration: 86.037427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:51.00939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.960901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:51.009433Z","caller":"traceutil/trace.go:171","msg":"trace[982280968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25621; }","duration":"335.056063ms","start":"2026-07-02T04:13:50.674366Z","end":"2026-07-02T04:13:51.009422Z","steps":["trace[982280968] 'agreement among raft nodes before linearized reading' (duration: 334.961382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:51.009468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.674345Z","time spent":"335.115734ms","remote":"127.0.0.1:52480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T04:13:51.009578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.639125Z","time spent":"370.301423ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:51.041066Z","caller":"traceutil/trace.go:171","msg":"trace[351938343] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"284.079743ms","start":"2026-07-02T04:13:50.756936Z","end":"2026-07-02T04:13:51.041016Z","steps":["trace[351938343] 'process raft request' (duration: 283.819838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:51.041395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.576257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:51.041486Z","caller":"traceutil/trace.go:171","msg":"trace[1429944969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25622; }","duration":"321.710419ms","start":"2026-07-02T04:13:50.719754Z","end":"2026-07-02T04:13:51.041465Z","steps":["trace[1429944969] 'agreement among raft nodes before linearized reading' (duration: 321.534456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:51.041409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.274253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:51.041558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.569984ms","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-07-02T04:13:51.041683Z","caller":"traceutil/trace.go:171","msg":"trace[142742210] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25622; }","duration":"333.754758ms","start":"2026-07-02T04:13:50.707897Z","end":"2026-07-02T04:13:51.041652Z","steps":["trace[142742210] 'agreement among raft nodes before linearized reading' (duration: 333.549984ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:51.041604Z","caller":"traceutil/trace.go:171","msg":"trace[351088694] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25622; }","duration":"329.529097ms","start":"2026-07-02T04:13:50.712045Z","end":"2026-07-02T04:13:51.041574Z","steps":["trace[351088694] 'agreement among raft nodes before linearized reading' (duration: 329.294103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:51.041745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.707872Z","time spent":"333.856ms","remote":"127.0.0.1:51662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:51.041801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.712019Z","time spent":"329.754001ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:51.041536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:50.719744Z","time spent":"321.78088ms","remote":"127.0.0.1:56886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-02T04:13:51.229113Z","caller":"traceutil/trace.go:171","msg":"trace[922069564] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"183.042062ms","start":"2026-07-02T04:13:51.04603Z","end":"2026-07-02T04:13:51.229072Z","steps":["trace[922069564] 'process raft request' (duration: 131.628174ms)","trace[922069564] 'compare' (duration: 51.164803ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:51.702079Z","caller":"traceutil/trace.go:171","msg":"trace[471135613] linearizableReadLoop","detail":"{readStateIndex:26470; appliedIndex:26469; }","duration":"145.796113ms","start":"2026-07-02T04:13:51.556257Z","end":"2026-07-02T04:13:51.702053Z","steps":["trace[471135613] 'read index received' (duration: 145.518218ms)","trace[471135613] 'applied index is now lower than readState.Index' (duration: 276.935µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:51.702408Z","caller":"traceutil/trace.go:171","msg":"trace[157912425] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"148.536745ms","start":"2026-07-02T04:13:51.55384Z","end":"2026-07-02T04:13:51.702377Z","steps":["trace[157912425] 'process raft request' (duration: 148.011885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:51.702506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.225391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:51.702565Z","caller":"traceutil/trace.go:171","msg":"trace[259215002] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25624; }","duration":"146.337613ms","start":"2026-07-02T04:13:51.55621Z","end":"2026-07-02T04:13:51.702547Z","steps":["trace[259215002] 'agreement among raft nodes before linearized reading' (duration: 146.191ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:51.863798Z","caller":"traceutil/trace.go:171","msg":"trace[1846504501] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"121.919799ms","start":"2026-07-02T04:13:51.741839Z","end":"2026-07-02T04:13:51.863758Z","steps":["trace[1846504501] 'process raft request' (duration: 108.359141ms)","trace[1846504501] 'compare' (duration: 13.200631ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:52.135183Z","caller":"traceutil/trace.go:171","msg":"trace[969966353] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"392.875893ms","start":"2026-07-02T04:13:51.742259Z","end":"2026-07-02T04:13:52.135135Z","steps":["trace[969966353] 'process raft request' (duration: 357.064731ms)","trace[969966353] 'compare' (duration: 35.492556ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:52.135208Z","caller":"traceutil/trace.go:171","msg":"trace[1682243078] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"392.421063ms","start":"2026-07-02T04:13:51.742751Z","end":"2026-07-02T04:13:52.135172Z","steps":["trace[1682243078] 'process raft request' (duration: 392.305901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:52.135389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:51.74223Z","time spent":"393.053417ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:52.135427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:51.742693Z","time spent":"392.618377ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:52.448455Z","caller":"traceutil/trace.go:171","msg":"trace[680446071] linearizableReadLoop","detail":"{readStateIndex:26476; appliedIndex:26474; }","duration":"212.432731ms","start":"2026-07-02T04:13:52.235985Z","end":"2026-07-02T04:13:52.448418Z","steps":["trace[680446071] 'read index received' (duration: 187.19863ms)","trace[680446071] 'applied index is now lower than readState.Index' (duration: 25.232701ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:52.448527Z","caller":"traceutil/trace.go:171","msg":"trace[891027828] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"244.199125ms","start":"2026-07-02T04:13:52.204297Z","end":"2026-07-02T04:13:52.448496Z","steps":["trace[891027828] 'process raft request' (duration: 218.870913ms)","trace[891027828] 'compare' (duration: 24.527076ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:52.448704Z","caller":"traceutil/trace.go:171","msg":"trace[1025473800] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"242.088466ms","start":"2026-07-02T04:13:52.206599Z","end":"2026-07-02T04:13:52.448687Z","steps":["trace[1025473800] 'process raft request' (duration: 241.704207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:52.449051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.480988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:52.449156Z","caller":"traceutil/trace.go:171","msg":"trace[364522777] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25630; }","duration":"206.569189ms","start":"2026-07-02T04:13:52.242527Z","end":"2026-07-02T04:13:52.449096Z","steps":["trace[364522777] 'agreement among raft nodes before linearized reading' (duration: 206.452057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:52.449522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.5124ms","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-07-02T04:13:52.450247Z","caller":"traceutil/trace.go:171","msg":"trace[175750666] 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:25630; }","duration":"213.640813ms","start":"2026-07-02T04:13:52.235923Z","end":"2026-07-02T04:13:52.449564Z","steps":["trace[175750666] 'agreement among raft nodes before linearized reading' (duration: 213.515401ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:52.778198Z","caller":"traceutil/trace.go:171","msg":"trace[750810094] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"151.018642ms","start":"2026-07-02T04:13:52.627122Z","end":"2026-07-02T04:13:52.778141Z","steps":["trace[750810094] 'process raft request' (duration: 150.806208ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:52.848814Z","caller":"traceutil/trace.go:171","msg":"trace[777743599] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"217.362174ms","start":"2026-07-02T04:13:52.631416Z","end":"2026-07-02T04:13:52.848778Z","steps":["trace[777743599] 'process raft request' (duration: 214.147992ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:52.849216Z","caller":"traceutil/trace.go:171","msg":"trace[2096675766] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"214.846247ms","start":"2026-07-02T04:13:52.634363Z","end":"2026-07-02T04:13:52.849209Z","steps":["trace[2096675766] 'process raft request' (duration: 211.364109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.129798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.738898ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T04:13:53.129899Z","caller":"traceutil/trace.go:171","msg":"trace[1060538227] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26477; }","duration":"481.331155ms","start":"2026-07-02T04:13:52.648552Z","end":"2026-07-02T04:13:53.129883Z","steps":["trace[1060538227] 'read index received' (duration: 129.406681ms)","trace[1060538227] 'applied index is now lower than readState.Index' (duration: 351.923194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:53.130641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.077529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274825"} {"level":"info","ts":"2026-07-02T04:13:53.130677Z","caller":"traceutil/trace.go:171","msg":"trace[83077590] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25634; }","duration":"482.12287ms","start":"2026-07-02T04:13:52.648545Z","end":"2026-07-02T04:13:53.130668Z","steps":["trace[83077590] 'agreement among raft nodes before linearized reading' (duration: 481.693161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.130703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:52.648497Z","time spent":"482.199871ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274848,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-07-02T04:13:53.449895Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006773.wal"} {"level":"info","ts":"2026-07-02T04:13:53.450646Z","caller":"traceutil/trace.go:171","msg":"trace[512192857] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"455.288219ms","start":"2026-07-02T04:13:52.995325Z","end":"2026-07-02T04:13:53.450613Z","steps":["trace[512192857] 'process raft request' (duration: 454.887821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.450844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:52.995286Z","time spent":"455.452852ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418382,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:53.459108Z","caller":"traceutil/trace.go:171","msg":"trace[1317212467] linearizableReadLoop","detail":"{readStateIndex:26483; appliedIndex:26481; }","duration":"329.13108ms","start":"2026-07-02T04:13:53.129946Z","end":"2026-07-02T04:13:53.459077Z","steps":["trace[1317212467] 'read index received' (duration: 320.138649ms)","trace[1317212467] 'applied index is now lower than readState.Index' (duration: 8.991111ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:53.459293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.584317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:53.459351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.31043ms","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-07-02T04:13:53.45939Z","caller":"traceutil/trace.go:171","msg":"trace[770281299] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25636; }","duration":"392.712519ms","start":"2026-07-02T04:13:53.066641Z","end":"2026-07-02T04:13:53.459353Z","steps":["trace[770281299] 'agreement among raft nodes before linearized reading' (duration: 392.479805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.459374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.631964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:53.459433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.490669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:53.459438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:53.066631Z","time spent":"392.797731ms","remote":"127.0.0.1:36328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-07-02T04:13:53.459455Z","caller":"traceutil/trace.go:171","msg":"trace[1043300789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25636; }","duration":"783.750576ms","start":"2026-07-02T04:13:52.675685Z","end":"2026-07-02T04:13:53.459435Z","steps":["trace[1043300789] 'agreement among raft nodes before linearized reading' (duration: 783.610014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.459478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.061374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:53.459496Z","caller":"traceutil/trace.go:171","msg":"trace[1847751344] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25636; }","duration":"602.587241ms","start":"2026-07-02T04:13:52.856891Z","end":"2026-07-02T04:13:53.459479Z","steps":["trace[1847751344] 'agreement among raft nodes before linearized reading' (duration: 602.481469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.459506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:52.675674Z","time spent":"783.824168ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T04:13:53.459516Z","caller":"traceutil/trace.go:171","msg":"trace[108317315] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25636; }","duration":"109.163827ms","start":"2026-07-02T04:13:53.350342Z","end":"2026-07-02T04:13:53.459505Z","steps":["trace[108317315] 'agreement among raft nodes before linearized reading' (duration: 109.095085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.459539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:52.856871Z","time spent":"602.657752ms","remote":"127.0.0.1:38042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:53.45957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.930721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T04:13:53.459661Z","caller":"traceutil/trace.go:171","msg":"trace[1732132719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25636; }","duration":"222.081995ms","start":"2026-07-02T04:13:53.237555Z","end":"2026-07-02T04:13:53.459637Z","steps":["trace[1732132719] 'agreement among raft nodes before linearized reading' (duration: 221.823419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.459726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.743343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:53.459773Z","caller":"traceutil/trace.go:171","msg":"trace[1075662135] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25636; }","duration":"768.834803ms","start":"2026-07-02T04:13:52.690926Z","end":"2026-07-02T04:13:53.45976Z","steps":["trace[1075662135] 'agreement among raft nodes before linearized reading' (duration: 768.766042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:53.459401Z","caller":"traceutil/trace.go:171","msg":"trace[1714338877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25636; }","duration":"444.414873ms","start":"2026-07-02T04:13:53.014973Z","end":"2026-07-02T04:13:53.459387Z","steps":["trace[1714338877] 'agreement among raft nodes before linearized reading' (duration: 444.220689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.459811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:52.690905Z","time spent":"768.896645ms","remote":"127.0.0.1:36296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:53.45982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:53.01495Z","time spent":"444.860101ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T04:13:53.460104Z","caller":"traceutil/trace.go:171","msg":"trace[2015950379] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"412.135509ms","start":"2026-07-02T04:13:53.047949Z","end":"2026-07-02T04:13:53.460084Z","steps":["trace[2015950379] 'process raft request' (duration: 410.952645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:53.460239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:53.047915Z","time spent":"412.244731ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:53.770756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.621413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:53.770876Z","caller":"traceutil/trace.go:171","msg":"trace[61376684] linearizableReadLoop","detail":"{readStateIndex:26485; appliedIndex:26484; }","duration":"172.247017ms","start":"2026-07-02T04:13:53.598617Z","end":"2026-07-02T04:13:53.770864Z","steps":["trace[61376684] 'read index received' (duration: 9.401379ms)","trace[61376684] 'applied index is now lower than readState.Index' (duration: 162.844808ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:53.77093Z","caller":"traceutil/trace.go:171","msg":"trace[229931099] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"298.567189ms","start":"2026-07-02T04:13:53.472355Z","end":"2026-07-02T04:13:53.770922Z","steps":["trace[229931099] 'process raft request' (duration: 135.721131ms)","trace[229931099] 'compare' (duration: 162.100783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:53.771124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.505921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:53.771148Z","caller":"traceutil/trace.go:171","msg":"trace[669511161] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25639; }","duration":"172.585263ms","start":"2026-07-02T04:13:53.598555Z","end":"2026-07-02T04:13:53.771141Z","steps":["trace[669511161] 'agreement among raft nodes before linearized reading' (duration: 172.530642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.122777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.834936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:54.122934Z","caller":"traceutil/trace.go:171","msg":"trace[91841224] linearizableReadLoop","detail":"{readStateIndex:26487; appliedIndex:26486; }","duration":"246.379576ms","start":"2026-07-02T04:13:53.876528Z","end":"2026-07-02T04:13:54.122908Z","steps":["trace[91841224] 'read index received' (duration: 102.194954ms)","trace[91841224] 'applied index is now lower than readState.Index' (duration: 144.182562ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:54.123153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.622511ms","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-07-02T04:13:54.123147Z","caller":"traceutil/trace.go:171","msg":"trace[1134772658] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"254.424378ms","start":"2026-07-02T04:13:53.868701Z","end":"2026-07-02T04:13:54.123125Z","steps":["trace[1134772658] 'process raft request' (duration: 110.098283ms)","trace[1134772658] 'compare' (duration: 143.655512ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:54.123197Z","caller":"traceutil/trace.go:171","msg":"trace[282620311] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25640; }","duration":"246.707072ms","start":"2026-07-02T04:13:53.876474Z","end":"2026-07-02T04:13:54.123181Z","steps":["trace[282620311] 'agreement among raft nodes before linearized reading' (duration: 246.505418ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:54.278683Z","caller":"traceutil/trace.go:171","msg":"trace[2088890781] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"137.549906ms","start":"2026-07-02T04:13:54.141109Z","end":"2026-07-02T04:13:54.278658Z","steps":["trace[2088890781] 'process raft request' (duration: 137.349312ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:54.279297Z","caller":"traceutil/trace.go:171","msg":"trace[336759426] linearizableReadLoop","detail":"{readStateIndex:26489; appliedIndex:26487; }","duration":"118.175417ms","start":"2026-07-02T04:13:54.161107Z","end":"2026-07-02T04:13:54.279282Z","steps":["trace[336759426] 'read index received' (duration: 117.315151ms)","trace[336759426] 'applied index is now lower than readState.Index' (duration: 859.456µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:54.279414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.298569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T04:13:54.279439Z","caller":"traceutil/trace.go:171","msg":"trace[913366873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25643; }","duration":"118.360501ms","start":"2026-07-02T04:13:54.161073Z","end":"2026-07-02T04:13:54.279434Z","steps":["trace[913366873] 'agreement among raft nodes before linearized reading' (duration: 118.267579ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:54.279509Z","caller":"traceutil/trace.go:171","msg":"trace[1028830835] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"137.175829ms","start":"2026-07-02T04:13:54.142313Z","end":"2026-07-02T04:13:54.279488Z","steps":["trace[1028830835] 'process raft request' (duration: 136.837163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.591989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.057277ms","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-07-02T04:13:54.592095Z","caller":"traceutil/trace.go:171","msg":"trace[901330635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25644; }","duration":"138.22184ms","start":"2026-07-02T04:13:54.453848Z","end":"2026-07-02T04:13:54.59207Z","steps":["trace[901330635] 'range keys from in-memory index tree' (duration: 137.578917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.593134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.823529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T04:13:54.593243Z","caller":"traceutil/trace.go:171","msg":"trace[2025794714] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25644; }","duration":"171.027383ms","start":"2026-07-02T04:13:54.42219Z","end":"2026-07-02T04:13:54.593217Z","steps":["trace[2025794714] 'count revisions from in-memory index tree' (duration: 170.706776ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:54.617478Z","caller":"traceutil/trace.go:171","msg":"trace[732569016] linearizableReadLoop","detail":"{readStateIndex:26492; appliedIndex:26491; }","duration":"121.690125ms","start":"2026-07-02T04:13:54.495761Z","end":"2026-07-02T04:13:54.617451Z","steps":["trace[732569016] 'read index received' (duration: 121.344348ms)","trace[732569016] 'applied index is now lower than readState.Index' (duration: 344.717µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:54.617538Z","caller":"traceutil/trace.go:171","msg":"trace[1877169540] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"162.361268ms","start":"2026-07-02T04:13:54.455138Z","end":"2026-07-02T04:13:54.617499Z","steps":["trace[1877169540] 'process raft request' (duration: 161.96651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.617667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.892599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-07-02T04:13:54.617709Z","caller":"traceutil/trace.go:171","msg":"trace[994973179] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:25645; }","duration":"122.014001ms","start":"2026-07-02T04:13:54.495681Z","end":"2026-07-02T04:13:54.617695Z","steps":["trace[994973179] 'agreement among raft nodes before linearized reading' (duration: 121.903069ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:54.918282Z","caller":"traceutil/trace.go:171","msg":"trace[883637922] linearizableReadLoop","detail":"{readStateIndex:26493; appliedIndex:26492; }","duration":"300.704859ms","start":"2026-07-02T04:13:54.61755Z","end":"2026-07-02T04:13:54.918255Z","steps":["trace[883637922] 'read index received' (duration: 254.624823ms)","trace[883637922] 'applied index is now lower than readState.Index' (duration: 46.079186ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:54.918431Z","caller":"traceutil/trace.go:171","msg":"trace[1896266285] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"321.828492ms","start":"2026-07-02T04:13:54.596582Z","end":"2026-07-02T04:13:54.91841Z","steps":["trace[1896266285] 'process raft request' (duration: 275.571332ms)","trace[1896266285] 'compare' (duration: 46.002785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:54.91865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:54.59656Z","time spent":"321.950023ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:54.918679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.876249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:54.918797Z","caller":"traceutil/trace.go:171","msg":"trace[406654372] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25646; }","duration":"335.065942ms","start":"2026-07-02T04:13:54.583705Z","end":"2026-07-02T04:13:54.918771Z","steps":["trace[406654372] 'agreement among raft nodes before linearized reading' (duration: 334.859518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.918858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:54.583677Z","time spent":"335.169264ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:54.92174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.510224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-htw8c\" ","response":"range_response_count:1 size:5638"} {"level":"info","ts":"2026-07-02T04:13:54.921832Z","caller":"traceutil/trace.go:171","msg":"trace[2048937146] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-htw8c; range_end:; response_count:1; response_revision:25648; }","duration":"302.621476ms","start":"2026-07-02T04:13:54.61919Z","end":"2026-07-02T04:13:54.921811Z","steps":["trace[2048937146] 'agreement among raft nodes before linearized reading' (duration: 302.360901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.921787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.76787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:54.921882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:54.619147Z","time spent":"302.725538ms","remote":"127.0.0.1:52660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5661,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-htw8c\" "} {"level":"info","ts":"2026-07-02T04:13:54.92192Z","caller":"traceutil/trace.go:171","msg":"trace[1246385410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25648; }","duration":"249.936104ms","start":"2026-07-02T04:13:54.671974Z","end":"2026-07-02T04:13:54.92191Z","steps":["trace[1246385410] 'agreement among raft nodes before linearized reading' (duration: 249.650059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:54.921939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.089295ms","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-07-02T04:13:54.922024Z","caller":"traceutil/trace.go:171","msg":"trace[853797633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25648; }","duration":"139.218449ms","start":"2026-07-02T04:13:54.782787Z","end":"2026-07-02T04:13:54.922005Z","steps":["trace[853797633] 'agreement among raft nodes before linearized reading' (duration: 138.82393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:55.199987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.042859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:55.200126Z","caller":"traceutil/trace.go:171","msg":"trace[840266149] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"257.893655ms","start":"2026-07-02T04:13:54.94221Z","end":"2026-07-02T04:13:55.200104Z","steps":["trace[840266149] 'process raft request' (duration: 132.636602ms)","trace[840266149] 'compare' (duration: 124.885936ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:02.024242Z","caller":"traceutil/trace.go:171","msg":"trace[248286496] linearizableReadLoop","detail":"{readStateIndex:26546; appliedIndex:26545; }","duration":"209.356572ms","start":"2026-07-02T04:14:01.814855Z","end":"2026-07-02T04:14:02.024212Z","steps":["trace[248286496] 'read index received' (duration: 209.097118ms)","trace[248286496] 'applied index is now lower than readState.Index' (duration: 258.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:02.024283Z","caller":"traceutil/trace.go:171","msg":"trace[156819383] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"214.301846ms","start":"2026-07-02T04:14:01.809949Z","end":"2026-07-02T04:14:02.024251Z","steps":["trace[156819383] 'process raft request' (duration: 214.030991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:02.024484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.600507ms","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-07-02T04:14:02.024535Z","caller":"traceutil/trace.go:171","msg":"trace[1421058125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25698; }","duration":"209.718159ms","start":"2026-07-02T04:14:01.814799Z","end":"2026-07-02T04:14:02.024518Z","steps":["trace[1421058125] 'agreement among raft nodes before linearized reading' (duration: 209.523676ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:02.338068Z","caller":"traceutil/trace.go:171","msg":"trace[1341737620] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"187.276433ms","start":"2026-07-02T04:14:02.150763Z","end":"2026-07-02T04:14:02.33804Z","steps":["trace[1341737620] 'process raft request' (duration: 185.947677ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:02.628838Z","caller":"traceutil/trace.go:171","msg":"trace[1330866206] linearizableReadLoop","detail":"{readStateIndex:26550; appliedIndex:26548; }","duration":"289.120849ms","start":"2026-07-02T04:14:02.339695Z","end":"2026-07-02T04:14:02.628816Z","steps":["trace[1330866206] 'read index received' (duration: 288.855824ms)","trace[1330866206] 'applied index is now lower than readState.Index' (duration: 264.415µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:02.628863Z","caller":"traceutil/trace.go:171","msg":"trace[825054336] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"323.079336ms","start":"2026-07-02T04:14:02.305754Z","end":"2026-07-02T04:14:02.628833Z","steps":["trace[825054336] 'process raft request' (duration: 322.888662ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:02.628892Z","caller":"traceutil/trace.go:171","msg":"trace[566721875] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"313.754228ms","start":"2026-07-02T04:14:02.315098Z","end":"2026-07-02T04:14:02.628853Z","steps":["trace[566721875] 'process raft request' (duration: 313.685067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:02.629013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.305737Z","time spent":"323.187867ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:02.629049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.343724ms","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-07-02T04:14:02.629045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.315091Z","time spent":"313.87237ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:02.629078Z","caller":"traceutil/trace.go:171","msg":"trace[1137321241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25702; }","duration":"289.436786ms","start":"2026-07-02T04:14:02.339633Z","end":"2026-07-02T04:14:02.62907Z","steps":["trace[1137321241] 'agreement among raft nodes before linearized reading' (duration: 289.293433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:02.629102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.490923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:02.629188Z","caller":"traceutil/trace.go:171","msg":"trace[767610811] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25702; }","duration":"150.594805ms","start":"2026-07-02T04:14:02.478571Z","end":"2026-07-02T04:14:02.629166Z","steps":["trace[767610811] 'agreement among raft nodes before linearized reading' (duration: 150.428072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.195339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.00149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:03.195607Z","caller":"traceutil/trace.go:171","msg":"trace[1675251755] linearizableReadLoop","detail":"{readStateIndex:26552; appliedIndex:26551; }","duration":"537.396242ms","start":"2026-07-02T04:14:02.658181Z","end":"2026-07-02T04:14:03.195577Z","steps":["trace[1675251755] 'read index received' (duration: 262.044425ms)","trace[1675251755] 'applied index is now lower than readState.Index' (duration: 275.349557ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:03.19574Z","caller":"traceutil/trace.go:171","msg":"trace[950953552] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"542.5588ms","start":"2026-07-02T04:14:02.653163Z","end":"2026-07-02T04:14:03.195722Z","steps":["trace[950953552] 'process raft request' (duration: 267.04983ms)","trace[950953552] 'compare' (duration: 274.622153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:03.195864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.653136Z","time spent":"542.634462ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:03.196098Z","caller":"traceutil/trace.go:171","msg":"trace[387360975] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"478.286278ms","start":"2026-07-02T04:14:02.717761Z","end":"2026-07-02T04:14:03.196047Z","steps":["trace[387360975] 'process raft request' (duration: 477.718537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.196315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.717723Z","time spent":"478.472901ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:03.219723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.518891ms","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-07-02T04:14:03.219794Z","caller":"traceutil/trace.go:171","msg":"trace[184655447] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25705; }","duration":"561.617662ms","start":"2026-07-02T04:14:02.658156Z","end":"2026-07-02T04:14:03.219773Z","steps":["trace[184655447] 'agreement among raft nodes before linearized reading' (duration: 537.84719ms)","trace[184655447] 'range keys from in-memory index tree' (duration: 23.662041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:03.219844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.658145Z","time spent":"561.689164ms","remote":"127.0.0.1:52764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-07-02T04:14:03.502268Z","caller":"traceutil/trace.go:171","msg":"trace[1553264637] linearizableReadLoop","detail":"{readStateIndex:26556; appliedIndex:26554; }","duration":"306.57094ms","start":"2026-07-02T04:14:03.19568Z","end":"2026-07-02T04:14:03.502251Z","steps":["trace[1553264637] 'read index received' (duration: 306.340806ms)","trace[1553264637] 'applied index is now lower than readState.Index' (duration: 229.644µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:03.50234Z","caller":"traceutil/trace.go:171","msg":"trace[1969571870] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"545.997714ms","start":"2026-07-02T04:14:02.956298Z","end":"2026-07-02T04:14:03.502296Z","steps":["trace[1969571870] 'process raft request' (duration: 545.918703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.502415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.69949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:03.502444Z","caller":"traceutil/trace.go:171","msg":"trace[1821577885] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25707; }","duration":"452.795672ms","start":"2026-07-02T04:14:03.049638Z","end":"2026-07-02T04:14:03.502434Z","steps":["trace[1821577885] 'agreement among raft nodes before linearized reading' (duration: 452.69469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.502468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:03.049491Z","time spent":"452.971314ms","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:14:03.502546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.833493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:14:03.502641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.784645ms","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-07-02T04:14:03.502659Z","caller":"traceutil/trace.go:171","msg":"trace[747443502] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25707; }","duration":"276.802205ms","start":"2026-07-02T04:14:03.225851Z","end":"2026-07-02T04:14:03.502653Z","steps":["trace[747443502] 'agreement among raft nodes before linearized reading' (duration: 276.770394ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:03.502643Z","caller":"traceutil/trace.go:171","msg":"trace[1428022203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25707; }","duration":"828.963787ms","start":"2026-07-02T04:14:02.673658Z","end":"2026-07-02T04:14:03.502622Z","steps":["trace[1428022203] 'agreement among raft nodes before linearized reading' (duration: 828.725442ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:03.502658Z","caller":"traceutil/trace.go:171","msg":"trace[641599328] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"547.579445ms","start":"2026-07-02T04:14:02.955041Z","end":"2026-07-02T04:14:03.50262Z","steps":["trace[641599328] 'process raft request' (duration: 547.032704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.50271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.673651Z","time spent":"829.040669ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T04:14:03.502649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.956262Z","time spent":"546.179327ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:03.502797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.505508ms","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-07-02T04:14:03.502784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.955007Z","time spent":"547.698268ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:03.502819Z","caller":"traceutil/trace.go:171","msg":"trace[150652571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25707; }","duration":"268.570118ms","start":"2026-07-02T04:14:03.234242Z","end":"2026-07-02T04:14:03.502812Z","steps":["trace[150652571] 'agreement among raft nodes before linearized reading' (duration: 268.496437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.502985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.12731ms","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-07-02T04:14:03.503178Z","caller":"traceutil/trace.go:171","msg":"trace[1543724196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25707; }","duration":"533.347465ms","start":"2026-07-02T04:14:02.969791Z","end":"2026-07-02T04:14:03.503138Z","steps":["trace[1543724196] 'agreement among raft nodes before linearized reading' (duration: 532.744034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:03.503282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:02.969772Z","time spent":"533.495407ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T04:14:08.82354Z","caller":"traceutil/trace.go:171","msg":"trace[1020065271] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"172.611514ms","start":"2026-07-02T04:14:08.650881Z","end":"2026-07-02T04:14:08.823493Z","steps":["trace[1020065271] 'process raft request' (duration: 172.3846ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:08.847206Z","caller":"traceutil/trace.go:171","msg":"trace[1387373478] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26593; }","duration":"169.584045ms","start":"2026-07-02T04:14:08.6776Z","end":"2026-07-02T04:14:08.847184Z","steps":["trace[1387373478] 'read index received' (duration: 145.62654ms)","trace[1387373478] 'applied index is now lower than readState.Index' (duration: 23.956705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:08.847421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.806239ms","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-07-02T04:14:08.84745Z","caller":"traceutil/trace.go:171","msg":"trace[1306349936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25745; }","duration":"169.910831ms","start":"2026-07-02T04:14:08.677532Z","end":"2026-07-02T04:14:08.847443Z","steps":["trace[1306349936] 'agreement among raft nodes before linearized reading' (duration: 169.741808ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:08.847764Z","caller":"traceutil/trace.go:171","msg":"trace[531744419] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"195.861226ms","start":"2026-07-02T04:14:08.651894Z","end":"2026-07-02T04:14:08.847755Z","steps":["trace[531744419] 'process raft request' (duration: 195.133481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:09.036567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.249164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:09.036704Z","caller":"traceutil/trace.go:171","msg":"trace[354607194] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26595; }","duration":"130.527473ms","start":"2026-07-02T04:14:08.906157Z","end":"2026-07-02T04:14:09.036684Z","steps":["trace[354607194] 'read index received' (duration: 16.034455ms)","trace[354607194] 'applied index is now lower than readState.Index' (duration: 114.491908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:09.036779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.623175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:09.036812Z","caller":"traceutil/trace.go:171","msg":"trace[2145535085] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25746; }","duration":"130.674446ms","start":"2026-07-02T04:14:08.906125Z","end":"2026-07-02T04:14:09.036799Z","steps":["trace[2145535085] 'agreement among raft nodes before linearized reading' (duration: 130.608265ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:09.036767Z","caller":"traceutil/trace.go:171","msg":"trace[2099703495] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"184.53411ms","start":"2026-07-02T04:14:08.852184Z","end":"2026-07-02T04:14:09.036719Z","steps":["trace[2099703495] 'process raft request' (duration: 70.062753ms)","trace[2099703495] 'compare' (duration: 114.09471ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:13.040446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.761787ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T04:14:13.040833Z","caller":"traceutil/trace.go:171","msg":"trace[1992899316] linearizableReadLoop","detail":"{readStateIndex:26684; appliedIndex:26683; }","duration":"250.934942ms","start":"2026-07-02T04:14:12.789868Z","end":"2026-07-02T04:14:13.040803Z","steps":["trace[1992899316] 'read index received' (duration: 100.033233ms)","trace[1992899316] 'applied index is now lower than readState.Index' (duration: 150.899599ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:13.041035Z","caller":"traceutil/trace.go:171","msg":"trace[1240114070] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"206.508177ms","start":"2026-07-02T04:14:12.834493Z","end":"2026-07-02T04:14:13.041002Z","steps":["trace[1240114070] 'process raft request' (duration: 206.11584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:13.04115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.260098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abc24feb-1dcb-4479-aedb-5dd37641ccd8\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-02T04:14:13.041198Z","caller":"traceutil/trace.go:171","msg":"trace[1679070046] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abc24feb-1dcb-4479-aedb-5dd37641ccd8; range_end:; response_count:1; response_revision:25833; }","duration":"251.365432ms","start":"2026-07-02T04:14:12.789822Z","end":"2026-07-02T04:14:13.041188Z","steps":["trace[1679070046] 'agreement among raft nodes before linearized reading' (duration: 251.112666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:13.041339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.158696ms","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-07-02T04:14:13.041422Z","caller":"traceutil/trace.go:171","msg":"trace[971968420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25834; }","duration":"212.283478ms","start":"2026-07-02T04:14:12.829119Z","end":"2026-07-02T04:14:13.041402Z","steps":["trace[971968420] 'agreement among raft nodes before linearized reading' (duration: 212.127475ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:13.04162Z","caller":"traceutil/trace.go:171","msg":"trace[834345298] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"178.166979ms","start":"2026-07-02T04:14:12.863435Z","end":"2026-07-02T04:14:13.041602Z","steps":["trace[834345298] 'process raft request' (duration: 177.432165ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:13.26842Z","caller":"traceutil/trace.go:171","msg":"trace[1063586832] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"213.898439ms","start":"2026-07-02T04:14:13.054499Z","end":"2026-07-02T04:14:13.268397Z","steps":["trace[1063586832] 'process raft request' (duration: 213.760867ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:13.349642Z","caller":"traceutil/trace.go:171","msg":"trace[1099001014] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"117.604987ms","start":"2026-07-02T04:14:13.232001Z","end":"2026-07-02T04:14:13.349606Z","steps":["trace[1099001014] 'process raft request' (duration: 117.131327ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:13.534212Z","caller":"traceutil/trace.go:171","msg":"trace[1111897973] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"146.941734ms","start":"2026-07-02T04:14:13.387227Z","end":"2026-07-02T04:14:13.534168Z","steps":["trace[1111897973] 'process raft request' (duration: 126.325772ms)","trace[1111897973] 'compare' (duration: 20.397788ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:15.408642Z","caller":"traceutil/trace.go:171","msg":"trace[1389989963] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"130.88252ms","start":"2026-07-02T04:14:15.277709Z","end":"2026-07-02T04:14:15.408592Z","steps":["trace[1389989963] 'process raft request' (duration: 130.694466ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:20.272143Z","caller":"traceutil/trace.go:171","msg":"trace[336932372] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"134.981477ms","start":"2026-07-02T04:14:20.137126Z","end":"2026-07-02T04:14:20.272107Z","steps":["trace[336932372] 'process raft request' (duration: 65.954254ms)","trace[336932372] 'compare' (duration: 68.700737ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:21.107034Z","caller":"traceutil/trace.go:171","msg":"trace[1246376047] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"175.774364ms","start":"2026-07-02T04:14:20.931231Z","end":"2026-07-02T04:14:21.107005Z","steps":["trace[1246376047] 'process raft request' (duration: 175.462328ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:21.181512Z","caller":"traceutil/trace.go:171","msg":"trace[666741177] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"110.996661ms","start":"2026-07-02T04:14:21.070468Z","end":"2026-07-02T04:14:21.181465Z","steps":["trace[666741177] 'process raft request' (duration: 110.93646ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:21.181554Z","caller":"traceutil/trace.go:171","msg":"trace[343357510] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"111.035002ms","start":"2026-07-02T04:14:21.070457Z","end":"2026-07-02T04:14:21.181492Z","steps":["trace[343357510] 'process raft request' (duration: 110.740756ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:44.408496Z","caller":"traceutil/trace.go:171","msg":"trace[1615271265] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"176.599979ms","start":"2026-07-02T04:14:44.231867Z","end":"2026-07-02T04:14:44.408467Z","steps":["trace[1615271265] 'process raft request' (duration: 176.378875ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:44.618456Z","caller":"traceutil/trace.go:171","msg":"trace[526067055] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"162.169535ms","start":"2026-07-02T04:14:44.456252Z","end":"2026-07-02T04:14:44.618422Z","steps":["trace[526067055] 'process raft request' (duration: 92.692853ms)","trace[526067055] 'compare' (duration: 69.046653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:45.509401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.389775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:45.509527Z","caller":"traceutil/trace.go:171","msg":"trace[1046618515] linearizableReadLoop","detail":"{readStateIndex:26974; appliedIndex:26973; }","duration":"153.596982ms","start":"2026-07-02T04:14:45.355911Z","end":"2026-07-02T04:14:45.509508Z","steps":["trace[1046618515] 'read index received' (duration: 48.247397ms)","trace[1046618515] 'applied index is now lower than readState.Index' (duration: 105.347795ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:45.509628Z","caller":"traceutil/trace.go:171","msg":"trace[1990108990] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"226.943887ms","start":"2026-07-02T04:14:45.282674Z","end":"2026-07-02T04:14:45.509618Z","steps":["trace[1990108990] 'process raft request' (duration: 121.501251ms)","trace[1990108990] 'compare' (duration: 104.206762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:45.510141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.227183ms","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-07-02T04:14:45.510175Z","caller":"traceutil/trace.go:171","msg":"trace[1851508093] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26116; }","duration":"154.320145ms","start":"2026-07-02T04:14:45.355847Z","end":"2026-07-02T04:14:45.510167Z","steps":["trace[1851508093] 'agreement among raft nodes before linearized reading' (duration: 154.011459ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:45.791176Z","caller":"traceutil/trace.go:171","msg":"trace[257175692] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26975; }","duration":"116.550166ms","start":"2026-07-02T04:14:45.6746Z","end":"2026-07-02T04:14:45.79115Z","steps":["trace[257175692] 'read index received' (duration: 68.140076ms)","trace[257175692] 'applied index is now lower than readState.Index' (duration: 48.40692ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:45.791284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.678518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:45.791312Z","caller":"traceutil/trace.go:171","msg":"trace[1251945387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26118; }","duration":"116.74964ms","start":"2026-07-02T04:14:45.674552Z","end":"2026-07-02T04:14:45.791301Z","steps":["trace[1251945387] 'agreement among raft nodes before linearized reading' (duration: 116.689569ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:45.791266Z","caller":"traceutil/trace.go:171","msg":"trace[1181287592] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"198.052776ms","start":"2026-07-02T04:14:45.593148Z","end":"2026-07-02T04:14:45.791201Z","steps":["trace[1181287592] 'process raft request' (duration: 149.612525ms)","trace[1181287592] 'compare' (duration: 48.228617ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:46.029174Z","caller":"traceutil/trace.go:171","msg":"trace[810363136] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"199.793279ms","start":"2026-07-02T04:14:45.829325Z","end":"2026-07-02T04:14:46.029118Z","steps":["trace[810363136] 'process raft request' (duration: 162.102943ms)","trace[810363136] 'compare' (duration: 36.737759ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:46.02988Z","caller":"traceutil/trace.go:171","msg":"trace[2126038036] linearizableReadLoop","detail":"{readStateIndex:26979; appliedIndex:26977; }","duration":"174.543569ms","start":"2026-07-02T04:14:45.855316Z","end":"2026-07-02T04:14:46.029859Z","steps":["trace[2126038036] 'read index received' (duration: 136.012957ms)","trace[2126038036] 'applied index is now lower than readState.Index' (duration: 38.529122ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:46.030143Z","caller":"traceutil/trace.go:171","msg":"trace[1354290714] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"199.980995ms","start":"2026-07-02T04:14:45.830126Z","end":"2026-07-02T04:14:46.030107Z","steps":["trace[1354290714] 'process raft request' (duration: 198.788722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:46.030295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.959348ms","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-07-02T04:14:46.03039Z","caller":"traceutil/trace.go:171","msg":"trace[844225716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26121; }","duration":"175.071071ms","start":"2026-07-02T04:14:45.855296Z","end":"2026-07-02T04:14:46.030367Z","steps":["trace[844225716] 'agreement among raft nodes before linearized reading' (duration: 174.721034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:46.030529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.123254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:46.030617Z","caller":"traceutil/trace.go:171","msg":"trace[139915398] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:26121; }","duration":"122.276826ms","start":"2026-07-02T04:14:45.908316Z","end":"2026-07-02T04:14:46.030593Z","steps":["trace[139915398] 'agreement among raft nodes before linearized reading' (duration: 122.105123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:46.03063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.615076ms","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-07-02T04:14:46.030722Z","caller":"traceutil/trace.go:171","msg":"trace[63385230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26121; }","duration":"170.746758ms","start":"2026-07-02T04:14:45.859956Z","end":"2026-07-02T04:14:46.030703Z","steps":["trace[63385230] 'agreement among raft nodes before linearized reading' (duration: 170.490493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:46.031323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.325378ms","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-07-02T04:14:46.03149Z","caller":"traceutil/trace.go:171","msg":"trace[851068870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26121; }","duration":"171.552803ms","start":"2026-07-02T04:14:45.85991Z","end":"2026-07-02T04:14:46.031463Z","steps":["trace[851068870] 'agreement among raft nodes before linearized reading' (duration: 170.448862ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:46.266565Z","caller":"traceutil/trace.go:171","msg":"trace[1957019943] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"227.889274ms","start":"2026-07-02T04:14:46.038638Z","end":"2026-07-02T04:14:46.266527Z","steps":["trace[1957019943] 'process raft request' (duration: 148.623337ms)","trace[1957019943] 'compare' (duration: 78.700037ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:46.266586Z","caller":"traceutil/trace.go:171","msg":"trace[131347961] linearizableReadLoop","detail":"{readStateIndex:26982; appliedIndex:26980; }","duration":"100.100575ms","start":"2026-07-02T04:14:46.166451Z","end":"2026-07-02T04:14:46.266552Z","steps":["trace[131347961] 'read index received' (duration: 20.741245ms)","trace[131347961] 'applied index is now lower than readState.Index' (duration: 79.35806ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:46.266758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.280848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:46.266813Z","caller":"traceutil/trace.go:171","msg":"trace[565822388] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26124; }","duration":"100.39914ms","start":"2026-07-02T04:14:46.166395Z","end":"2026-07-02T04:14:46.266794Z","steps":["trace[565822388] 'agreement among raft nodes before linearized reading' (duration: 100.275558ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:46.266772Z","caller":"traceutil/trace.go:171","msg":"trace[129089092] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"225.770805ms","start":"2026-07-02T04:14:46.040957Z","end":"2026-07-02T04:14:46.266728Z","steps":["trace[129089092] 'process raft request' (duration: 225.462999ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:46.696969Z","caller":"traceutil/trace.go:171","msg":"trace[1934681789] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"125.177381ms","start":"2026-07-02T04:14:46.571768Z","end":"2026-07-02T04:14:46.696946Z","steps":["trace[1934681789] 'process raft request' (duration: 43.726882ms)","trace[1934681789] 'compare' (duration: 81.296586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:52.073781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.400945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:52.073942Z","caller":"traceutil/trace.go:171","msg":"trace[973825503] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"199.099706ms","start":"2026-07-02T04:14:51.874812Z","end":"2026-07-02T04:14:52.073911Z","steps":["trace[973825503] 'process raft request' (duration: 63.473437ms)","trace[973825503] 'compare' (duration: 135.272113ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:52.241462Z","caller":"traceutil/trace.go:171","msg":"trace[432106418] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"185.543699ms","start":"2026-07-02T04:14:52.055893Z","end":"2026-07-02T04:14:52.241437Z","steps":["trace[432106418] 'process raft request' (duration: 174.917327ms)","trace[432106418] 'compare' (duration: 10.219174ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:52.372561Z","caller":"traceutil/trace.go:171","msg":"trace[1263267442] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"125.827103ms","start":"2026-07-02T04:14:52.24668Z","end":"2026-07-02T04:14:52.372507Z","steps":["trace[1263267442] 'process raft request' (duration: 122.681572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:52.694036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.707671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:52.694279Z","caller":"traceutil/trace.go:171","msg":"trace[1915732084] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"189.157118ms","start":"2026-07-02T04:14:52.505082Z","end":"2026-07-02T04:14:52.694239Z","steps":["trace[1915732084] 'process raft request' (duration: 189.109497ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:52.694332Z","caller":"traceutil/trace.go:171","msg":"trace[576771244] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"316.203384ms","start":"2026-07-02T04:14:52.37809Z","end":"2026-07-02T04:14:52.694293Z","steps":["trace[576771244] 'process raft request' (duration: 139.114346ms)","trace[576771244] 'compare' (duration: 176.565148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:52.694496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:52.378076Z","time spent":"316.341456ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:52.694529Z","caller":"traceutil/trace.go:171","msg":"trace[1604314337] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"316.162344ms","start":"2026-07-02T04:14:52.378356Z","end":"2026-07-02T04:14:52.694519Z","steps":["trace[1604314337] 'process raft request' (duration: 315.789916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:52.694588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:52.378348Z","time spent":"316.206525ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:53.019743Z","caller":"traceutil/trace.go:171","msg":"trace[1298005441] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"293.087825ms","start":"2026-07-02T04:14:52.726612Z","end":"2026-07-02T04:14:53.0197Z","steps":["trace[1298005441] 'process raft request' (duration: 292.84647ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:53.317865Z","caller":"traceutil/trace.go:171","msg":"trace[191197687] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"589.38354ms","start":"2026-07-02T04:14:52.728439Z","end":"2026-07-02T04:14:53.317822Z","steps":["trace[191197687] 'process raft request' (duration: 589.020363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:53.318107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:52.728415Z","time spent":"589.568323ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:53.318626Z","caller":"traceutil/trace.go:171","msg":"trace[625479305] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"513.410335ms","start":"2026-07-02T04:14:52.805164Z","end":"2026-07-02T04:14:53.318574Z","steps":["trace[625479305] 'process raft request' (duration: 512.712102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:53.31882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:52.805135Z","time spent":"513.581877ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418457,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:53.457216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.991735ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T04:14:53.457537Z","caller":"traceutil/trace.go:171","msg":"trace[789995585] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"130.113365ms","start":"2026-07-02T04:14:53.327402Z","end":"2026-07-02T04:14:53.457516Z","steps":["trace[789995585] 'process raft request' (duration: 129.949462ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:53.457477Z","caller":"traceutil/trace.go:171","msg":"trace[1401875947] linearizableReadLoop","detail":"{readStateIndex:27032; appliedIndex:27029; }","duration":"402.474425ms","start":"2026-07-02T04:14:53.054936Z","end":"2026-07-02T04:14:53.45741Z","steps":["trace[1401875947] 'read index received' (duration: 262.424031ms)","trace[1401875947] 'applied index is now lower than readState.Index' (duration: 140.031124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:53.457608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.644129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:53.457696Z","caller":"traceutil/trace.go:171","msg":"trace[1058971581] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:26173; }","duration":"402.785801ms","start":"2026-07-02T04:14:53.054889Z","end":"2026-07-02T04:14:53.457675Z","steps":["trace[1058971581] 'agreement among raft nodes before linearized reading' (duration: 402.591057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:53.457748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:53.054868Z","time spent":"402.869293ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:14:53.457943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.163714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:53.458036Z","caller":"traceutil/trace.go:171","msg":"trace[920342719] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26173; }","duration":"253.351628ms","start":"2026-07-02T04:14:53.204662Z","end":"2026-07-02T04:14:53.458014Z","steps":["trace[920342719] 'agreement among raft nodes before linearized reading' (duration: 253.097104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:53.765455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.859426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:53.765601Z","caller":"traceutil/trace.go:171","msg":"trace[673840088] linearizableReadLoop","detail":"{readStateIndex:27034; appliedIndex:27033; }","duration":"185.393146ms","start":"2026-07-02T04:14:53.580184Z","end":"2026-07-02T04:14:53.765577Z","steps":["trace[673840088] 'read index received' (duration: 51.302736ms)","trace[673840088] 'applied index is now lower than readState.Index' (duration: 134.08848ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:53.765677Z","caller":"traceutil/trace.go:171","msg":"trace[1326587519] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"224.266296ms","start":"2026-07-02T04:14:53.541335Z","end":"2026-07-02T04:14:53.765601Z","steps":["trace[1326587519] 'process raft request' (duration: 90.123924ms)","trace[1326587519] 'compare' (duration: 133.595382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:53.765804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.566829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:53.765905Z","caller":"traceutil/trace.go:171","msg":"trace[1645759703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26174; }","duration":"185.760253ms","start":"2026-07-02T04:14:53.580123Z","end":"2026-07-02T04:14:53.765883Z","steps":["trace[1645759703] 'agreement among raft nodes before linearized reading' (duration: 185.539259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:53.81142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.419484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:53.811426Z","caller":"traceutil/trace.go:171","msg":"trace[1688622530] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"186.71102ms","start":"2026-07-02T04:14:53.624655Z","end":"2026-07-02T04:14:53.811366Z","steps":["trace[1688622530] 'process raft request' (duration: 186.490806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:53.811498Z","caller":"traceutil/trace.go:171","msg":"trace[1463109873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26175; }","duration":"137.570007ms","start":"2026-07-02T04:14:53.673909Z","end":"2026-07-02T04:14:53.811479Z","steps":["trace[1463109873] 'agreement among raft nodes before linearized reading' (duration: 137.393414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:54.050057Z","caller":"traceutil/trace.go:171","msg":"trace[907728089] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"113.113291ms","start":"2026-07-02T04:14:53.936898Z","end":"2026-07-02T04:14:54.050012Z","steps":["trace[907728089] 'process raft request' (duration: 112.914957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:54.064154Z","caller":"traceutil/trace.go:171","msg":"trace[1816564787] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"118.977233ms","start":"2026-07-02T04:14:53.945144Z","end":"2026-07-02T04:14:54.064121Z","steps":["trace[1816564787] 'process raft request' (duration: 118.721088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:54.90936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.23731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:54.909805Z","caller":"traceutil/trace.go:171","msg":"trace[390836607] linearizableReadLoop","detail":"{readStateIndex:27045; appliedIndex:27043; }","duration":"117.305602ms","start":"2026-07-02T04:14:54.792476Z","end":"2026-07-02T04:14:54.909781Z","steps":["trace[390836607] 'read index received' (duration: 30.041µs)","trace[390836607] 'applied index is now lower than readState.Index' (duration: 117.274331ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:54.909906Z","caller":"traceutil/trace.go:171","msg":"trace[1040464676] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"206.063388ms","start":"2026-07-02T04:14:54.703797Z","end":"2026-07-02T04:14:54.90986Z","steps":["trace[1040464676] 'process raft request' (duration: 27.177646ms)","trace[1040464676] 'compare' (duration: 177.929915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:54.91019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.710529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-02T04:14:54.910241Z","caller":"traceutil/trace.go:171","msg":"trace[1399196590] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26185; }","duration":"117.79031ms","start":"2026-07-02T04:14:54.792438Z","end":"2026-07-02T04:14:54.910228Z","steps":["trace[1399196590] 'agreement among raft nodes before linearized reading' (duration: 117.418143ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:54.910767Z","caller":"traceutil/trace.go:171","msg":"trace[1963136793] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"206.690492ms","start":"2026-07-02T04:14:54.70404Z","end":"2026-07-02T04:14:54.910731Z","steps":["trace[1963136793] 'process raft request' (duration: 205.450758ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:55.313142Z","caller":"traceutil/trace.go:171","msg":"trace[21938667] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"286.857175ms","start":"2026-07-02T04:14:55.026258Z","end":"2026-07-02T04:14:55.313116Z","steps":["trace[21938667] 'process raft request' (duration: 286.650981ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:55.478829Z","caller":"traceutil/trace.go:171","msg":"trace[405559415] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"151.818127ms","start":"2026-07-02T04:14:55.326978Z","end":"2026-07-02T04:14:55.478796Z","steps":["trace[405559415] 'process raft request' (duration: 60.022922ms)","trace[405559415] 'compare' (duration: 91.623212ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:55.702845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.929147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:55.703168Z","caller":"traceutil/trace.go:171","msg":"trace[1318855583] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"239.215551ms","start":"2026-07-02T04:14:55.463935Z","end":"2026-07-02T04:14:55.703151Z","steps":["trace[1318855583] 'process raft request' (duration: 124.871646ms)","trace[1318855583] 'compare' (duration: 113.514848ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:55.925638Z","caller":"traceutil/trace.go:171","msg":"trace[2105566619] linearizableReadLoop","detail":"{readStateIndex:27050; appliedIndex:27049; }","duration":"120.592213ms","start":"2026-07-02T04:14:55.805014Z","end":"2026-07-02T04:14:55.925606Z","steps":["trace[2105566619] 'read index received' (duration: 72.720363ms)","trace[2105566619] 'applied index is now lower than readState.Index' (duration: 47.87046ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:55.925798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.759257ms","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-07-02T04:14:55.925851Z","caller":"traceutil/trace.go:171","msg":"trace[2029706208] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26190; }","duration":"120.877529ms","start":"2026-07-02T04:14:55.804957Z","end":"2026-07-02T04:14:55.925834Z","steps":["trace[2029706208] 'agreement among raft nodes before linearized reading' (duration: 120.765957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:55.925847Z","caller":"traceutil/trace.go:171","msg":"trace[146766975] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"150.660956ms","start":"2026-07-02T04:14:55.775154Z","end":"2026-07-02T04:14:55.925815Z","steps":["trace[146766975] 'process raft request' (duration: 101.981221ms)","trace[146766975] 'compare' (duration: 48.262577ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:55.961428Z","caller":"traceutil/trace.go:171","msg":"trace[882905414] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"144.330055ms","start":"2026-07-02T04:14:55.817076Z","end":"2026-07-02T04:14:55.961407Z","steps":["trace[882905414] 'process raft request' (duration: 144.072711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:56.243792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.275203ms","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-07-02T04:14:56.243892Z","caller":"traceutil/trace.go:171","msg":"trace[1630890096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26191; }","duration":"175.412287ms","start":"2026-07-02T04:14:56.068458Z","end":"2026-07-02T04:14:56.243871Z","steps":["trace[1630890096] 'range keys from in-memory index tree' (duration: 175.08423ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:56.243853Z","caller":"traceutil/trace.go:171","msg":"trace[1746266950] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"153.678633ms","start":"2026-07-02T04:14:56.090143Z","end":"2026-07-02T04:14:56.243821Z","steps":["trace[1746266950] 'process raft request' (duration: 128.684738ms)","trace[1746266950] 'compare' (duration: 24.727009ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:56.332834Z","caller":"traceutil/trace.go:171","msg":"trace[1941023076] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"222.94582ms","start":"2026-07-02T04:14:56.10985Z","end":"2026-07-02T04:14:56.332796Z","steps":["trace[1941023076] 'process raft request' (duration: 222.689785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:56.590733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.371241ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:56.590937Z","caller":"traceutil/trace.go:171","msg":"trace[106387851] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26193; }","duration":"159.619036ms","start":"2026-07-02T04:14:56.43129Z","end":"2026-07-02T04:14:56.590909Z","steps":["trace[106387851] 'range keys from in-memory index tree' (duration: 159.333151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:56.59101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.630818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:56.591213Z","caller":"traceutil/trace.go:171","msg":"trace[390690575] linearizableReadLoop","detail":"{readStateIndex:27055; appliedIndex:27053; }","duration":"202.984091ms","start":"2026-07-02T04:14:56.388208Z","end":"2026-07-02T04:14:56.591192Z","steps":["trace[390690575] 'read index received' (duration: 76.097367ms)","trace[390690575] 'applied index is now lower than readState.Index' (duration: 126.884994ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:56.591242Z","caller":"traceutil/trace.go:171","msg":"trace[965023886] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"342.431293ms","start":"2026-07-02T04:14:56.248764Z","end":"2026-07-02T04:14:56.591195Z","steps":["trace[965023886] 'process raft request' (duration: 215.512819ms)","trace[965023886] 'compare' (duration: 126.490645ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:56.591387Z","caller":"traceutil/trace.go:171","msg":"trace[1526673881] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"326.630672ms","start":"2026-07-02T04:14:56.264729Z","end":"2026-07-02T04:14:56.591359Z","steps":["trace[1526673881] 'process raft request' (duration: 326.389667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:56.591468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:56.248711Z","time spent":"342.626497ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:56.591561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:56.264709Z","time spent":"326.746105ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:56.591362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.160534ms","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-07-02T04:14:56.591976Z","caller":"traceutil/trace.go:171","msg":"trace[687684556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26195; }","duration":"203.846748ms","start":"2026-07-02T04:14:56.388113Z","end":"2026-07-02T04:14:56.59196Z","steps":["trace[687684556] 'agreement among raft nodes before linearized reading' (duration: 203.133344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:56.871088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.651779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:56.871278Z","caller":"traceutil/trace.go:171","msg":"trace[225480263] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"146.350643ms","start":"2026-07-02T04:14:56.724908Z","end":"2026-07-02T04:14:56.871259Z","steps":["trace[225480263] 'process raft request' (duration: 146.292122ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:56.87131Z","caller":"traceutil/trace.go:171","msg":"trace[1868115962] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"275.101824ms","start":"2026-07-02T04:14:56.596185Z","end":"2026-07-02T04:14:56.871287Z","steps":["trace[1868115962] 'process raft request' (duration: 108.168248ms)","trace[1868115962] 'compare' (duration: 166.526537ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:57.038279Z","caller":"traceutil/trace.go:171","msg":"trace[895807261] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"118.119517ms","start":"2026-07-02T04:14:56.920124Z","end":"2026-07-02T04:14:57.038244Z","steps":["trace[895807261] 'process raft request' (duration: 53.480667ms)","trace[895807261] 'compare' (duration: 64.476917ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:57.356771Z","caller":"traceutil/trace.go:171","msg":"trace[918849435] linearizableReadLoop","detail":"{readStateIndex:27061; appliedIndex:27060; }","duration":"110.764657ms","start":"2026-07-02T04:14:57.245977Z","end":"2026-07-02T04:14:57.356742Z","steps":["trace[918849435] 'read index received' (duration: 110.626164ms)","trace[918849435] 'applied index is now lower than readState.Index' (duration: 137.323µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:57.356955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.944881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:57.356994Z","caller":"traceutil/trace.go:171","msg":"trace[990152028] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26201; }","duration":"111.058492ms","start":"2026-07-02T04:14:57.24592Z","end":"2026-07-02T04:14:57.356979Z","steps":["trace[990152028] 'agreement among raft nodes before linearized reading' (duration: 110.92685ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.357231Z","caller":"traceutil/trace.go:171","msg":"trace[750959308] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"111.385648ms","start":"2026-07-02T04:14:57.245811Z","end":"2026-07-02T04:14:57.357197Z","steps":["trace[750959308] 'process raft request' (duration: 110.705536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.502724Z","caller":"traceutil/trace.go:171","msg":"trace[1407324118] linearizableReadLoop","detail":"{readStateIndex:27063; appliedIndex:27061; }","duration":"129.112416ms","start":"2026-07-02T04:14:57.373591Z","end":"2026-07-02T04:14:57.502704Z","steps":["trace[1407324118] 'read index received' (duration: 66.468695ms)","trace[1407324118] 'applied index is now lower than readState.Index' (duration: 62.642981ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:57.502743Z","caller":"traceutil/trace.go:171","msg":"trace[519848237] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"185.381156ms","start":"2026-07-02T04:14:57.317333Z","end":"2026-07-02T04:14:57.502714Z","steps":["trace[519848237] 'process raft request' (duration: 185.263264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:57.502942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.34128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-8w7hx\" ","response":"range_response_count:1 size:3396"} {"level":"info","ts":"2026-07-02T04:14:57.502976Z","caller":"traceutil/trace.go:171","msg":"trace[1831134473] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-8w7hx; range_end:; response_count:1; response_revision:26203; }","duration":"129.427812ms","start":"2026-07-02T04:14:57.373539Z","end":"2026-07-02T04:14:57.502967Z","steps":["trace[1831134473] 'agreement among raft nodes before linearized reading' (duration: 129.283009ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.502809Z","caller":"traceutil/trace.go:171","msg":"trace[683952821] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"187.411365ms","start":"2026-07-02T04:14:57.315323Z","end":"2026-07-02T04:14:57.502734Z","steps":["trace[683952821] 'process raft request' (duration: 124.810164ms)","trace[683952821] 'compare' (duration: 62.261284ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:57.778988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.29644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:57.779122Z","caller":"traceutil/trace.go:171","msg":"trace[87826839] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"270.880163ms","start":"2026-07-02T04:14:57.508221Z","end":"2026-07-02T04:14:57.779101Z","steps":["trace[87826839] 'process raft request' (duration: 101.378658ms)","trace[87826839] 'compare' (duration: 169.160937ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:57.828063Z","caller":"traceutil/trace.go:171","msg":"trace[232734457] linearizableReadLoop","detail":"{readStateIndex:27065; appliedIndex:27063; }","duration":"283.333929ms","start":"2026-07-02T04:14:57.544683Z","end":"2026-07-02T04:14:57.828017Z","steps":["trace[232734457] 'read index received' (duration: 64.932225ms)","trace[232734457] 'applied index is now lower than readState.Index' (duration: 218.400634ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:57.828078Z","caller":"traceutil/trace.go:171","msg":"trace[1636341062] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"317.005739ms","start":"2026-07-02T04:14:57.51104Z","end":"2026-07-02T04:14:57.828046Z","steps":["trace[1636341062] 'process raft request' (duration: 316.872656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:57.828348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:57.511022Z","time spent":"317.133712ms","remote":"127.0.0.1:52660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3701,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:57.828681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.001601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:14:57.828761Z","caller":"traceutil/trace.go:171","msg":"trace[1981110183] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26205; }","duration":"284.148194ms","start":"2026-07-02T04:14:57.544599Z","end":"2026-07-02T04:14:57.828747Z","steps":["trace[1981110183] 'agreement among raft nodes before linearized reading' (duration: 283.516662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:57.860066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.632111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:57.860116Z","caller":"traceutil/trace.go:171","msg":"trace[1682046779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26206; }","duration":"184.729012ms","start":"2026-07-02T04:14:57.675376Z","end":"2026-07-02T04:14:57.860105Z","steps":["trace[1682046779] 'agreement among raft nodes before linearized reading' (duration: 184.643541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:57.860156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.943494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-02T04:14:57.860202Z","caller":"traceutil/trace.go:171","msg":"trace[1885834678] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:26206; }","duration":"270.014376ms","start":"2026-07-02T04:14:57.590175Z","end":"2026-07-02T04:14:57.860189Z","steps":["trace[1885834678] 'agreement among raft nodes before linearized reading' (duration: 269.867452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:57.860323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.035515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:57.860768Z","caller":"traceutil/trace.go:171","msg":"trace[1321342289] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:26206; }","duration":"291.231369ms","start":"2026-07-02T04:14:57.569181Z","end":"2026-07-02T04:14:57.860413Z","steps":["trace[1321342289] 'agreement among raft nodes before linearized reading' (duration: 290.734069ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.859982Z","caller":"traceutil/trace.go:171","msg":"trace[2041062143] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"151.276918ms","start":"2026-07-02T04:14:57.708687Z","end":"2026-07-02T04:14:57.859964Z","steps":["trace[2041062143] 'process raft request' (duration: 151.006293ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.978013Z","caller":"traceutil/trace.go:171","msg":"trace[701456162] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"140.460003ms","start":"2026-07-02T04:14:57.837533Z","end":"2026-07-02T04:14:57.977993Z","steps":["trace[701456162] 'process raft request' (duration: 140.400391ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.978162Z","caller":"traceutil/trace.go:171","msg":"trace[1929377946] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"141.635955ms","start":"2026-07-02T04:14:57.836493Z","end":"2026-07-02T04:14:57.978129Z","steps":["trace[1929377946] 'process raft request' (duration: 141.311058ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:57.982406Z","caller":"traceutil/trace.go:171","msg":"trace[1165100203] linearizableReadLoop","detail":"{readStateIndex:27069; appliedIndex:27066; }","duration":"119.86408ms","start":"2026-07-02T04:14:57.862526Z","end":"2026-07-02T04:14:57.98239Z","steps":["trace[1165100203] 'read index received' (duration: 115.346594ms)","trace[1165100203] 'applied index is now lower than readState.Index' (duration: 4.516756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:57.982583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.035534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:57.982609Z","caller":"traceutil/trace.go:171","msg":"trace[1195139667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26210; }","duration":"120.118025ms","start":"2026-07-02T04:14:57.862484Z","end":"2026-07-02T04:14:57.982602Z","steps":["trace[1195139667] 'agreement among raft nodes before linearized reading' (duration: 119.975292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:18:24.990266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25307} {"level":"info","ts":"2026-07-02T04:18:25.13787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25307,"took":"143.484009ms","hash":2173193012,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":38727680,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-07-02T04:18:25.137934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2173193012,"revision":25307,"compact-revision":22034} {"level":"info","ts":"2026-07-02T04:19:55.252851Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T04:19:55.261376Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T04:19:55.261533Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T04:23:24.996427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28283} {"level":"info","ts":"2026-07-02T04:23:25.149403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28283,"took":"148.411396ms","hash":1367621600,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":32337920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T04:23:25.149474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1367621600,"revision":28283,"compact-revision":25307} {"level":"info","ts":"2026-07-02T04:28:25.005977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30639} {"level":"info","ts":"2026-07-02T04:28:25.145749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30639,"took":"135.609161ms","hash":186649510,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":30670848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T04:28:25.145845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":186649510,"revision":30639,"compact-revision":28283} {"level":"info","ts":"2026-07-02T04:33:25.012139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32894} {"level":"info","ts":"2026-07-02T04:33:25.170115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32894,"took":"153.312177ms","hash":1298177976,"current-db-size-bytes":60092416,"current-db-size":"60 MB","current-db-size-in-use-bytes":30412800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T04:33:25.170212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1298177976,"revision":32894,"compact-revision":30639} {"level":"info","ts":"2026-07-02T04:38:25.019141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35130} {"level":"info","ts":"2026-07-02T04:38:25.183281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35130,"took":"156.536607ms","hash":4069378293,"current-db-size-bytes":60092416,"current-db-size":"60 MB","current-db-size-in-use-bytes":32137216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T04:38:25.183354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4069378293,"revision":35130,"compact-revision":32894} {"level":"info","ts":"2026-07-02T04:41:20.530953Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T04:41:20.535746Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T04:41:20.535866Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-02T04:43:25.026275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37424} {"level":"info","ts":"2026-07-02T04:43:25.15807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37424,"took":"126.840642ms","hash":1842863435,"current-db-size-bytes":60092416,"current-db-size":"60 MB","current-db-size-in-use-bytes":32043008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T04:43:25.158139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1842863435,"revision":37424,"compact-revision":35130}