{"level":"warn","ts":"2026-09-19T06:09:14.207151Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:09:14.207463Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.156: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-09-19T06:09:14.207587Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:09:14.207612Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-19T06:09:14.207642Z","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-09-19T06:09:14.208912Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-09-19T06:09:14.209141Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.156: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-09-19T06:09:14.214612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.208105ms"} {"level":"info","ts":"2026-09-19T06:09:14.221715Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-09-19T06:09:14.221795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T06:09:14.221816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-09-19T06:09:14.22183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T06:09:14.221839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-09-19T06:09:14.221875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-09-19T06:09:14.233672Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T06:09:14.235445Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T06:09:14.237122Z","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-09-19T06:09:14.240485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T06:09:14.240676Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T06:09:14.240989Z","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-09-19T06:09:14.241206Z","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-09-19T06:09:14.24126Z","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-09-19T06:09:14.243253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-09-19T06:09:14.244534Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-19T06:09:14.247937Z","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-09-19T06:09:14.248103Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-19T06:09:14.248216Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-19T06:09:14.249011Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T06:09:14.248945Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T06:09:14.823757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T06:09:14.82395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T06:09:14.824039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-09-19T06:09:14.824102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-09-19T06:09:14.824136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-19T06:09:14.824199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-09-19T06:09:14.824245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-19T06:09:14.825702Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T06:09:14.825732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:09:14.82574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:09:14.826301Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T06:09:14.826404Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T06:09:14.827173Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:09:14.82733Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:09:14.827391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:09:14.829025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T06:09:14.829196Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:09:14.842629Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-09-19T06:09:30.761671Z","caller":"traceutil/trace.go:171","msg":"trace[990137416] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"276.736272ms","start":"2026-09-19T06:09:30.484898Z","end":"2026-09-19T06:09:30.761634Z","steps":["trace[990137416] 'process raft request' (duration: 276.486677ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:30.762605Z","caller":"traceutil/trace.go:171","msg":"trace[736717517] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"276.568579ms","start":"2026-09-19T06:09:30.486005Z","end":"2026-09-19T06:09:30.762573Z","steps":["trace[736717517] 'process raft request' (duration: 276.253872ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:10:03.830599Z","caller":"traceutil/trace.go:171","msg":"trace[2045433038] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"197.172837ms","start":"2026-09-19T06:10:03.633401Z","end":"2026-09-19T06:10:03.830574Z","steps":["trace[2045433038] 'process raft request' (duration: 196.985701ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:10:03.942128Z","caller":"traceutil/trace.go:171","msg":"trace[172913076] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"170.11626ms","start":"2026-09-19T06:10:03.771963Z","end":"2026-09-19T06:10:03.942079Z","steps":["trace[172913076] 'read index received' (duration: 58.395784ms)","trace[172913076] 'applied index is now lower than readState.Index' (duration: 111.719566ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:10:03.94386Z","caller":"traceutil/trace.go:171","msg":"trace[713168239] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"264.930147ms","start":"2026-09-19T06:10:03.678827Z","end":"2026-09-19T06:10:03.943757Z","steps":["trace[713168239] 'process raft request' (duration: 262.399886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:10:03.943825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.796431ms","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-09-19T06:10:03.947132Z","caller":"traceutil/trace.go:171","msg":"trace[532464912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"175.07911ms","start":"2026-09-19T06:10:03.771921Z","end":"2026-09-19T06:10:03.947Z","steps":["trace[532464912] 'agreement among raft nodes before linearized reading' (duration: 170.291104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:10:04.539707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.437658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:10:04.540092Z","caller":"traceutil/trace.go:171","msg":"trace[964372483] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"512.138755ms","start":"2026-09-19T06:10:04.027921Z","end":"2026-09-19T06:10:04.540059Z","steps":["trace[964372483] 'process raft request' (duration: 409.868358ms)","trace[964372483] 'compare' (duration: 101.283073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:10:04.540235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:10:04.027878Z","time spent":"512.287249ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:10:19.847618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.938011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pxzbq\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-19T06:10:19.84772Z","caller":"traceutil/trace.go:171","msg":"trace[161657491] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pxzbq; range_end:; response_count:1; response_revision:812; }","duration":"131.085285ms","start":"2026-09-19T06:10:19.716614Z","end":"2026-09-19T06:10:19.847699Z","steps":["trace[161657491] 'range keys from in-memory index tree' (duration: 130.737967ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:10:49.334591Z","caller":"traceutil/trace.go:171","msg":"trace[1776902165] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"143.935927ms","start":"2026-09-19T06:10:49.190631Z","end":"2026-09-19T06:10:49.334567Z","steps":["trace[1776902165] 'process raft request' (duration: 143.762322ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:36.103939Z","caller":"traceutil/trace.go:171","msg":"trace[1413297445] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"112.918245ms","start":"2026-09-19T06:11:35.990988Z","end":"2026-09-19T06:11:36.103907Z","steps":["trace[1413297445] 'read index received' (duration: 100.382341ms)","trace[1413297445] 'applied index is now lower than readState.Index' (duration: 12.534134ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:11:36.104024Z","caller":"traceutil/trace.go:171","msg":"trace[644469160] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"234.445919ms","start":"2026-09-19T06:11:35.869534Z","end":"2026-09-19T06:11:36.10398Z","steps":["trace[644469160] 'process raft request' (duration: 221.808802ms)","trace[644469160] 'compare' (duration: 12.33508ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:11:36.104132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.13123ms","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-09-19T06:11:36.104155Z","caller":"traceutil/trace.go:171","msg":"trace[953151892] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"113.207412ms","start":"2026-09-19T06:11:35.990939Z","end":"2026-09-19T06:11:36.104147Z","steps":["trace[953151892] 'agreement among raft nodes before linearized reading' (duration: 113.067038ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:36.280382Z","caller":"traceutil/trace.go:171","msg":"trace[848724386] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"154.858261ms","start":"2026-09-19T06:11:36.125466Z","end":"2026-09-19T06:11:36.280324Z","steps":["trace[848724386] 'process raft request' (duration: 90.230126ms)","trace[848724386] 'compare' (duration: 64.133672ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:11:36.678054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.344235ms","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-09-19T06:11:36.678142Z","caller":"traceutil/trace.go:171","msg":"trace[894113487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"255.505989ms","start":"2026-09-19T06:11:36.422616Z","end":"2026-09-19T06:11:36.678122Z","steps":["trace[894113487] 'range keys from in-memory index tree' (duration: 255.13346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:36.678205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.368771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T06:11:36.678319Z","caller":"traceutil/trace.go:171","msg":"trace[521803433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1890; }","duration":"273.511534ms","start":"2026-09-19T06:11:36.404764Z","end":"2026-09-19T06:11:36.678276Z","steps":["trace[521803433] 'range keys from in-memory index tree' (duration: 273.137746ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:42.538674Z","caller":"traceutil/trace.go:171","msg":"trace[221546455] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"161.43355ms","start":"2026-09-19T06:11:42.377216Z","end":"2026-09-19T06:11:42.538649Z","steps":["trace[221546455] 'process raft request' (duration: 161.229596ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:52.713303Z","caller":"traceutil/trace.go:171","msg":"trace[1058817610] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"142.273867ms","start":"2026-09-19T06:11:52.571008Z","end":"2026-09-19T06:11:52.713282Z","steps":["trace[1058817610] 'process raft request' (duration: 142.160234ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:52.966885Z","caller":"traceutil/trace.go:171","msg":"trace[1288177574] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"184.671332ms","start":"2026-09-19T06:11:52.782188Z","end":"2026-09-19T06:11:52.96686Z","steps":["trace[1288177574] 'process raft request' (duration: 184.523539ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:52.968594Z","caller":"traceutil/trace.go:171","msg":"trace[503745333] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"184.610902ms","start":"2026-09-19T06:11:52.783947Z","end":"2026-09-19T06:11:52.968558Z","steps":["trace[503745333] 'process raft request' (duration: 184.290454ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:12:25.747516Z","caller":"traceutil/trace.go:171","msg":"trace[1409937502] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"150.953894ms","start":"2026-09-19T06:12:25.596536Z","end":"2026-09-19T06:12:25.74749Z","steps":["trace[1409937502] 'process raft request' (duration: 150.748239ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:12:26.474868Z","caller":"traceutil/trace.go:171","msg":"trace[358892858] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"132.563118ms","start":"2026-09-19T06:12:26.342278Z","end":"2026-09-19T06:12:26.474841Z","steps":["trace[358892858] 'process raft request' (duration: 132.421885ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:12:32.262312Z","caller":"traceutil/trace.go:171","msg":"trace[1314916107] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"304.002388ms","start":"2026-09-19T06:12:31.958281Z","end":"2026-09-19T06:12:32.262283Z","steps":["trace[1314916107] 'process raft request' (duration: 303.870435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:12:32.262686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:12:31.958264Z","time spent":"304.123381ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:12:32.583503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.672378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:12:32.583681Z","caller":"traceutil/trace.go:171","msg":"trace[663906934] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2325; }","duration":"394.98043ms","start":"2026-09-19T06:12:32.188674Z","end":"2026-09-19T06:12:32.583654Z","steps":["trace[663906934] 'read index received' (duration: 73.55575ms)","trace[663906934] 'applied index is now lower than readState.Index' (duration: 321.42357ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:12:32.583688Z","caller":"traceutil/trace.go:171","msg":"trace[542258150] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"623.935692ms","start":"2026-09-19T06:12:31.959717Z","end":"2026-09-19T06:12:32.583653Z","steps":["trace[542258150] 'process raft request' (duration: 316.008478ms)","trace[542258150] 'compare' (duration: 307.386141ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:12:32.583744Z","caller":"traceutil/trace.go:171","msg":"trace[947811852] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"481.871364ms","start":"2026-09-19T06:12:32.101862Z","end":"2026-09-19T06:12:32.583734Z","steps":["trace[947811852] 'process raft request' (duration: 481.72504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:12:32.583823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:12:32.101841Z","time spent":"481.925204ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:12:32.58385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.166195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:12:32.583891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:12:31.959697Z","time spent":"624.054574ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:12:32.583906Z","caller":"traceutil/trace.go:171","msg":"trace[1583574541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2242; }","duration":"395.247426ms","start":"2026-09-19T06:12:32.188642Z","end":"2026-09-19T06:12:32.583889Z","steps":["trace[1583574541] 'agreement among raft nodes before linearized reading' (duration: 395.134984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:12:32.583957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:12:32.188627Z","time spent":"395.318229ms","remote":"127.0.0.1:41422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T06:12:32.61545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.584001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:12:32.615554Z","caller":"traceutil/trace.go:171","msg":"trace[1211649188] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2243; }","duration":"330.765686ms","start":"2026-09-19T06:12:32.284768Z","end":"2026-09-19T06:12:32.615534Z","steps":["trace[1211649188] 'agreement among raft nodes before linearized reading' (duration: 330.544811ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:12:32.615543Z","caller":"traceutil/trace.go:171","msg":"trace[1997635883] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"107.391339ms","start":"2026-09-19T06:12:32.508114Z","end":"2026-09-19T06:12:32.615505Z","steps":["trace[1997635883] 'process raft request' (duration: 107.068372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:12:32.615599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:12:32.284751Z","time spent":"330.836487ms","remote":"127.0.0.1:41550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:12:32.616005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.943018ms","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-09-19T06:12:32.616112Z","caller":"traceutil/trace.go:171","msg":"trace[708359725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2243; }","duration":"114.082241ms","start":"2026-09-19T06:12:32.502005Z","end":"2026-09-19T06:12:32.616088Z","steps":["trace[708359725] 'agreement among raft nodes before linearized reading' (duration: 113.383364ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:12:32.854232Z","caller":"traceutil/trace.go:171","msg":"trace[1003418203] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"233.45272ms","start":"2026-09-19T06:12:32.620747Z","end":"2026-09-19T06:12:32.8542Z","steps":["trace[1003418203] 'process raft request' (duration: 165.014524ms)","trace[1003418203] 'compare' (duration: 68.243381ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:00.144231Z","caller":"traceutil/trace.go:171","msg":"trace[1055746537] transaction","detail":"{read_only:false; response_revision:2915; number_of_response:1; }","duration":"118.360425ms","start":"2026-09-19T06:14:00.025838Z","end":"2026-09-19T06:14:00.144199Z","steps":["trace[1055746537] 'process raft request' (duration: 118.12868ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:16:30.764136Z","caller":"traceutil/trace.go:171","msg":"trace[21852117] linearizableReadLoop","detail":"{readStateIndex:3977; appliedIndex:3976; }","duration":"111.157633ms","start":"2026-09-19T06:16:30.652951Z","end":"2026-09-19T06:16:30.764108Z","steps":["trace[21852117] 'read index received' (duration: 89.477027ms)","trace[21852117] 'applied index is now lower than readState.Index' (duration: 21.679106ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:16:30.764281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.308986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secrettemplates.secretgen.carvel.dev\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:16:30.764315Z","caller":"traceutil/trace.go:171","msg":"trace[231963240] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secrettemplates.secretgen.carvel.dev; range_end:; response_count:0; response_revision:3829; }","duration":"111.39187ms","start":"2026-09-19T06:16:30.652911Z","end":"2026-09-19T06:16:30.764303Z","steps":["trace[231963240] 'agreement among raft nodes before linearized reading' (duration: 111.313428ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:16:30.764387Z","caller":"traceutil/trace.go:171","msg":"trace[1701807486] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"138.425743ms","start":"2026-09-19T06:16:30.625909Z","end":"2026-09-19T06:16:30.764334Z","steps":["trace[1701807486] 'process raft request' (duration: 116.494621ms)","trace[1701807486] 'compare' (duration: 21.486571ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:16:30.765991Z","caller":"traceutil/trace.go:171","msg":"trace[22021296] transaction","detail":"{read_only:false; response_revision:3830; number_of_response:1; }","duration":"112.913756ms","start":"2026-09-19T06:16:30.653056Z","end":"2026-09-19T06:16:30.765969Z","steps":["trace[22021296] 'process raft request' (duration: 112.812893ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:17:02.055672Z","caller":"traceutil/trace.go:171","msg":"trace[1886929917] transaction","detail":"{read_only:false; response_revision:4071; number_of_response:1; }","duration":"153.313145ms","start":"2026-09-19T06:17:01.902289Z","end":"2026-09-19T06:17:02.055602Z","steps":["trace[1886929917] 'process raft request' (duration: 125.775148ms)","trace[1886929917] 'compare' (duration: 27.404884ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:18:31.532736Z","caller":"traceutil/trace.go:171","msg":"trace[563913094] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"121.440814ms","start":"2026-09-19T06:18:31.411262Z","end":"2026-09-19T06:18:31.532703Z","steps":["trace[563913094] 'process raft request' (duration: 121.139887ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:32.382784Z","caller":"traceutil/trace.go:171","msg":"trace[715093385] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"173.001024ms","start":"2026-09-19T06:18:32.209754Z","end":"2026-09-19T06:18:32.382755Z","steps":["trace[715093385] 'process raft request' (duration: 168.992036ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:32.383436Z","caller":"traceutil/trace.go:171","msg":"trace[1254199896] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5355; }","duration":"111.870162ms","start":"2026-09-19T06:18:32.271534Z","end":"2026-09-19T06:18:32.383404Z","steps":["trace[1254199896] 'read index received' (duration: 107.311521ms)","trace[1254199896] 'applied index is now lower than readState.Index' (duration: 4.556241ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:18:32.38351Z","caller":"traceutil/trace.go:171","msg":"trace[1432610161] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"164.463996ms","start":"2026-09-19T06:18:32.21903Z","end":"2026-09-19T06:18:32.383494Z","steps":["trace[1432610161] 'process raft request' (duration: 164.035486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:32.383717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.159419ms","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-09-19T06:18:32.383767Z","caller":"traceutil/trace.go:171","msg":"trace[1567938973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5158; }","duration":"112.262942ms","start":"2026-09-19T06:18:32.271489Z","end":"2026-09-19T06:18:32.383752Z","steps":["trace[1567938973] 'agreement among raft nodes before linearized reading' (duration: 112.057876ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:35.571299Z","caller":"traceutil/trace.go:171","msg":"trace[225748638] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"326.365381ms","start":"2026-09-19T06:18:35.244909Z","end":"2026-09-19T06:18:35.571274Z","steps":["trace[225748638] 'process raft request' (duration: 326.170087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:35.571474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:35.244868Z","time spent":"326.505184ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:18:50.703899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.520779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:18:50.704135Z","caller":"traceutil/trace.go:171","msg":"trace[1825155400] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"272.594907ms","start":"2026-09-19T06:18:50.431502Z","end":"2026-09-19T06:18:50.704097Z","steps":["trace[1825155400] 'process raft request' (duration: 69.771671ms)","trace[1825155400] 'compare' (duration: 202.16762ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:18:50.704234Z","caller":"traceutil/trace.go:171","msg":"trace[1183082696] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"272.69756ms","start":"2026-09-19T06:18:50.431518Z","end":"2026-09-19T06:18:50.704216Z","steps":["trace[1183082696] 'process raft request' (duration: 272.487135ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:50.704273Z","caller":"traceutil/trace.go:171","msg":"trace[1608893186] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"213.541255ms","start":"2026-09-19T06:18:50.490712Z","end":"2026-09-19T06:18:50.704253Z","steps":["trace[1608893186] 'process raft request' (duration: 213.473274ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:50.70427Z","caller":"traceutil/trace.go:171","msg":"trace[154354758] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"271.952991ms","start":"2026-09-19T06:18:50.432293Z","end":"2026-09-19T06:18:50.704246Z","steps":["trace[154354758] 'process raft request' (duration: 271.810928ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:50.70438Z","caller":"traceutil/trace.go:171","msg":"trace[693000283] linearizableReadLoop","detail":"{readStateIndex:5768; appliedIndex:5765; }","duration":"234.822671ms","start":"2026-09-19T06:18:50.469529Z","end":"2026-09-19T06:18:50.704352Z","steps":["trace[693000283] 'read index received' (duration: 14.820639ms)","trace[693000283] 'applied index is now lower than readState.Index' (duration: 219.966401ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:18:50.70472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.16683ms","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-09-19T06:18:50.704804Z","caller":"traceutil/trace.go:171","msg":"trace[1415261185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5557; }","duration":"235.285193ms","start":"2026-09-19T06:18:50.469496Z","end":"2026-09-19T06:18:50.704781Z","steps":["trace[1415261185] 'agreement among raft nodes before linearized reading' (duration: 235.045957ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:50.894225Z","caller":"traceutil/trace.go:171","msg":"trace[635471375] linearizableReadLoop","detail":"{readStateIndex:5770; appliedIndex:5769; }","duration":"189.676287ms","start":"2026-09-19T06:18:50.704526Z","end":"2026-09-19T06:18:50.894203Z","steps":["trace[635471375] 'read index received' (duration: 189.580875ms)","trace[635471375] 'applied index is now lower than readState.Index' (duration: 94.492µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:18:50.894338Z","caller":"traceutil/trace.go:171","msg":"trace[1526810975] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"330.088202ms","start":"2026-09-19T06:18:50.564217Z","end":"2026-09-19T06:18:50.894306Z","steps":["trace[1526810975] 'process raft request' (duration: 329.829746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:50.894424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.356865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-19T06:18:50.894473Z","caller":"traceutil/trace.go:171","msg":"trace[1464589019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5558; }","duration":"221.443287ms","start":"2026-09-19T06:18:50.673001Z","end":"2026-09-19T06:18:50.894444Z","steps":["trace[1464589019] 'agreement among raft nodes before linearized reading' (duration: 221.309054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:50.894501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:50.564187Z","time spent":"330.215634ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:18:51.29206Z","caller":"traceutil/trace.go:171","msg":"trace[1908683774] linearizableReadLoop","detail":"{readStateIndex:5771; appliedIndex:5770; }","duration":"397.762121ms","start":"2026-09-19T06:18:50.894282Z","end":"2026-09-19T06:18:51.292044Z","steps":["trace[1908683774] 'read index received' (duration: 397.243448ms)","trace[1908683774] 'applied index is now lower than readState.Index' (duration: 517.593µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:18:51.292141Z","caller":"traceutil/trace.go:171","msg":"trace[2040684141] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"582.99864ms","start":"2026-09-19T06:18:50.709134Z","end":"2026-09-19T06:18:51.292132Z","steps":["trace[2040684141] 'process raft request' (duration: 582.312923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:51.292383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.739623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:18:51.292415Z","caller":"traceutil/trace.go:171","msg":"trace[1876866977] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5560; }","duration":"348.806043ms","start":"2026-09-19T06:18:50.943601Z","end":"2026-09-19T06:18:51.292407Z","steps":["trace[1876866977] 'agreement among raft nodes before linearized reading' (duration: 348.741782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:51.292434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:50.943585Z","time spent":"348.843015ms","remote":"127.0.0.1:47320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:18:51.292421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.20278ms","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-09-19T06:18:51.292524Z","caller":"traceutil/trace.go:171","msg":"trace[2021797866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5559; }","duration":"471.338944ms","start":"2026-09-19T06:18:50.821162Z","end":"2026-09-19T06:18:51.292501Z","steps":["trace[2021797866] 'agreement among raft nodes before linearized reading' (duration: 471.116778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:51.292578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:50.821143Z","time spent":"471.424667ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T06:18:51.29263Z","caller":"traceutil/trace.go:171","msg":"trace[1016921782] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"393.496028ms","start":"2026-09-19T06:18:50.899126Z","end":"2026-09-19T06:18:51.292622Z","steps":["trace[1016921782] 'process raft request' (duration: 393.17432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:51.292668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:50.89911Z","time spent":"393.536609ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:18:51.292841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.776882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T06:18:51.292863Z","caller":"traceutil/trace.go:171","msg":"trace[1857391513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5560; }","duration":"199.850264ms","start":"2026-09-19T06:18:51.093007Z","end":"2026-09-19T06:18:51.292857Z","steps":["trace[1857391513] 'agreement among raft nodes before linearized reading' (duration: 199.779232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:51.292919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.255034ms","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-09-19T06:18:51.292933Z","caller":"traceutil/trace.go:171","msg":"trace[1049974918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5560; }","duration":"212.314076ms","start":"2026-09-19T06:18:51.080615Z","end":"2026-09-19T06:18:51.292929Z","steps":["trace[1049974918] 'agreement among raft nodes before linearized reading' (duration: 212.270455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:51.292217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:50.709113Z","time spent":"583.043332ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:18:59.527052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.348144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:18:59.527165Z","caller":"traceutil/trace.go:171","msg":"trace[1050767370] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5758; }","duration":"105.520977ms","start":"2026-09-19T06:18:59.421621Z","end":"2026-09-19T06:18:59.527142Z","steps":["trace[1050767370] 'count revisions from in-memory index tree' (duration: 105.218971ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:59.691109Z","caller":"traceutil/trace.go:171","msg":"trace[1633888843] transaction","detail":"{read_only:false; response_revision:5759; number_of_response:1; }","duration":"156.554754ms","start":"2026-09-19T06:18:59.534531Z","end":"2026-09-19T06:18:59.691085Z","steps":["trace[1633888843] 'process raft request' (duration: 156.405431ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:19:14.865663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2994} {"level":"info","ts":"2026-09-19T06:19:14.941174Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2994,"took":"73.957362ms","hash":1204336715,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-19T06:19:14.941412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1204336715,"revision":2994,"compact-revision":-1} {"level":"info","ts":"2026-09-19T06:19:53.03234Z","caller":"traceutil/trace.go:171","msg":"trace[1241917605] transaction","detail":"{read_only:false; response_revision:6235; number_of_response:1; }","duration":"106.627675ms","start":"2026-09-19T06:19:52.925647Z","end":"2026-09-19T06:19:53.032274Z","steps":["trace[1241917605] 'process raft request' (duration: 57.113195ms)","trace[1241917605] 'compare' (duration: 48.116786ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:24:14.873679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5908} {"level":"info","ts":"2026-09-19T06:24:14.970266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5908,"took":"93.26128ms","hash":136605279,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":26390528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-19T06:24:14.970339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":136605279,"revision":5908,"compact-revision":2994} {"level":"warn","ts":"2026-09-19T06:25:26.738059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.850262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6x5kl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-19T06:25:26.738146Z","caller":"traceutil/trace.go:171","msg":"trace[2142201184] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6x5kl; range_end:; response_count:1; response_revision:8664; }","duration":"237.002645ms","start":"2026-09-19T06:25:26.501127Z","end":"2026-09-19T06:25:26.738129Z","steps":["trace[2142201184] 'range keys from in-memory index tree' (duration: 236.633076ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:25:26.739476Z","caller":"traceutil/trace.go:171","msg":"trace[1508927139] linearizableReadLoop","detail":"{readStateIndex:8977; appliedIndex:8976; }","duration":"101.253434ms","start":"2026-09-19T06:25:26.638207Z","end":"2026-09-19T06:25:26.73946Z","steps":["trace[1508927139] 'read index received' (duration: 100.902816ms)","trace[1508927139] 'applied index is now lower than readState.Index' (duration: 349.818µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:25:26.739594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.382968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:25:26.739627Z","caller":"traceutil/trace.go:171","msg":"trace[1567305196] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8665; }","duration":"101.4587ms","start":"2026-09-19T06:25:26.638158Z","end":"2026-09-19T06:25:26.739616Z","steps":["trace[1567305196] 'agreement among raft nodes before linearized reading' (duration: 101.378717ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:25:26.739737Z","caller":"traceutil/trace.go:171","msg":"trace[1058179116] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"206.397983ms","start":"2026-09-19T06:25:26.5333Z","end":"2026-09-19T06:25:26.739698Z","steps":["trace[1058179116] 'process raft request' (duration: 206.018163ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:25:39.801571Z","caller":"traceutil/trace.go:171","msg":"trace[945756350] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"100.756082ms","start":"2026-09-19T06:25:39.700786Z","end":"2026-09-19T06:25:39.801542Z","steps":["trace[945756350] 'process raft request' (duration: 100.579317ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:25:41.781351Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002368.wal"} {"level":"info","ts":"2026-09-19T06:26:08.993526Z","caller":"traceutil/trace.go:171","msg":"trace[1038826317] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"484.689183ms","start":"2026-09-19T06:26:08.508793Z","end":"2026-09-19T06:26:08.993482Z","steps":["trace[1038826317] 'process raft request' (duration: 484.54413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:26:08.993677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:26:08.50877Z","time spent":"484.835207ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:26:09.001979Z","caller":"traceutil/trace.go:171","msg":"trace[553390158] linearizableReadLoop","detail":"{readStateIndex:9265; appliedIndex:9263; }","duration":"323.252669ms","start":"2026-09-19T06:26:08.678706Z","end":"2026-09-19T06:26:09.001958Z","steps":["trace[553390158] 'read index received' (duration: 314.671021ms)","trace[553390158] 'applied index is now lower than readState.Index' (duration: 8.580748ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:26:09.002015Z","caller":"traceutil/trace.go:171","msg":"trace[2107001835] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"471.943054ms","start":"2026-09-19T06:26:08.530046Z","end":"2026-09-19T06:26:09.001989Z","steps":["trace[2107001835] 'process raft request' (duration: 471.76239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:26:09.002272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.541376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-19T06:26:09.002572Z","caller":"traceutil/trace.go:171","msg":"trace[656972559] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8943; }","duration":"323.890224ms","start":"2026-09-19T06:26:08.678664Z","end":"2026-09-19T06:26:09.002554Z","steps":["trace[656972559] 'agreement among raft nodes before linearized reading' (duration: 323.415132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:26:09.002623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:26:08.678644Z","time spent":"323.963516ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T06:26:09.00261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:26:08.530025Z","time spent":"472.422756ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:26:21.218292Z","caller":"traceutil/trace.go:171","msg":"trace[1002698867] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"157.49064ms","start":"2026-09-19T06:26:21.060766Z","end":"2026-09-19T06:26:21.218257Z","steps":["trace[1002698867] 'process raft request' (duration: 58.093779ms)","trace[1002698867] 'compare' (duration: 99.055562ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:26:21.676593Z","caller":"traceutil/trace.go:171","msg":"trace[1146742350] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"197.090199ms","start":"2026-09-19T06:26:21.479473Z","end":"2026-09-19T06:26:21.676563Z","steps":["trace[1146742350] 'process raft request' (duration: 196.865194ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:27:23.655362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":10010,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T06:27:23.664066Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10010} {"level":"info","ts":"2026-09-19T06:27:23.664388Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5010} {"level":"info","ts":"2026-09-19T06:27:49.75206Z","caller":"traceutil/trace.go:171","msg":"trace[217204730] transaction","detail":"{read_only:false; response_revision:9880; number_of_response:1; }","duration":"226.82862ms","start":"2026-09-19T06:27:49.525201Z","end":"2026-09-19T06:27:49.752029Z","steps":["trace[217204730] 'process raft request' (duration: 223.552351ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:29:14.882493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8050} {"level":"info","ts":"2026-09-19T06:29:14.953356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8050,"took":"68.214734ms","hash":682370753,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":27971584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T06:29:14.953432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":682370753,"revision":8050,"compact-revision":5908} {"level":"info","ts":"2026-09-19T06:33:32.33169Z","caller":"traceutil/trace.go:171","msg":"trace[1635053400] linearizableReadLoop","detail":"{readStateIndex:12763; appliedIndex:12762; }","duration":"143.016987ms","start":"2026-09-19T06:33:32.188649Z","end":"2026-09-19T06:33:32.331666Z","steps":["trace[1635053400] 'read index received' (duration: 142.788132ms)","trace[1635053400] 'applied index is now lower than readState.Index' (duration: 227.765µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:33:32.331763Z","caller":"traceutil/trace.go:171","msg":"trace[1310955424] transaction","detail":"{read_only:false; response_revision:12335; number_of_response:1; }","duration":"154.833824ms","start":"2026-09-19T06:33:32.176886Z","end":"2026-09-19T06:33:32.33172Z","steps":["trace[1310955424] 'process raft request' (duration: 154.63705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:33:32.331926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.257143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:33:32.331961Z","caller":"traceutil/trace.go:171","msg":"trace[1321000197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12335; }","duration":"143.324415ms","start":"2026-09-19T06:33:32.188625Z","end":"2026-09-19T06:33:32.33195Z","steps":["trace[1321000197] 'agreement among raft nodes before linearized reading' (duration: 143.253293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:33:32.332169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.978162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6x5kl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-19T06:33:32.332247Z","caller":"traceutil/trace.go:171","msg":"trace[298253722] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6x5kl; range_end:; response_count:1; response_revision:12335; }","duration":"130.074834ms","start":"2026-09-19T06:33:32.202155Z","end":"2026-09-19T06:33:32.33223Z","steps":["trace[298253722] 'agreement among raft nodes before linearized reading' (duration: 129.856128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:33:32.33231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.100504ms","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-09-19T06:33:32.332164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.599531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T06:33:32.332374Z","caller":"traceutil/trace.go:171","msg":"trace[285934546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12335; }","duration":"138.851777ms","start":"2026-09-19T06:33:32.19351Z","end":"2026-09-19T06:33:32.332362Z","steps":["trace[285934546] 'agreement among raft nodes before linearized reading' (duration: 138.486648ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:33:32.332376Z","caller":"traceutil/trace.go:171","msg":"trace[1908941328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12335; }","duration":"132.178695ms","start":"2026-09-19T06:33:32.200178Z","end":"2026-09-19T06:33:32.332357Z","steps":["trace[1908941328] 'agreement among raft nodes before linearized reading' (duration: 132.037542ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:33:32.461829Z","caller":"traceutil/trace.go:171","msg":"trace[33402848] linearizableReadLoop","detail":"{readStateIndex:12765; appliedIndex:12763; }","duration":"120.931971ms","start":"2026-09-19T06:33:32.340877Z","end":"2026-09-19T06:33:32.461809Z","steps":["trace[33402848] 'read index received' (duration: 45.189835ms)","trace[33402848] 'applied index is now lower than readState.Index' (duration: 75.741086ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:33:32.4619Z","caller":"traceutil/trace.go:171","msg":"trace[301077110] transaction","detail":"{read_only:false; response_revision:12337; number_of_response:1; }","duration":"123.420814ms","start":"2026-09-19T06:33:32.338456Z","end":"2026-09-19T06:33:32.461876Z","steps":["trace[301077110] 'process raft request' (duration: 87.568734ms)","trace[301077110] 'compare' (duration: 35.665775ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:33:32.462375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.481165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:447796"} {"level":"info","ts":"2026-09-19T06:33:32.462428Z","caller":"traceutil/trace.go:171","msg":"trace[1728033926] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:12337; }","duration":"121.566167ms","start":"2026-09-19T06:33:32.340851Z","end":"2026-09-19T06:33:32.462417Z","steps":["trace[1728033926] 'agreement among raft nodes before linearized reading' (duration: 121.030065ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:34:14.889357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10457} {"level":"info","ts":"2026-09-19T06:34:14.975185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10457,"took":"83.481584ms","hash":1221341659,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":25997312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-19T06:34:14.975249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1221341659,"revision":10457,"compact-revision":8050} {"level":"info","ts":"2026-09-19T06:39:14.898955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12643} {"level":"info","ts":"2026-09-19T06:39:14.984506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12643,"took":"82.987802ms","hash":3608615590,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T06:39:14.984583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3608615590,"revision":12643,"compact-revision":10457} {"level":"info","ts":"2026-09-19T06:40:22.401852Z","caller":"traceutil/trace.go:171","msg":"trace[1317729871] linearizableReadLoop","detail":"{readStateIndex:15577; appliedIndex:15576; }","duration":"324.243236ms","start":"2026-09-19T06:40:22.077589Z","end":"2026-09-19T06:40:22.401833Z","steps":["trace[1317729871] 'read index received' (duration: 324.184355ms)","trace[1317729871] 'applied index is now lower than readState.Index' (duration: 57.881µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:40:22.401937Z","caller":"traceutil/trace.go:171","msg":"trace[1631324983] transaction","detail":"{read_only:false; response_revision:15050; number_of_response:1; }","duration":"356.337365ms","start":"2026-09-19T06:40:22.045561Z","end":"2026-09-19T06:40:22.401898Z","steps":["trace[1631324983] 'process raft request' (duration: 356.125509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:40:22.402013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.414932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6x5kl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-19T06:40:22.402047Z","caller":"traceutil/trace.go:171","msg":"trace[1809298631] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6x5kl; range_end:; response_count:1; response_revision:15050; }","duration":"324.508214ms","start":"2026-09-19T06:40:22.07753Z","end":"2026-09-19T06:40:22.402038Z","steps":["trace[1809298631] 'agreement among raft nodes before linearized reading' (duration: 324.375801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:40:22.402068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:40:22.077507Z","time spent":"324.556244ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-6x5kl\" "} {"level":"warn","ts":"2026-09-19T06:40:22.402125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:40:22.045526Z","time spent":"356.498868ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:40:22.418544Z","caller":"traceutil/trace.go:171","msg":"trace[1194231526] transaction","detail":"{read_only:false; response_revision:15051; number_of_response:1; }","duration":"186.534759ms","start":"2026-09-19T06:40:22.231982Z","end":"2026-09-19T06:40:22.418516Z","steps":["trace[1194231526] 'process raft request' (duration: 183.456975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:40:22.418779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.738308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:40:22.418814Z","caller":"traceutil/trace.go:171","msg":"trace[374309196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15051; }","duration":"229.81928ms","start":"2026-09-19T06:40:22.188982Z","end":"2026-09-19T06:40:22.418801Z","steps":["trace[374309196] 'agreement among raft nodes before linearized reading' (duration: 229.753878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:40:22.418821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.895923ms","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-09-19T06:40:22.418854Z","caller":"traceutil/trace.go:171","msg":"trace[556754907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15051; }","duration":"154.939515ms","start":"2026-09-19T06:40:22.263905Z","end":"2026-09-19T06:40:22.418844Z","steps":["trace[556754907] 'agreement among raft nodes before linearized reading' (duration: 154.799292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:40:22.418831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.316146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:40:22.418942Z","caller":"traceutil/trace.go:171","msg":"trace[1701709390] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:15051; }","duration":"153.498801ms","start":"2026-09-19T06:40:22.265416Z","end":"2026-09-19T06:40:22.418915Z","steps":["trace[1701709390] 'agreement among raft nodes before linearized reading' (duration: 153.254964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:40:22.41892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.29689ms","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-09-19T06:40:22.419078Z","caller":"traceutil/trace.go:171","msg":"trace[872014335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15051; }","duration":"159.484305ms","start":"2026-09-19T06:40:22.259572Z","end":"2026-09-19T06:40:22.419056Z","steps":["trace[872014335] 'agreement among raft nodes before linearized reading' (duration: 158.974232ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:40:46.597561Z","caller":"traceutil/trace.go:171","msg":"trace[1333781550] transaction","detail":"{read_only:false; response_revision:15370; number_of_response:1; }","duration":"174.415367ms","start":"2026-09-19T06:40:46.42312Z","end":"2026-09-19T06:40:46.597535Z","steps":["trace[1333781550] 'process raft request' (duration: 174.217772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:41:39.720075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.897856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-19T06:41:39.720187Z","caller":"traceutil/trace.go:171","msg":"trace[115447932] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16010; }","duration":"120.0654ms","start":"2026-09-19T06:41:39.600099Z","end":"2026-09-19T06:41:39.720164Z","steps":["trace[115447932] 'range keys from in-memory index tree' (duration: 119.671341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:41:59.507416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.346025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:41:59.507986Z","caller":"traceutil/trace.go:171","msg":"trace[1073787925] transaction","detail":"{read_only:false; response_revision:16118; number_of_response:1; }","duration":"324.321269ms","start":"2026-09-19T06:41:59.183225Z","end":"2026-09-19T06:41:59.507546Z","steps":["trace[1073787925] 'process raft request' (duration: 137.757369ms)","trace[1073787925] 'compare' (duration: 185.995896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:41:59.508235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:41:59.183199Z","time spent":"324.912383ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:41:59.696667Z","caller":"traceutil/trace.go:171","msg":"trace[1372682222] transaction","detail":"{read_only:false; response_revision:16119; number_of_response:1; }","duration":"102.144055ms","start":"2026-09-19T06:41:59.59449Z","end":"2026-09-19T06:41:59.696634Z","steps":["trace[1372682222] 'process raft request' (duration: 101.000057ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:44:14.906494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14632} {"level":"info","ts":"2026-09-19T06:44:14.98955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14632,"took":"80.349197ms","hash":2476528202,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T06:44:14.989615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2476528202,"revision":14632,"compact-revision":12643} {"level":"info","ts":"2026-09-19T06:48:49.949458Z","caller":"traceutil/trace.go:171","msg":"trace[1669921189] linearizableReadLoop","detail":"{readStateIndex:19678; appliedIndex:19677; }","duration":"106.937951ms","start":"2026-09-19T06:48:49.842487Z","end":"2026-09-19T06:48:49.949425Z","steps":["trace[1669921189] 'read index received' (duration: 106.624754ms)","trace[1669921189] 'applied index is now lower than readState.Index' (duration: 311.207µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:48:49.94968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.157877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T06:48:49.949722Z","caller":"traceutil/trace.go:171","msg":"trace[762884097] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:19020; }","duration":"107.26714ms","start":"2026-09-19T06:48:49.842442Z","end":"2026-09-19T06:48:49.949709Z","steps":["trace[762884097] 'agreement among raft nodes before linearized reading' (duration: 107.115115ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:48:49.950131Z","caller":"traceutil/trace.go:171","msg":"trace[428282908] transaction","detail":"{read_only:false; response_revision:19020; number_of_response:1; }","duration":"155.701213ms","start":"2026-09-19T06:48:49.794419Z","end":"2026-09-19T06:48:49.95012Z","steps":["trace[428282908] 'process raft request' (duration: 154.73266ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:48:50.47121Z","caller":"traceutil/trace.go:171","msg":"trace[750849223] linearizableReadLoop","detail":"{readStateIndex:19679; appliedIndex:19678; }","duration":"147.439193ms","start":"2026-09-19T06:48:50.323746Z","end":"2026-09-19T06:48:50.471185Z","steps":["trace[750849223] 'read index received' (duration: 147.092765ms)","trace[750849223] 'applied index is now lower than readState.Index' (duration: 345.078µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:48:50.471308Z","caller":"traceutil/trace.go:171","msg":"trace[587417880] transaction","detail":"{read_only:false; response_revision:19021; number_of_response:1; }","duration":"184.49276ms","start":"2026-09-19T06:48:50.286767Z","end":"2026-09-19T06:48:50.47126Z","steps":["trace[587417880] 'process raft request' (duration: 184.162562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:48:50.471582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.435087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-19T06:48:50.47159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.797502ms","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-09-19T06:48:50.471631Z","caller":"traceutil/trace.go:171","msg":"trace[1711252313] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19021; }","duration":"122.52825ms","start":"2026-09-19T06:48:50.349091Z","end":"2026-09-19T06:48:50.471619Z","steps":["trace[1711252313] 'agreement among raft nodes before linearized reading' (duration: 122.317774ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:48:50.471687Z","caller":"traceutil/trace.go:171","msg":"trace[447595043] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:19021; }","duration":"147.987747ms","start":"2026-09-19T06:48:50.323675Z","end":"2026-09-19T06:48:50.471663Z","steps":["trace[447595043] 'agreement among raft nodes before linearized reading' (duration: 147.697589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:48:50.903008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.257641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fltxx\" ","response":"range_response_count:1 size:20881"} {"level":"info","ts":"2026-09-19T06:48:50.90311Z","caller":"traceutil/trace.go:171","msg":"trace[797227204] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fltxx; range_end:; response_count:1; response_revision:19021; }","duration":"417.426035ms","start":"2026-09-19T06:48:50.485664Z","end":"2026-09-19T06:48:50.903091Z","steps":["trace[797227204] 'range keys from in-memory index tree' (duration: 417.137878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:48:50.903142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:48:50.485638Z","time spent":"417.495716ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20903,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fltxx\" "} {"level":"warn","ts":"2026-09-19T06:48:50.903256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.5168ms","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-09-19T06:48:50.903355Z","caller":"traceutil/trace.go:171","msg":"trace[1372352321] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19021; }","duration":"361.685733ms","start":"2026-09-19T06:48:50.541646Z","end":"2026-09-19T06:48:50.903332Z","steps":["trace[1372352321] 'range keys from in-memory index tree' (duration: 361.426357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:48:50.903436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:48:50.541621Z","time spent":"361.797086ms","remote":"127.0.0.1:41814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-19T06:48:50.903753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.412372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-19T06:48:50.903821Z","caller":"traceutil/trace.go:171","msg":"trace[410338688] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:19021; }","duration":"296.503925ms","start":"2026-09-19T06:48:50.607283Z","end":"2026-09-19T06:48:50.903786Z","steps":["trace[410338688] 'count revisions from in-memory index tree' (duration: 295.682655ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:48:50.90655Z","caller":"traceutil/trace.go:171","msg":"trace[2108379199] transaction","detail":"{read_only:false; response_revision:19022; number_of_response:1; }","duration":"217.294156ms","start":"2026-09-19T06:48:50.688862Z","end":"2026-09-19T06:48:50.906156Z","steps":["trace[2108379199] 'process raft request' (duration: 217.019729ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:48:50.907095Z","caller":"traceutil/trace.go:171","msg":"trace[977247212] transaction","detail":"{read_only:false; response_revision:19023; number_of_response:1; }","duration":"216.819133ms","start":"2026-09-19T06:48:50.690264Z","end":"2026-09-19T06:48:50.907083Z","steps":["trace[977247212] 'process raft request' (duration: 216.717221ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:48:51.219185Z","caller":"traceutil/trace.go:171","msg":"trace[355553986] linearizableReadLoop","detail":"{readStateIndex:19682; appliedIndex:19681; }","duration":"293.565083ms","start":"2026-09-19T06:48:50.925575Z","end":"2026-09-19T06:48:51.21914Z","steps":["trace[355553986] 'read index received' (duration: 291.240438ms)","trace[355553986] 'applied index is now lower than readState.Index' (duration: 2.322105ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:48:51.21994Z","caller":"traceutil/trace.go:171","msg":"trace[774289506] transaction","detail":"{read_only:false; response_revision:19024; number_of_response:1; }","duration":"299.860055ms","start":"2026-09-19T06:48:50.920004Z","end":"2026-09-19T06:48:51.219864Z","steps":["trace[774289506] 'process raft request' (duration: 296.947675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:48:51.220046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.436224ms","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-09-19T06:48:51.220186Z","caller":"traceutil/trace.go:171","msg":"trace[1506340726] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:19024; }","duration":"294.604928ms","start":"2026-09-19T06:48:50.925527Z","end":"2026-09-19T06:48:51.220132Z","steps":["trace[1506340726] 'agreement among raft nodes before linearized reading' (duration: 294.23045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:48:51.2202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:48:50.919955Z","time spent":"300.095001ms","remote":"127.0.0.1:52112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:48:51.345149Z","caller":"traceutil/trace.go:171","msg":"trace[986256335] transaction","detail":"{read_only:false; response_revision:19025; number_of_response:1; }","duration":"104.612775ms","start":"2026-09-19T06:48:51.240489Z","end":"2026-09-19T06:48:51.345102Z","steps":["trace[986256335] 'process raft request' (duration: 64.248127ms)","trace[986256335] 'compare' (duration: 39.857306ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:49:14.913962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16941} {"level":"info","ts":"2026-09-19T06:49:15.015092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16941,"took":"97.707618ms","hash":1241356162,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-19T06:49:15.015166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1241356162,"revision":16941,"compact-revision":14632} {"level":"info","ts":"2026-09-19T06:49:18.127989Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":20011,"local-member-snapshot-index":10010,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T06:49:18.133452Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20011} {"level":"info","ts":"2026-09-19T06:49:18.133559Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15011} {"level":"info","ts":"2026-09-19T06:49:50.332609Z","caller":"traceutil/trace.go:171","msg":"trace[723085921] transaction","detail":"{read_only:false; response_revision:19742; number_of_response:1; }","duration":"145.048775ms","start":"2026-09-19T06:49:50.187526Z","end":"2026-09-19T06:49:50.332575Z","steps":["trace[723085921] 'process raft request' (duration: 144.576063ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:54:14.92201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19313} {"level":"info","ts":"2026-09-19T06:54:15.024486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19313,"took":"99.155153ms","hash":3221696456,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":33460224,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-19T06:54:15.024556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221696456,"revision":19313,"compact-revision":16941} {"level":"info","ts":"2026-09-19T06:54:56.535038Z","caller":"traceutil/trace.go:171","msg":"trace[1570727964] transaction","detail":"{read_only:false; response_revision:21814; number_of_response:1; }","duration":"186.09975ms","start":"2026-09-19T06:54:56.348901Z","end":"2026-09-19T06:54:56.535001Z","steps":["trace[1570727964] 'process raft request' (duration: 185.864145ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:54:56.562952Z","caller":"traceutil/trace.go:171","msg":"trace[1284286511] linearizableReadLoop","detail":"{readStateIndex:22566; appliedIndex:22564; }","duration":"110.774745ms","start":"2026-09-19T06:54:56.452144Z","end":"2026-09-19T06:54:56.562919Z","steps":["trace[1284286511] 'read index received' (duration: 82.658664ms)","trace[1284286511] 'applied index is now lower than readState.Index' (duration: 28.114581ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:54:56.563203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.03931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7697"} {"level":"info","ts":"2026-09-19T06:54:56.563237Z","caller":"traceutil/trace.go:171","msg":"trace[2117768518] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:21815; }","duration":"111.135883ms","start":"2026-09-19T06:54:56.452089Z","end":"2026-09-19T06:54:56.563225Z","steps":["trace[2117768518] 'agreement among raft nodes before linearized reading' (duration: 110.946708ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:54:56.563323Z","caller":"traceutil/trace.go:171","msg":"trace[1181631895] transaction","detail":"{read_only:false; response_revision:21815; number_of_response:1; }","duration":"184.866889ms","start":"2026-09-19T06:54:56.37842Z","end":"2026-09-19T06:54:56.563287Z","steps":["trace[1181631895] 'process raft request' (duration: 184.291065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:03.474941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.620959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T06:55:03.475066Z","caller":"traceutil/trace.go:171","msg":"trace[1873119535] transaction","detail":"{read_only:false; response_revision:21870; number_of_response:1; }","duration":"156.185375ms","start":"2026-09-19T06:55:03.318861Z","end":"2026-09-19T06:55:03.475047Z","steps":["trace[1873119535] 'compare' (duration: 150.350053ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:55:03.490832Z","caller":"traceutil/trace.go:171","msg":"trace[814082827] transaction","detail":"{read_only:false; response_revision:21871; number_of_response:1; }","duration":"153.621712ms","start":"2026-09-19T06:55:03.337194Z","end":"2026-09-19T06:55:03.490816Z","steps":["trace[814082827] 'process raft request' (duration: 153.493739ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:55:07.623773Z","caller":"traceutil/trace.go:171","msg":"trace[727107872] linearizableReadLoop","detail":"{readStateIndex:22650; appliedIndex:22649; }","duration":"102.930304ms","start":"2026-09-19T06:55:07.520808Z","end":"2026-09-19T06:55:07.623738Z","steps":["trace[727107872] 'read index received' (duration: 102.678998ms)","trace[727107872] 'applied index is now lower than readState.Index' (duration: 249.696µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:55:07.623959Z","caller":"traceutil/trace.go:171","msg":"trace[1594173954] transaction","detail":"{read_only:false; response_revision:21897; number_of_response:1; }","duration":"128.439142ms","start":"2026-09-19T06:55:07.495485Z","end":"2026-09-19T06:55:07.623924Z","steps":["trace[1594173954] 'process raft request' (duration: 128.079943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:07.624122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.294753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T06:55:07.624185Z","caller":"traceutil/trace.go:171","msg":"trace[477391375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21897; }","duration":"103.429037ms","start":"2026-09-19T06:55:07.520735Z","end":"2026-09-19T06:55:07.624164Z","steps":["trace[477391375] 'agreement among raft nodes before linearized reading' (duration: 103.220282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:07.624164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.95639ms","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-09-19T06:55:07.62426Z","caller":"traceutil/trace.go:171","msg":"trace[1806378921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21897; }","duration":"102.099313ms","start":"2026-09-19T06:55:07.522136Z","end":"2026-09-19T06:55:07.624235Z","steps":["trace[1806378921] 'agreement among raft nodes before linearized reading' (duration: 101.839187ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:55:25.031833Z","caller":"traceutil/trace.go:171","msg":"trace[714920516] transaction","detail":"{read_only:false; response_revision:22013; number_of_response:1; }","duration":"391.342542ms","start":"2026-09-19T06:55:24.640426Z","end":"2026-09-19T06:55:25.031768Z","steps":["trace[714920516] 'process raft request' (duration: 391.146677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:25.032033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:55:24.640396Z","time spent":"391.538698ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:55:25.075139Z","caller":"traceutil/trace.go:171","msg":"trace[2147395307] transaction","detail":"{read_only:false; response_revision:22016; number_of_response:1; }","duration":"137.359738ms","start":"2026-09-19T06:55:24.937751Z","end":"2026-09-19T06:55:25.075111Z","steps":["trace[2147395307] 'process raft request' (duration: 137.303977ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:55:25.075587Z","caller":"traceutil/trace.go:171","msg":"trace[682563255] transaction","detail":"{read_only:false; response_revision:22014; number_of_response:1; }","duration":"397.277187ms","start":"2026-09-19T06:55:24.678297Z","end":"2026-09-19T06:55:25.075574Z","steps":["trace[682563255] 'process raft request' (duration: 396.562269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:25.075701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:55:24.678279Z","time spent":"397.339828ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:55:25.075962Z","caller":"traceutil/trace.go:171","msg":"trace[2136920785] transaction","detail":"{read_only:false; response_revision:22015; number_of_response:1; }","duration":"152.26778ms","start":"2026-09-19T06:55:24.92368Z","end":"2026-09-19T06:55:25.075948Z","steps":["trace[2136920785] 'process raft request' (duration: 151.327747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:25.284295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.5583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T06:55:25.284402Z","caller":"traceutil/trace.go:171","msg":"trace[765481668] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:22016; }","duration":"193.664772ms","start":"2026-09-19T06:55:25.090695Z","end":"2026-09-19T06:55:25.28436Z","steps":["trace[765481668] 'count revisions from in-memory index tree' (duration: 193.476808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:25.606188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.220837ms","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-09-19T06:55:25.606302Z","caller":"traceutil/trace.go:171","msg":"trace[911649354] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:22017; }","duration":"114.34478ms","start":"2026-09-19T06:55:25.491939Z","end":"2026-09-19T06:55:25.606284Z","steps":["trace[911649354] 'range keys from in-memory index tree' (duration: 114.206197ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:55:26.024092Z","caller":"traceutil/trace.go:171","msg":"trace[1789549298] linearizableReadLoop","detail":"{readStateIndex:22775; appliedIndex:22774; }","duration":"302.833179ms","start":"2026-09-19T06:55:25.721231Z","end":"2026-09-19T06:55:26.024064Z","steps":["trace[1789549298] 'read index received' (duration: 302.647144ms)","trace[1789549298] 'applied index is now lower than readState.Index' (duration: 184.605µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:55:26.024189Z","caller":"traceutil/trace.go:171","msg":"trace[2014419799] transaction","detail":"{read_only:false; response_revision:22018; number_of_response:1; }","duration":"312.595285ms","start":"2026-09-19T06:55:25.711539Z","end":"2026-09-19T06:55:26.024134Z","steps":["trace[2014419799] 'process raft request' (duration: 312.212845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:26.024411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.117386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-19T06:55:26.024466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:55:25.71152Z","time spent":"312.774209ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:55:26.024507Z","caller":"traceutil/trace.go:171","msg":"trace[2007094750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22018; }","duration":"303.275939ms","start":"2026-09-19T06:55:25.721209Z","end":"2026-09-19T06:55:26.024485Z","steps":["trace[2007094750] 'agreement among raft nodes before linearized reading' (duration: 302.979072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:26.024555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:55:25.721198Z","time spent":"303.34802ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T06:55:26.085173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.993513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T06:55:26.085302Z","caller":"traceutil/trace.go:171","msg":"trace[1760420947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22019; }","duration":"360.153538ms","start":"2026-09-19T06:55:25.725115Z","end":"2026-09-19T06:55:26.085269Z","steps":["trace[1760420947] 'agreement among raft nodes before linearized reading' (duration: 359.81381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:26.085311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.348868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-19T06:55:26.085363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:55:25.725104Z","time spent":"360.244139ms","remote":"127.0.0.1:41672","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-09-19T06:55:26.085427Z","caller":"traceutil/trace.go:171","msg":"trace[1891708549] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22019; }","duration":"333.508642ms","start":"2026-09-19T06:55:25.751893Z","end":"2026-09-19T06:55:26.085402Z","steps":["trace[1891708549] 'agreement among raft nodes before linearized reading' (duration: 333.251816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:26.085487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:55:25.751864Z","time spent":"333.609483ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T06:55:26.30345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.227185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T06:55:26.303625Z","caller":"traceutil/trace.go:171","msg":"trace[807748483] transaction","detail":"{read_only:false; response_revision:22020; number_of_response:1; }","duration":"274.625045ms","start":"2026-09-19T06:55:26.028952Z","end":"2026-09-19T06:55:26.303577Z","steps":["trace[807748483] 'process raft request' (duration: 168.147995ms)","trace[807748483] 'compare' (duration: 105.968497ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:55:26.30371Z","caller":"traceutil/trace.go:171","msg":"trace[1809890888] transaction","detail":"{read_only:false; response_revision:22021; number_of_response:1; }","duration":"210.426458ms","start":"2026-09-19T06:55:26.093263Z","end":"2026-09-19T06:55:26.30369Z","steps":["trace[1809890888] 'process raft request' (duration: 210.307036ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:55:26.303735Z","caller":"traceutil/trace.go:171","msg":"trace[348254536] linearizableReadLoop","detail":"{readStateIndex:22777; appliedIndex:22776; }","duration":"214.366065ms","start":"2026-09-19T06:55:26.089339Z","end":"2026-09-19T06:55:26.303705Z","steps":["trace[348254536] 'read index received' (duration: 107.769912ms)","trace[348254536] 'applied index is now lower than readState.Index' (duration: 106.594083ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:55:26.304049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.698063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-19T06:55:26.304108Z","caller":"traceutil/trace.go:171","msg":"trace[1843014261] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22021; }","duration":"214.805766ms","start":"2026-09-19T06:55:26.089287Z","end":"2026-09-19T06:55:26.304093Z","steps":["trace[1843014261] 'agreement among raft nodes before linearized reading' (duration: 214.58948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:26.304123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.923039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T06:55:26.304198Z","caller":"traceutil/trace.go:171","msg":"trace[1123283231] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:22021; }","duration":"123.015281ms","start":"2026-09-19T06:55:26.181166Z","end":"2026-09-19T06:55:26.304181Z","steps":["trace[1123283231] 'agreement among raft nodes before linearized reading' (duration: 122.870296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:55:26.304214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.832491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T06:55:26.304296Z","caller":"traceutil/trace.go:171","msg":"trace[1703548884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22021; }","duration":"104.980895ms","start":"2026-09-19T06:55:26.199296Z","end":"2026-09-19T06:55:26.304277Z","steps":["trace[1703548884] 'agreement among raft nodes before linearized reading' (duration: 104.806021ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:56:45.580262Z","caller":"traceutil/trace.go:171","msg":"trace[469485162] transaction","detail":"{read_only:false; response_revision:22699; number_of_response:1; }","duration":"137.754304ms","start":"2026-09-19T06:56:45.442472Z","end":"2026-09-19T06:56:45.580226Z","steps":["trace[469485162] 'process raft request' (duration: 58.791343ms)","trace[469485162] 'compare' (duration: 78.460999ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:56:45.603329Z","caller":"traceutil/trace.go:171","msg":"trace[1400690051] transaction","detail":"{read_only:false; response_revision:22700; number_of_response:1; }","duration":"160.108006ms","start":"2026-09-19T06:56:45.443195Z","end":"2026-09-19T06:56:45.603303Z","steps":["trace[1400690051] 'process raft request' (duration: 159.983633ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:59:14.929602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21602} {"level":"info","ts":"2026-09-19T06:59:15.052919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21602,"took":"119.40715ms","hash":3441220260,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T06:59:15.053005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3441220260,"revision":21602,"compact-revision":19313} {"level":"info","ts":"2026-09-19T06:59:44.223194Z","caller":"traceutil/trace.go:171","msg":"trace[28871280] linearizableReadLoop","detail":"{readStateIndex:25403; appliedIndex:25402; }","duration":"143.025923ms","start":"2026-09-19T06:59:44.080132Z","end":"2026-09-19T06:59:44.223158Z","steps":["trace[28871280] 'read index received' (duration: 127.724092ms)","trace[28871280] 'applied index is now lower than readState.Index' (duration: 15.300431ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:59:44.223353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.202546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T06:59:44.223413Z","caller":"traceutil/trace.go:171","msg":"trace[289926786] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24566; }","duration":"143.325819ms","start":"2026-09-19T06:59:44.080072Z","end":"2026-09-19T06:59:44.223398Z","steps":["trace[289926786] 'agreement among raft nodes before linearized reading' (duration: 143.213616ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:59:44.223557Z","caller":"traceutil/trace.go:171","msg":"trace[1843633497] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"178.670204ms","start":"2026-09-19T06:59:44.044853Z","end":"2026-09-19T06:59:44.223523Z","steps":["trace[1843633497] 'process raft request' (duration: 163.026435ms)","trace[1843633497] 'compare' (duration: 14.739757ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:01:14.739273Z","caller":"traceutil/trace.go:171","msg":"trace[1802945597] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"127.937727ms","start":"2026-09-19T07:01:14.611301Z","end":"2026-09-19T07:01:14.739239Z","steps":["trace[1802945597] 'process raft request' (duration: 127.68893ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:01:14.781594Z","caller":"traceutil/trace.go:171","msg":"trace[1067011645] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"132.471467ms","start":"2026-09-19T07:01:14.649091Z","end":"2026-09-19T07:01:14.781563Z","steps":["trace[1067011645] 'process raft request' (duration: 132.206041ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:01:14.781604Z","caller":"traceutil/trace.go:171","msg":"trace[327978845] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"131.489812ms","start":"2026-09-19T07:01:14.650089Z","end":"2026-09-19T07:01:14.781579Z","steps":["trace[327978845] 'process raft request' (duration: 131.37157ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:01:14.781824Z","caller":"traceutil/trace.go:171","msg":"trace[796267987] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"129.243268ms","start":"2026-09-19T07:01:14.652525Z","end":"2026-09-19T07:01:14.781769Z","steps":["trace[796267987] 'process raft request' (duration: 129.003532ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:01:33.008064Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006826.wal"} {"level":"warn","ts":"2026-09-19T07:03:30.392806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.036805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:30.392895Z","caller":"traceutil/trace.go:171","msg":"trace[752951417] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:26872; }","duration":"146.317661ms","start":"2026-09-19T07:03:30.246559Z","end":"2026-09-19T07:03:30.392877Z","steps":["trace[752951417] 'count revisions from in-memory index tree' (duration: 145.937392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:30.690814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.876367ms","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-09-19T07:03:30.690961Z","caller":"traceutil/trace.go:171","msg":"trace[806485278] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:26873; }","duration":"195.079992ms","start":"2026-09-19T07:03:30.495859Z","end":"2026-09-19T07:03:30.690939Z","steps":["trace[806485278] 'count revisions from in-memory index tree' (duration: 194.771175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:30.691319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.50521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:30.691462Z","caller":"traceutil/trace.go:171","msg":"trace[1378563999] linearizableReadLoop","detail":"{readStateIndex:27768; appliedIndex:27767; }","duration":"156.663431ms","start":"2026-09-19T07:03:30.534779Z","end":"2026-09-19T07:03:30.691442Z","steps":["trace[1378563999] 'read index received' (duration: 34.882843ms)","trace[1378563999] 'applied index is now lower than readState.Index' (duration: 121.778988ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:30.691676Z","caller":"traceutil/trace.go:171","msg":"trace[1069973990] transaction","detail":"{read_only:false; response_revision:26874; number_of_response:1; }","duration":"174.453781ms","start":"2026-09-19T07:03:30.51713Z","end":"2026-09-19T07:03:30.691584Z","steps":["trace[1069973990] 'process raft request' (duration: 52.596152ms)","trace[1069973990] 'compare' (duration: 121.025979ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:30.691709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.911377ms","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-09-19T07:03:30.691771Z","caller":"traceutil/trace.go:171","msg":"trace[1630510783] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26874; }","duration":"157.017919ms","start":"2026-09-19T07:03:30.534734Z","end":"2026-09-19T07:03:30.691752Z","steps":["trace[1630510783] 'agreement among raft nodes before linearized reading' (duration: 156.802664ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.0731Z","caller":"traceutil/trace.go:171","msg":"trace[130359350] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"377.349842ms","start":"2026-09-19T07:03:30.695713Z","end":"2026-09-19T07:03:31.073063Z","steps":["trace[130359350] 'process raft request' (duration: 303.011774ms)","trace[130359350] 'compare' (duration: 74.037292ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:31.073267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:30.695692Z","time spent":"377.496067ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:31.556489Z","caller":"traceutil/trace.go:171","msg":"trace[2132922311] transaction","detail":"{read_only:false; response_revision:26878; number_of_response:1; }","duration":"264.46793ms","start":"2026-09-19T07:03:31.291993Z","end":"2026-09-19T07:03:31.556461Z","steps":["trace[2132922311] 'process raft request' (duration: 264.204394ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.556796Z","caller":"traceutil/trace.go:171","msg":"trace[1271418587] transaction","detail":"{read_only:false; response_revision:26879; number_of_response:1; }","duration":"263.275892ms","start":"2026-09-19T07:03:31.293478Z","end":"2026-09-19T07:03:31.556754Z","steps":["trace[1271418587] 'process raft request' (duration: 262.939094ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.556816Z","caller":"traceutil/trace.go:171","msg":"trace[937664129] transaction","detail":"{read_only:false; response_revision:26880; number_of_response:1; }","duration":"256.518729ms","start":"2026-09-19T07:03:31.300271Z","end":"2026-09-19T07:03:31.55679Z","steps":["trace[937664129] 'process raft request' (duration: 256.377934ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.55703Z","caller":"traceutil/trace.go:171","msg":"trace[1931951946] transaction","detail":"{read_only:false; response_revision:26881; number_of_response:1; }","duration":"256.60156ms","start":"2026-09-19T07:03:31.300418Z","end":"2026-09-19T07:03:31.55702Z","steps":["trace[1931951946] 'process raft request' (duration: 256.328593ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.557072Z","caller":"traceutil/trace.go:171","msg":"trace[286686327] transaction","detail":"{read_only:false; response_revision:26882; number_of_response:1; }","duration":"256.651141ms","start":"2026-09-19T07:03:31.300408Z","end":"2026-09-19T07:03:31.557059Z","steps":["trace[286686327] 'process raft request' (duration: 256.474437ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.557068Z","caller":"traceutil/trace.go:171","msg":"trace[552021654] transaction","detail":"{read_only:false; response_revision:26883; number_of_response:1; }","duration":"251.010955ms","start":"2026-09-19T07:03:31.30604Z","end":"2026-09-19T07:03:31.557051Z","steps":["trace[552021654] 'process raft request' (duration: 250.933263ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:31.557804Z","caller":"traceutil/trace.go:171","msg":"trace[333903941] transaction","detail":"{read_only:false; response_revision:26884; number_of_response:1; }","duration":"122.807703ms","start":"2026-09-19T07:03:31.43495Z","end":"2026-09-19T07:03:31.557758Z","steps":["trace[333903941] 'process raft request' (duration: 122.666279ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:36.572002Z","caller":"traceutil/trace.go:171","msg":"trace[287936387] transaction","detail":"{read_only:false; response_revision:26916; number_of_response:1; }","duration":"155.699207ms","start":"2026-09-19T07:03:36.416273Z","end":"2026-09-19T07:03:36.571973Z","steps":["trace[287936387] 'process raft request' (duration: 155.548774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:36.962521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.582011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:36.96263Z","caller":"traceutil/trace.go:171","msg":"trace[268356508] transaction","detail":"{read_only:false; response_revision:26917; number_of_response:1; }","duration":"249.384956ms","start":"2026-09-19T07:03:36.713225Z","end":"2026-09-19T07:03:36.96261Z","steps":["trace[268356508] 'process raft request' (duration: 56.60991ms)","trace[268356508] 'compare' (duration: 192.251263ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:37.206946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.161606ms","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-09-19T07:03:37.207033Z","caller":"traceutil/trace.go:171","msg":"trace[1762219067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26917; }","duration":"105.303559ms","start":"2026-09-19T07:03:37.101709Z","end":"2026-09-19T07:03:37.207013Z","steps":["trace[1762219067] 'range keys from in-memory index tree' (duration: 104.913078ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:37.452561Z","caller":"traceutil/trace.go:171","msg":"trace[304869433] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"202.408629ms","start":"2026-09-19T07:03:37.250128Z","end":"2026-09-19T07:03:37.452536Z","steps":["trace[304869433] 'process raft request' (duration: 202.263325ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:37.472899Z","caller":"traceutil/trace.go:171","msg":"trace[1251216640] linearizableReadLoop","detail":"{readStateIndex:27816; appliedIndex:27814; }","duration":"107.476941ms","start":"2026-09-19T07:03:37.365407Z","end":"2026-09-19T07:03:37.472884Z","steps":["trace[1251216640] 'read index received' (duration: 86.900733ms)","trace[1251216640] 'applied index is now lower than readState.Index' (duration: 20.575198ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:37.473033Z","caller":"traceutil/trace.go:171","msg":"trace[451842801] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"176.773929ms","start":"2026-09-19T07:03:37.296221Z","end":"2026-09-19T07:03:37.472995Z","steps":["trace[451842801] 'process raft request' (duration: 176.586143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:37.473151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.737928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-19T07:03:37.473195Z","caller":"traceutil/trace.go:171","msg":"trace[223273899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26920; }","duration":"107.794889ms","start":"2026-09-19T07:03:37.365386Z","end":"2026-09-19T07:03:37.47318Z","steps":["trace[223273899] 'agreement among raft nodes before linearized reading' (duration: 107.558044ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:37.844827Z","caller":"traceutil/trace.go:171","msg":"trace[700479004] linearizableReadLoop","detail":"{readStateIndex:27817; appliedIndex:27816; }","duration":"273.706324ms","start":"2026-09-19T07:03:37.57109Z","end":"2026-09-19T07:03:37.844797Z","steps":["trace[700479004] 'read index received' (duration: 273.55051ms)","trace[700479004] 'applied index is now lower than readState.Index' (duration: 154.174µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:37.844944Z","caller":"traceutil/trace.go:171","msg":"trace[1262572893] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"367.071054ms","start":"2026-09-19T07:03:37.47786Z","end":"2026-09-19T07:03:37.844931Z","steps":["trace[1262572893] 'process raft request' (duration: 366.769737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:37.845052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.47784Z","time spent":"367.128265ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:37.845048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.936439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:37.845118Z","caller":"traceutil/trace.go:171","msg":"trace[746238481] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:26921; }","duration":"274.041662ms","start":"2026-09-19T07:03:37.571057Z","end":"2026-09-19T07:03:37.845099Z","steps":["trace[746238481] 'agreement among raft nodes before linearized reading' (duration: 273.933019ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:38.008555Z","caller":"traceutil/trace.go:171","msg":"trace[1703674008] transaction","detail":"{read_only:false; response_revision:26922; number_of_response:1; }","duration":"417.501354ms","start":"2026-09-19T07:03:37.591027Z","end":"2026-09-19T07:03:38.008528Z","steps":["trace[1703674008] 'process raft request' (duration: 399.298794ms)","trace[1703674008] 'compare' (duration: 18.013846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:38.008702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.591014Z","time spent":"417.609388ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:38.008947Z","caller":"traceutil/trace.go:171","msg":"trace[1855362297] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"413.259413ms","start":"2026-09-19T07:03:37.595676Z","end":"2026-09-19T07:03:38.008935Z","steps":["trace[1855362297] 'process raft request' (duration: 413.076938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.595663Z","time spent":"413.306484ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:38.009Z","caller":"traceutil/trace.go:171","msg":"trace[26236803] transaction","detail":"{read_only:false; response_revision:26923; number_of_response:1; }","duration":"413.449867ms","start":"2026-09-19T07:03:37.595477Z","end":"2026-09-19T07:03:38.008927Z","steps":["trace[26236803] 'process raft request' (duration: 413.011115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.009153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.59546Z","time spent":"413.614241ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:38.009167Z","caller":"traceutil/trace.go:171","msg":"trace[1197755154] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"412.455073ms","start":"2026-09-19T07:03:37.59669Z","end":"2026-09-19T07:03:38.009145Z","steps":["trace[1197755154] 'process raft request' (duration: 412.199577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.009279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.596681Z","time spent":"412.554295ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:38.009287Z","caller":"traceutil/trace.go:171","msg":"trace[133208376] linearizableReadLoop","detail":"{readStateIndex:27822; appliedIndex:27817; }","duration":"164.367488ms","start":"2026-09-19T07:03:37.844899Z","end":"2026-09-19T07:03:38.009266Z","steps":["trace[133208376] 'read index received' (duration: 145.476302ms)","trace[133208376] 'applied index is now lower than readState.Index' (duration: 18.889836ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:38.009431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.104789ms","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":"info","ts":"2026-09-19T07:03:38.009478Z","caller":"traceutil/trace.go:171","msg":"trace[1346486584] 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:26926; }","duration":"418.168531ms","start":"2026-09-19T07:03:37.591292Z","end":"2026-09-19T07:03:38.009461Z","steps":["trace[1346486584] 'agreement among raft nodes before linearized reading' (duration: 418.051358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.009487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.125485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-19T07:03:38.00949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.552165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T07:03:38.009527Z","caller":"traceutil/trace.go:171","msg":"trace[334467878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26926; }","duration":"410.175407ms","start":"2026-09-19T07:03:37.599341Z","end":"2026-09-19T07:03:38.009517Z","steps":["trace[334467878] 'agreement among raft nodes before linearized reading' (duration: 410.047074ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:38.009532Z","caller":"traceutil/trace.go:171","msg":"trace[1796646718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26926; }","duration":"412.603066ms","start":"2026-09-19T07:03:37.596919Z","end":"2026-09-19T07:03:38.009522Z","steps":["trace[1796646718] 'agreement among raft nodes before linearized reading' (duration: 412.471073ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:38.009539Z","caller":"traceutil/trace.go:171","msg":"trace[1463808919] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"410.680239ms","start":"2026-09-19T07:03:37.598834Z","end":"2026-09-19T07:03:38.009514Z","steps":["trace[1463808919] 'process raft request' (duration: 410.248359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.009563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.599335Z","time spent":"410.221068ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T07:03:38.009523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.591253Z","time spent":"418.259933ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-09-19T07:03:38.009623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.91427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-19T07:03:38.009658Z","caller":"traceutil/trace.go:171","msg":"trace[1529960940] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:26926; }","duration":"164.951101ms","start":"2026-09-19T07:03:37.844699Z","end":"2026-09-19T07:03:38.00965Z","steps":["trace[1529960940] 'agreement among raft nodes before linearized reading' (duration: 164.88722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.009651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.59881Z","time spent":"410.776802ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:38.009567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:37.596914Z","time spent":"412.647217ms","remote":"127.0.0.1:41672","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":"warn","ts":"2026-09-19T07:03:38.009436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.203567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:38.009795Z","caller":"traceutil/trace.go:171","msg":"trace[723550095] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26926; }","duration":"236.616887ms","start":"2026-09-19T07:03:37.773162Z","end":"2026-09-19T07:03:38.009779Z","steps":["trace[723550095] 'agreement among raft nodes before linearized reading' (duration: 236.121595ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:38.258686Z","caller":"traceutil/trace.go:171","msg":"trace[653669672] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"245.249736ms","start":"2026-09-19T07:03:38.013399Z","end":"2026-09-19T07:03:38.258649Z","steps":["trace[653669672] 'process raft request' (duration: 188.840671ms)","trace[653669672] 'compare' (duration: 56.22055ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:38.258707Z","caller":"traceutil/trace.go:171","msg":"trace[1982252439] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"244.660521ms","start":"2026-09-19T07:03:38.014025Z","end":"2026-09-19T07:03:38.258686Z","steps":["trace[1982252439] 'process raft request' (duration: 244.562549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.651569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.327092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:38.651672Z","caller":"traceutil/trace.go:171","msg":"trace[1961820046] linearizableReadLoop","detail":"{readStateIndex:27825; appliedIndex:27824; }","duration":"307.639455ms","start":"2026-09-19T07:03:38.344014Z","end":"2026-09-19T07:03:38.651653Z","steps":["trace[1961820046] 'read index received' (duration: 99.1462ms)","trace[1961820046] 'applied index is now lower than readState.Index' (duration: 208.491605ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:38.651737Z","caller":"traceutil/trace.go:171","msg":"trace[631647121] transaction","detail":"{read_only:false; response_revision:26929; number_of_response:1; }","duration":"387.467408ms","start":"2026-09-19T07:03:38.264257Z","end":"2026-09-19T07:03:38.651724Z","steps":["trace[631647121] 'process raft request' (duration: 178.88982ms)","trace[631647121] 'compare' (duration: 208.035475ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:38.651788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:38.264239Z","time spent":"387.518829ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:38.652039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.003123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-19T07:03:38.652126Z","caller":"traceutil/trace.go:171","msg":"trace[705094534] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26929; }","duration":"308.146098ms","start":"2026-09-19T07:03:38.34396Z","end":"2026-09-19T07:03:38.652106Z","steps":["trace[705094534] 'agreement among raft nodes before linearized reading' (duration: 307.828109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.652177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:38.343932Z","time spent":"308.236371ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T07:03:38.661558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.85805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-19T07:03:38.661658Z","caller":"traceutil/trace.go:171","msg":"trace[1925883595] range","detail":"{range_begin:/registry/masterleases/162.253.55.156; range_end:; response_count:1; response_revision:26931; }","duration":"196.009944ms","start":"2026-09-19T07:03:38.465628Z","end":"2026-09-19T07:03:38.661637Z","steps":["trace[1925883595] 'agreement among raft nodes before linearized reading' (duration: 195.766528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:38.936909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.084936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-19T07:03:38.937009Z","caller":"traceutil/trace.go:171","msg":"trace[942424848] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26932; }","duration":"184.228029ms","start":"2026-09-19T07:03:38.75276Z","end":"2026-09-19T07:03:38.936988Z","steps":["trace[942424848] 'range keys from in-memory index tree' (duration: 183.87416ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:39.252945Z","caller":"traceutil/trace.go:171","msg":"trace[1253525499] transaction","detail":"{read_only:false; response_revision:26933; number_of_response:1; }","duration":"284.737151ms","start":"2026-09-19T07:03:38.968174Z","end":"2026-09-19T07:03:39.252911Z","steps":["trace[1253525499] 'process raft request' (duration: 284.492456ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:39.372044Z","caller":"traceutil/trace.go:171","msg":"trace[463475694] linearizableReadLoop","detail":"{readStateIndex:27831; appliedIndex:27829; }","duration":"182.408915ms","start":"2026-09-19T07:03:39.189598Z","end":"2026-09-19T07:03:39.372007Z","steps":["trace[463475694] 'read index received' (duration: 63.100497ms)","trace[463475694] 'applied index is now lower than readState.Index' (duration: 119.306568ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:39.372108Z","caller":"traceutil/trace.go:171","msg":"trace[558594651] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"363.5827ms","start":"2026-09-19T07:03:39.008488Z","end":"2026-09-19T07:03:39.372071Z","steps":["trace[558594651] 'process raft request' (duration: 361.698785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:39.372285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:39.008475Z","time spent":"363.700383ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:39.372301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.683702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:39.372401Z","caller":"traceutil/trace.go:171","msg":"trace[1436113793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26934; }","duration":"182.801245ms","start":"2026-09-19T07:03:39.18954Z","end":"2026-09-19T07:03:39.372341Z","steps":["trace[1436113793] 'agreement among raft nodes before linearized reading' (duration: 182.648721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:39.731962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.913476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:39.732069Z","caller":"traceutil/trace.go:171","msg":"trace[2076678350] transaction","detail":"{read_only:false; response_revision:26936; number_of_response:1; }","duration":"253.244589ms","start":"2026-09-19T07:03:39.478806Z","end":"2026-09-19T07:03:39.73205Z","steps":["trace[2076678350] 'process raft request' (duration: 94.156529ms)","trace[2076678350] 'compare' (duration: 158.766393ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:40.20993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.308436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-19T07:03:40.210035Z","caller":"traceutil/trace.go:171","msg":"trace[1717405771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26936; }","duration":"359.47099ms","start":"2026-09-19T07:03:39.850538Z","end":"2026-09-19T07:03:40.210009Z","steps":["trace[1717405771] 'range keys from in-memory index tree' (duration: 359.075011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.210088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:39.850509Z","time spent":"359.565302ms","remote":"127.0.0.1:41672","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-09-19T07:03:40.210207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.752006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-19T07:03:40.2103Z","caller":"traceutil/trace.go:171","msg":"trace[1481649720] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26936; }","duration":"279.632018ms","start":"2026-09-19T07:03:39.930647Z","end":"2026-09-19T07:03:40.210279Z","steps":["trace[1481649720] 'count revisions from in-memory index tree' (duration: 278.617343ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:40.210749Z","caller":"traceutil/trace.go:171","msg":"trace[24107123] transaction","detail":"{read_only:false; response_revision:26937; number_of_response:1; }","duration":"196.094065ms","start":"2026-09-19T07:03:40.014627Z","end":"2026-09-19T07:03:40.210721Z","steps":["trace[24107123] 'process raft request' (duration: 97.816067ms)","trace[24107123] 'compare' (duration: 97.893829ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:40.484729Z","caller":"traceutil/trace.go:171","msg":"trace[2062920258] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"469.976205ms","start":"2026-09-19T07:03:40.014718Z","end":"2026-09-19T07:03:40.484694Z","steps":["trace[2062920258] 'process raft request' (duration: 452.661275ms)","trace[2062920258] 'compare' (duration: 17.073443ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:40.484965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.014679Z","time spent":"470.158289ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:40.485081Z","caller":"traceutil/trace.go:171","msg":"trace[1856830848] transaction","detail":"{read_only:false; response_revision:26939; number_of_response:1; }","duration":"469.685347ms","start":"2026-09-19T07:03:40.015344Z","end":"2026-09-19T07:03:40.48503Z","steps":["trace[1856830848] 'process raft request' (duration: 469.301508ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:40.485189Z","caller":"traceutil/trace.go:171","msg":"trace[465233907] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"265.811513ms","start":"2026-09-19T07:03:40.219348Z","end":"2026-09-19T07:03:40.48516Z","steps":["trace[465233907] 'process raft request' (duration: 265.764062ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:40.485245Z","caller":"traceutil/trace.go:171","msg":"trace[757777679] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"469.681357ms","start":"2026-09-19T07:03:40.015538Z","end":"2026-09-19T07:03:40.485219Z","steps":["trace[757777679] 'process raft request' (duration: 469.512263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.485319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.015325Z","time spent":"469.862851ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:40.485444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.015525Z","time spent":"469.80337ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:40.485531Z","caller":"traceutil/trace.go:171","msg":"trace[239530636] linearizableReadLoop","detail":"{readStateIndex:27838; appliedIndex:27833; }","duration":"433.823659ms","start":"2026-09-19T07:03:40.051694Z","end":"2026-09-19T07:03:40.485517Z","steps":["trace[239530636] 'read index received' (duration: 60.918624ms)","trace[239530636] 'applied index is now lower than readState.Index' (duration: 372.904115ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:40.485556Z","caller":"traceutil/trace.go:171","msg":"trace[1862257038] transaction","detail":"{read_only:false; response_revision:26940; number_of_response:1; }","duration":"470.067676ms","start":"2026-09-19T07:03:40.015476Z","end":"2026-09-19T07:03:40.485543Z","steps":["trace[1862257038] 'process raft request' (duration: 469.411421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.485611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.914101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-19T07:03:40.485624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.015464Z","time spent":"470.121217ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:40.485664Z","caller":"traceutil/trace.go:171","msg":"trace[151038855] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26942; }","duration":"434.005625ms","start":"2026-09-19T07:03:40.051637Z","end":"2026-09-19T07:03:40.485643Z","steps":["trace[151038855] 'agreement among raft nodes before linearized reading' (duration: 433.932922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.485694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.05161Z","time spent":"434.075666ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-09-19T07:03:40.485689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.802279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:40.485727Z","caller":"traceutil/trace.go:171","msg":"trace[1574765203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26942; }","duration":"295.889371ms","start":"2026-09-19T07:03:40.189828Z","end":"2026-09-19T07:03:40.485717Z","steps":["trace[1574765203] 'agreement among raft nodes before linearized reading' (duration: 295.81446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.486015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.653439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T07:03:40.486051Z","caller":"traceutil/trace.go:171","msg":"trace[1074350210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26942; }","duration":"222.705641ms","start":"2026-09-19T07:03:40.263337Z","end":"2026-09-19T07:03:40.486042Z","steps":["trace[1074350210] 'agreement among raft nodes before linearized reading' (duration: 222.559027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.486082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.918506ms","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-09-19T07:03:40.486132Z","caller":"traceutil/trace.go:171","msg":"trace[1915183312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26942; }","duration":"223.011228ms","start":"2026-09-19T07:03:40.26311Z","end":"2026-09-19T07:03:40.486121Z","steps":["trace[1915183312] 'agreement among raft nodes before linearized reading' (duration: 222.857534ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:40.824331Z","caller":"traceutil/trace.go:171","msg":"trace[1155313742] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"333.47771ms","start":"2026-09-19T07:03:40.490829Z","end":"2026-09-19T07:03:40.824307Z","steps":["trace[1155313742] 'process raft request' (duration: 333.316726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:40.824508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.490796Z","time spent":"333.629994ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:41.008323Z","caller":"traceutil/trace.go:171","msg":"trace[1490109286] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"512.998925ms","start":"2026-09-19T07:03:40.495304Z","end":"2026-09-19T07:03:41.008303Z","steps":["trace[1490109286] 'process raft request' (duration: 464.580264ms)","trace[1490109286] 'compare' (duration: 48.059313ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:41.008454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.495287Z","time spent":"513.106187ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:41.008498Z","caller":"traceutil/trace.go:171","msg":"trace[185529769] linearizableReadLoop","detail":"{readStateIndex:27841; appliedIndex:27840; }","duration":"352.86007ms","start":"2026-09-19T07:03:40.655613Z","end":"2026-09-19T07:03:41.008474Z","steps":["trace[185529769] 'read index received' (duration: 168.731503ms)","trace[185529769] 'applied index is now lower than readState.Index' (duration: 184.127337ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:41.008668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.041474ms","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-09-19T07:03:41.008693Z","caller":"traceutil/trace.go:171","msg":"trace[240787153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26945; }","duration":"353.140437ms","start":"2026-09-19T07:03:40.655545Z","end":"2026-09-19T07:03:41.008685Z","steps":["trace[240787153] 'agreement among raft nodes before linearized reading' (duration: 353.002644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:41.008714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.655511Z","time spent":"353.196548ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T07:03:41.008716Z","caller":"traceutil/trace.go:171","msg":"trace[1012481858] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"343.893313ms","start":"2026-09-19T07:03:40.664814Z","end":"2026-09-19T07:03:41.008707Z","steps":["trace[1012481858] 'process raft request' (duration: 343.431572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:41.008755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:40.664775Z","time spent":"343.957665ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:41.345457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.953744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:41.345678Z","caller":"traceutil/trace.go:171","msg":"trace[533237805] linearizableReadLoop","detail":"{readStateIndex:27843; appliedIndex:27842; }","duration":"155.500574ms","start":"2026-09-19T07:03:41.190137Z","end":"2026-09-19T07:03:41.345638Z","steps":["trace[533237805] 'read index received' (duration: 73.002µs)","trace[533237805] 'applied index is now lower than readState.Index' (duration: 155.423622ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:41.345684Z","caller":"traceutil/trace.go:171","msg":"trace[1770963276] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"331.301789ms","start":"2026-09-19T07:03:41.014306Z","end":"2026-09-19T07:03:41.345607Z","steps":["trace[1770963276] 'process raft request' (duration: 151.089657ms)","trace[1770963276] 'compare' (duration: 179.820811ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:41.345809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.660138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:41.345842Z","caller":"traceutil/trace.go:171","msg":"trace[121702592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26947; }","duration":"155.75416ms","start":"2026-09-19T07:03:41.190077Z","end":"2026-09-19T07:03:41.345831Z","steps":["trace[121702592] 'agreement among raft nodes before linearized reading' (duration: 155.659858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:41.345862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:41.014281Z","time spent":"331.481923ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:41.713442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.213861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:41.713566Z","caller":"traceutil/trace.go:171","msg":"trace[1848499869] linearizableReadLoop","detail":"{readStateIndex:27845; appliedIndex:27844; }","duration":"106.662691ms","start":"2026-09-19T07:03:41.606882Z","end":"2026-09-19T07:03:41.713545Z","steps":["trace[1848499869] 'read index received' (duration: 61.631µs)","trace[1848499869] 'applied index is now lower than readState.Index' (duration: 106.59876ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:41.713663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.776894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:41.713786Z","caller":"traceutil/trace.go:171","msg":"trace[1155558587] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26948; }","duration":"106.934679ms","start":"2026-09-19T07:03:41.606825Z","end":"2026-09-19T07:03:41.71376Z","steps":["trace[1155558587] 'agreement among raft nodes before linearized reading' (duration: 106.784605ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:41.713683Z","caller":"traceutil/trace.go:171","msg":"trace[1598860806] transaction","detail":"{read_only:false; response_revision:26948; number_of_response:1; }","duration":"314.84468ms","start":"2026-09-19T07:03:41.39877Z","end":"2026-09-19T07:03:41.713615Z","steps":["trace[1598860806] 'process raft request' (duration: 127.332371ms)","trace[1598860806] 'compare' (duration: 187.064237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:41.713961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:41.398749Z","time spent":"315.107776ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:41.909008Z","caller":"traceutil/trace.go:171","msg":"trace[1880583052] transaction","detail":"{read_only:false; response_revision:26949; number_of_response:1; }","duration":"277.041815ms","start":"2026-09-19T07:03:41.631941Z","end":"2026-09-19T07:03:41.908983Z","steps":["trace[1880583052] 'process raft request' (duration: 276.83963ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:41.926473Z","caller":"traceutil/trace.go:171","msg":"trace[1563152297] transaction","detail":"{read_only:false; response_revision:26950; number_of_response:1; }","duration":"182.390275ms","start":"2026-09-19T07:03:41.744063Z","end":"2026-09-19T07:03:41.926453Z","steps":["trace[1563152297] 'process raft request' (duration: 182.268802ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:42.739178Z","caller":"traceutil/trace.go:171","msg":"trace[1277341710] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"245.535642ms","start":"2026-09-19T07:03:42.49362Z","end":"2026-09-19T07:03:42.739155Z","steps":["trace[1277341710] 'process raft request' (duration: 245.43319ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:42.739453Z","caller":"traceutil/trace.go:171","msg":"trace[1055749124] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"245.898632ms","start":"2026-09-19T07:03:42.493538Z","end":"2026-09-19T07:03:42.739436Z","steps":["trace[1055749124] 'process raft request' (duration: 223.647643ms)","trace[1055749124] 'compare' (duration: 21.497871ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:42.739445Z","caller":"traceutil/trace.go:171","msg":"trace[362234610] linearizableReadLoop","detail":"{readStateIndex:27852; appliedIndex:27848; }","duration":"218.865558ms","start":"2026-09-19T07:03:42.520557Z","end":"2026-09-19T07:03:42.739423Z","steps":["trace[362234610] 'read index received' (duration: 14.86095ms)","trace[362234610] 'applied index is now lower than readState.Index' (duration: 204.003108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:42.73965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.084962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-09-19T07:03:42.739692Z","caller":"traceutil/trace.go:171","msg":"trace[844667448] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26955; }","duration":"219.171955ms","start":"2026-09-19T07:03:42.520509Z","end":"2026-09-19T07:03:42.739681Z","steps":["trace[844667448] 'agreement among raft nodes before linearized reading' (duration: 218.99193ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:42.739774Z","caller":"traceutil/trace.go:171","msg":"trace[1544235809] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"246.103907ms","start":"2026-09-19T07:03:42.493659Z","end":"2026-09-19T07:03:42.739763Z","steps":["trace[1544235809] 'process raft request' (duration: 245.45045ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:42.740049Z","caller":"traceutil/trace.go:171","msg":"trace[1415366847] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"184.430953ms","start":"2026-09-19T07:03:42.555606Z","end":"2026-09-19T07:03:42.740037Z","steps":["trace[1415366847] 'process raft request' (duration: 183.746327ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:42.74031Z","caller":"traceutil/trace.go:171","msg":"trace[1075734387] transaction","detail":"{read_only:false; response_revision:26957; number_of_response:1; }","duration":"170.491777ms","start":"2026-09-19T07:03:42.569806Z","end":"2026-09-19T07:03:42.740298Z","steps":["trace[1075734387] 'process raft request' (duration: 170.189849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:43.02068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.949872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:03:43.020852Z","caller":"traceutil/trace.go:171","msg":"trace[1279110067] linearizableReadLoop","detail":"{readStateIndex:27855; appliedIndex:27854; }","duration":"186.505234ms","start":"2026-09-19T07:03:42.834318Z","end":"2026-09-19T07:03:43.020823Z","steps":["trace[1279110067] 'read index received' (duration: 64.180553ms)","trace[1279110067] 'applied index is now lower than readState.Index' (duration: 122.322531ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:43.021005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.682068ms","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-09-19T07:03:43.021052Z","caller":"traceutil/trace.go:171","msg":"trace[1911835856] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26958; }","duration":"186.776321ms","start":"2026-09-19T07:03:42.834258Z","end":"2026-09-19T07:03:43.021034Z","steps":["trace[1911835856] 'agreement among raft nodes before linearized reading' (duration: 186.675788ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:43.020995Z","caller":"traceutil/trace.go:171","msg":"trace[1669456410] transaction","detail":"{read_only:false; response_revision:26958; number_of_response:1; }","duration":"186.666628ms","start":"2026-09-19T07:03:42.834278Z","end":"2026-09-19T07:03:43.020944Z","steps":["trace[1669456410] 'process raft request' (duration: 64.273186ms)","trace[1669456410] 'compare' (duration: 121.731285ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:43.463994Z","caller":"traceutil/trace.go:171","msg":"trace[1268753989] linearizableReadLoop","detail":"{readStateIndex:27857; appliedIndex:27856; }","duration":"274.838852ms","start":"2026-09-19T07:03:43.189113Z","end":"2026-09-19T07:03:43.463952Z","steps":["trace[1268753989] 'read index received' (duration: 274.579125ms)","trace[1268753989] 'applied index is now lower than readState.Index' (duration: 258.217µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:43.464048Z","caller":"traceutil/trace.go:171","msg":"trace[58713600] transaction","detail":"{read_only:false; response_revision:26960; number_of_response:1; }","duration":"424.371631ms","start":"2026-09-19T07:03:43.039647Z","end":"2026-09-19T07:03:43.464018Z","steps":["trace[58713600] 'process raft request' (duration: 424.085904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:43.464184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.048768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-19T07:03:43.4642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:43.039625Z","time spent":"424.490834ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:43.464241Z","caller":"traceutil/trace.go:171","msg":"trace[568804322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26960; }","duration":"275.17334ms","start":"2026-09-19T07:03:43.189051Z","end":"2026-09-19T07:03:43.464225Z","steps":["trace[568804322] 'agreement among raft nodes before linearized reading' (duration: 275.063878ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:43.555022Z","caller":"traceutil/trace.go:171","msg":"trace[541268442] transaction","detail":"{read_only:false; response_revision:26961; number_of_response:1; }","duration":"214.914332ms","start":"2026-09-19T07:03:43.340082Z","end":"2026-09-19T07:03:43.554996Z","steps":["trace[541268442] 'process raft request' (duration: 212.74589ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:43.555253Z","caller":"traceutil/trace.go:171","msg":"trace[1463144484] transaction","detail":"{read_only:false; response_revision:26962; number_of_response:1; }","duration":"204.760295ms","start":"2026-09-19T07:03:43.350468Z","end":"2026-09-19T07:03:43.555229Z","steps":["trace[1463144484] 'process raft request' (duration: 204.477719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:43.555537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.086264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-19T07:03:43.555646Z","caller":"traceutil/trace.go:171","msg":"trace[432173489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26962; }","duration":"205.279048ms","start":"2026-09-19T07:03:43.350344Z","end":"2026-09-19T07:03:43.555623Z","steps":["trace[432173489] 'agreement among raft nodes before linearized reading' (duration: 204.937991ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:50.035478Z","caller":"traceutil/trace.go:171","msg":"trace[1570919812] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"281.298028ms","start":"2026-09-19T07:03:49.754152Z","end":"2026-09-19T07:03:50.03545Z","steps":["trace[1570919812] 'process raft request' (duration: 281.105293ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:51.128689Z","caller":"traceutil/trace.go:171","msg":"trace[1357601484] transaction","detail":"{read_only:false; response_revision:27012; number_of_response:1; }","duration":"686.453395ms","start":"2026-09-19T07:03:50.442212Z","end":"2026-09-19T07:03:51.128665Z","steps":["trace[1357601484] 'process raft request' (duration: 686.198818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.128816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.442184Z","time spent":"686.574088ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T07:03:51.767454Z","caller":"traceutil/trace.go:171","msg":"trace[545518114] transaction","detail":"{read_only:false; response_revision:27013; number_of_response:1; }","duration":"1.168484521s","start":"2026-09-19T07:03:50.598942Z","end":"2026-09-19T07:03:51.767426Z","steps":["trace[545518114] 'process raft request' (duration: 1.168258465s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.767614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.598913Z","time spent":"1.168614773s","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:51.767791Z","caller":"traceutil/trace.go:171","msg":"trace[1432548417] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"996.731923ms","start":"2026-09-19T07:03:50.771044Z","end":"2026-09-19T07:03:51.767775Z","steps":["trace[1432548417] 'process raft request' (duration: 996.588809ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:51.767845Z","caller":"traceutil/trace.go:171","msg":"trace[1919014129] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"996.661581ms","start":"2026-09-19T07:03:50.771161Z","end":"2026-09-19T07:03:51.767823Z","steps":["trace[1919014129] 'process raft request' (duration: 996.533348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.767888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.77103Z","time spent":"996.820724ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:51.767854Z","caller":"traceutil/trace.go:171","msg":"trace[1401888972] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"996.762933ms","start":"2026-09-19T07:03:50.771008Z","end":"2026-09-19T07:03:51.767771Z","steps":["trace[1401888972] 'process raft request' (duration: 996.371554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.767906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.771116Z","time spent":"996.757303ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:03:51.767949Z","caller":"traceutil/trace.go:171","msg":"trace[1831952641] linearizableReadLoop","detail":"{readStateIndex:27912; appliedIndex:27910; }","duration":"1.096985369s","start":"2026-09-19T07:03:50.670937Z","end":"2026-09-19T07:03:51.767922Z","steps":["trace[1831952641] 'read index received' (duration: 457.709438ms)","trace[1831952641] 'applied index is now lower than readState.Index' (duration: 639.274431ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:51.76806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.770976Z","time spent":"996.971338ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:51.768116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097156584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:51.768162Z","caller":"traceutil/trace.go:171","msg":"trace[1433435288] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:27017; }","duration":"1.097252416s","start":"2026-09-19T07:03:50.670893Z","end":"2026-09-19T07:03:51.768146Z","steps":["trace[1433435288] 'agreement among raft nodes before linearized reading' (duration: 1.097146284s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.768199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.670831Z","time spent":"1.097356379s","remote":"127.0.0.1:60800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-09-19T07:03:51.768436Z","caller":"traceutil/trace.go:171","msg":"trace[1642102312] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"707.539972ms","start":"2026-09-19T07:03:51.060439Z","end":"2026-09-19T07:03:51.767979Z","steps":["trace[1642102312] 'process raft request' (duration: 707.379429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.769011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.060411Z","time spent":"708.507666ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:03:51.829385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.759663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:51.830405Z","caller":"traceutil/trace.go:171","msg":"trace[110966833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27018; }","duration":"640.830809ms","start":"2026-09-19T07:03:51.189515Z","end":"2026-09-19T07:03:51.830346Z","steps":["trace[110966833] 'agreement among raft nodes before linearized reading' (duration: 639.730233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.830536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.189497Z","time spent":"641.021333ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T07:03:51.829402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050442923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-19T07:03:51.830793Z","caller":"traceutil/trace.go:171","msg":"trace[934226149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27018; }","duration":"1.051919579s","start":"2026-09-19T07:03:50.778854Z","end":"2026-09-19T07:03:51.830773Z","steps":["trace[934226149] 'agreement among raft nodes before linearized reading' (duration: 1.050358951s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.830846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:50.778829Z","time spent":"1.05199936s","remote":"127.0.0.1:41672","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-09-19T07:03:51.82948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.832524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-19T07:03:51.830911Z","caller":"traceutil/trace.go:171","msg":"trace[72400136] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:27018; }","duration":"719.266699ms","start":"2026-09-19T07:03:51.111626Z","end":"2026-09-19T07:03:51.830893Z","steps":["trace[72400136] 'agreement among raft nodes before linearized reading' (duration: 717.791213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.829512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.771244ms","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-09-19T07:03:51.830969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.111571Z","time spent":"719.380111ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-09-19T07:03:51.831017Z","caller":"traceutil/trace.go:171","msg":"trace[1538407596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27018; }","duration":"748.26553ms","start":"2026-09-19T07:03:51.082692Z","end":"2026-09-19T07:03:51.830958Z","steps":["trace[1538407596] 'agreement among raft nodes before linearized reading' (duration: 746.759674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.829499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.534149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"warn","ts":"2026-09-19T07:03:51.831152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.082671Z","time spent":"748.431504ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T07:03:51.831175Z","caller":"traceutil/trace.go:171","msg":"trace[846247633] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27018; }","duration":"696.238891ms","start":"2026-09-19T07:03:51.134912Z","end":"2026-09-19T07:03:51.831151Z","steps":["trace[846247633] 'agreement among raft nodes before linearized reading' (duration: 694.410417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.831234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.134896Z","time spent":"696.316532ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1673,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-19T07:03:51.829485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.846697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-19T07:03:51.831286Z","caller":"traceutil/trace.go:171","msg":"trace[542913434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27018; }","duration":"232.657451ms","start":"2026-09-19T07:03:51.598615Z","end":"2026-09-19T07:03:51.831272Z","steps":["trace[542913434] 'agreement among raft nodes before linearized reading' (duration: 230.671463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.829572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.886523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:51.831464Z","caller":"traceutil/trace.go:171","msg":"trace[1058597453] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:27018; }","duration":"345.763428ms","start":"2026-09-19T07:03:51.485637Z","end":"2026-09-19T07:03:51.831401Z","steps":["trace[1058597453] 'agreement among raft nodes before linearized reading' (duration: 343.909003ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:51.829571Z","caller":"traceutil/trace.go:171","msg":"trace[1730006454] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"240.454249ms","start":"2026-09-19T07:03:51.589098Z","end":"2026-09-19T07:03:51.829552Z","steps":["trace[1730006454] 'process raft request' (duration: 239.950897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.829629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.559461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T07:03:51.831677Z","caller":"traceutil/trace.go:171","msg":"trace[1106859713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27018; }","duration":"332.620561ms","start":"2026-09-19T07:03:51.499035Z","end":"2026-09-19T07:03:51.831655Z","steps":["trace[1106859713] 'agreement among raft nodes before linearized reading' (duration: 330.493138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.831724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.499023Z","time spent":"332.687442ms","remote":"127.0.0.1:41672","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":"warn","ts":"2026-09-19T07:03:51.829654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.303611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-09-19T07:03:51.831785Z","caller":"traceutil/trace.go:171","msg":"trace[167574962] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27018; }","duration":"693.449833ms","start":"2026-09-19T07:03:51.138317Z","end":"2026-09-19T07:03:51.831767Z","steps":["trace[167574962] 'agreement among raft nodes before linearized reading' (duration: 691.216498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:51.831882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.138311Z","time spent":"693.553825ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1673,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-19T07:03:51.831673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:03:51.485617Z","time spent":"345.896161ms","remote":"127.0.0.1:56710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-09-19T07:03:52.10146Z","caller":"traceutil/trace.go:171","msg":"trace[1730463919] linearizableReadLoop","detail":"{readStateIndex:27918; appliedIndex:27917; }","duration":"261.166421ms","start":"2026-09-19T07:03:51.840262Z","end":"2026-09-19T07:03:52.101428Z","steps":["trace[1730463919] 'read index received' (duration: 240.128591ms)","trace[1730463919] 'applied index is now lower than readState.Index' (duration: 21.03614ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:03:52.101594Z","caller":"traceutil/trace.go:171","msg":"trace[155532076] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"265.554128ms","start":"2026-09-19T07:03:51.83602Z","end":"2026-09-19T07:03:52.101574Z","steps":["trace[155532076] 'process raft request' (duration: 244.214312ms)","trace[155532076] 'compare' (duration: 20.850584ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:03:52.101678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.397997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:03:52.101742Z","caller":"traceutil/trace.go:171","msg":"trace[407220455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27019; }","duration":"261.51475ms","start":"2026-09-19T07:03:51.840212Z","end":"2026-09-19T07:03:52.101727Z","steps":["trace[407220455] 'agreement among raft nodes before linearized reading' (duration: 261.405417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:03:52.124339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.902904ms","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-09-19T07:03:52.124426Z","caller":"traceutil/trace.go:171","msg":"trace[578724860] transaction","detail":"{read_only:false; response_revision:27022; number_of_response:1; }","duration":"283.372568ms","start":"2026-09-19T07:03:51.841033Z","end":"2026-09-19T07:03:52.124406Z","steps":["trace[578724860] 'process raft request' (duration: 283.00837ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:52.124471Z","caller":"traceutil/trace.go:171","msg":"trace[300793749] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27024; }","duration":"187.117518ms","start":"2026-09-19T07:03:51.93733Z","end":"2026-09-19T07:03:52.124447Z","steps":["trace[300793749] 'agreement among raft nodes before linearized reading' (duration: 186.892973ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:52.124729Z","caller":"traceutil/trace.go:171","msg":"trace[1170996679] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"284.431144ms","start":"2026-09-19T07:03:51.840284Z","end":"2026-09-19T07:03:52.124715Z","steps":["trace[1170996679] 'process raft request' (duration: 283.551333ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:52.124929Z","caller":"traceutil/trace.go:171","msg":"trace[1367171757] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"284.544937ms","start":"2026-09-19T07:03:51.840312Z","end":"2026-09-19T07:03:52.124857Z","steps":["trace[1367171757] 'process raft request' (duration: 283.666545ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:03:52.124672Z","caller":"traceutil/trace.go:171","msg":"trace[250026650] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"282.976739ms","start":"2026-09-19T07:03:51.841678Z","end":"2026-09-19T07:03:52.124655Z","steps":["trace[250026650] 'process raft request' (duration: 282.435136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:04.146737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.961341ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-19T07:04:04.180208Z","caller":"traceutil/trace.go:171","msg":"trace[1567919464] transaction","detail":"{read_only:false; response_revision:27188; number_of_response:1; }","duration":"318.270473ms","start":"2026-09-19T07:04:03.861911Z","end":"2026-09-19T07:04:04.180182Z","steps":["trace[1567919464] 'process raft request' (duration: 317.999856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:04.180399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:04:03.861881Z","time spent":"318.409086ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:04:04.180674Z","caller":"traceutil/trace.go:171","msg":"trace[11909250] transaction","detail":"{read_only:false; response_revision:27189; number_of_response:1; }","duration":"311.292264ms","start":"2026-09-19T07:04:03.869354Z","end":"2026-09-19T07:04:04.180646Z","steps":["trace[11909250] 'process raft request' (duration: 310.763391ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:04.180688Z","caller":"traceutil/trace.go:171","msg":"trace[1880704910] transaction","detail":"{read_only:false; response_revision:27190; number_of_response:1; }","duration":"295.756728ms","start":"2026-09-19T07:04:03.884913Z","end":"2026-09-19T07:04:04.18067Z","steps":["trace[1880704910] 'process raft request' (duration: 295.617545ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:04.180899Z","caller":"traceutil/trace.go:171","msg":"trace[793344101] transaction","detail":"{read_only:false; response_revision:27192; number_of_response:1; }","duration":"287.771554ms","start":"2026-09-19T07:04:03.893118Z","end":"2026-09-19T07:04:04.18089Z","steps":["trace[793344101] 'process raft request' (duration: 287.611391ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:04.180887Z","caller":"traceutil/trace.go:171","msg":"trace[1775069022] transaction","detail":"{read_only:false; response_revision:27193; number_of_response:1; }","duration":"287.606501ms","start":"2026-09-19T07:04:03.893259Z","end":"2026-09-19T07:04:04.180865Z","steps":["trace[1775069022] 'process raft request' (duration: 287.56937ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:04.180834Z","caller":"traceutil/trace.go:171","msg":"trace[1516583186] transaction","detail":"{read_only:false; response_revision:27191; number_of_response:1; }","duration":"287.791915ms","start":"2026-09-19T07:04:03.893025Z","end":"2026-09-19T07:04:04.180817Z","steps":["trace[1516583186] 'process raft request' (duration: 287.6042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:04.180846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:04:03.869331Z","time spent":"311.411846ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:04:05.984893Z","caller":"traceutil/trace.go:171","msg":"trace[1609141643] transaction","detail":"{read_only:false; response_revision:27202; number_of_response:1; }","duration":"260.70228ms","start":"2026-09-19T07:04:05.724164Z","end":"2026-09-19T07:04:05.984866Z","steps":["trace[1609141643] 'process raft request' (duration: 260.537575ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:14.936034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23797} {"level":"info","ts":"2026-09-19T07:04:15.058918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23797,"took":"117.796701ms","hash":711608288,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":45281280,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-19T07:04:15.058997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":711608288,"revision":23797,"compact-revision":21602} {"level":"info","ts":"2026-09-19T07:04:26.44185Z","caller":"traceutil/trace.go:171","msg":"trace[1718711430] transaction","detail":"{read_only:false; response_revision:27366; number_of_response:1; }","duration":"168.95833ms","start":"2026-09-19T07:04:26.272855Z","end":"2026-09-19T07:04:26.441813Z","steps":["trace[1718711430] 'process raft request' (duration: 168.698904ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.442135Z","caller":"traceutil/trace.go:171","msg":"trace[1918862820] transaction","detail":"{read_only:false; response_revision:27367; number_of_response:1; }","duration":"157.897841ms","start":"2026-09-19T07:04:26.28421Z","end":"2026-09-19T07:04:26.442108Z","steps":["trace[1918862820] 'process raft request' (duration: 157.549733ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.442411Z","caller":"traceutil/trace.go:171","msg":"trace[391563035] transaction","detail":"{read_only:false; response_revision:27368; number_of_response:1; }","duration":"157.892601ms","start":"2026-09-19T07:04:26.284502Z","end":"2026-09-19T07:04:26.442395Z","steps":["trace[391563035] 'process raft request' (duration: 157.485892ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.442519Z","caller":"traceutil/trace.go:171","msg":"trace[1703426337] transaction","detail":"{read_only:false; response_revision:27369; number_of_response:1; }","duration":"157.920613ms","start":"2026-09-19T07:04:26.284564Z","end":"2026-09-19T07:04:26.442485Z","steps":["trace[1703426337] 'process raft request' (duration: 157.688767ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.442583Z","caller":"traceutil/trace.go:171","msg":"trace[472577295] transaction","detail":"{read_only:false; response_revision:27370; number_of_response:1; }","duration":"155.884353ms","start":"2026-09-19T07:04:26.286668Z","end":"2026-09-19T07:04:26.442553Z","steps":["trace[472577295] 'process raft request' (duration: 155.730908ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.44265Z","caller":"traceutil/trace.go:171","msg":"trace[1503251070] transaction","detail":"{read_only:false; response_revision:27371; number_of_response:1; }","duration":"154.213453ms","start":"2026-09-19T07:04:26.288417Z","end":"2026-09-19T07:04:26.44263Z","steps":["trace[1503251070] 'process raft request' (duration: 154.033318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:26.670871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.816622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:04:26.671Z","caller":"traceutil/trace.go:171","msg":"trace[978555109] transaction","detail":"{read_only:false; response_revision:27372; number_of_response:1; }","duration":"222.165917ms","start":"2026-09-19T07:04:26.448811Z","end":"2026-09-19T07:04:26.670977Z","steps":["trace[978555109] 'process raft request' (duration: 118.13455ms)","trace[978555109] 'compare' (duration: 103.569466ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:26.709729Z","caller":"traceutil/trace.go:171","msg":"trace[149053552] transaction","detail":"{read_only:false; response_revision:27377; number_of_response:1; }","duration":"152.41145ms","start":"2026-09-19T07:04:26.557286Z","end":"2026-09-19T07:04:26.709697Z","steps":["trace[149053552] 'process raft request' (duration: 152.361259ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.710213Z","caller":"traceutil/trace.go:171","msg":"trace[1067732352] transaction","detail":"{read_only:false; response_revision:27373; number_of_response:1; }","duration":"260.854973ms","start":"2026-09-19T07:04:26.449347Z","end":"2026-09-19T07:04:26.710202Z","steps":["trace[1067732352] 'process raft request' (duration: 259.962471ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.710398Z","caller":"traceutil/trace.go:171","msg":"trace[943577411] transaction","detail":"{read_only:false; response_revision:27374; number_of_response:1; }","duration":"260.905234ms","start":"2026-09-19T07:04:26.449482Z","end":"2026-09-19T07:04:26.710388Z","steps":["trace[943577411] 'process raft request' (duration: 260.004413ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.710554Z","caller":"traceutil/trace.go:171","msg":"trace[1494802538] transaction","detail":"{read_only:false; response_revision:27375; number_of_response:1; }","duration":"261.008248ms","start":"2026-09-19T07:04:26.449536Z","end":"2026-09-19T07:04:26.710544Z","steps":["trace[1494802538] 'process raft request' (duration: 259.992993ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:26.710672Z","caller":"traceutil/trace.go:171","msg":"trace[1951178132] transaction","detail":"{read_only:false; response_revision:27376; number_of_response:1; }","duration":"260.873164ms","start":"2026-09-19T07:04:26.449791Z","end":"2026-09-19T07:04:26.710664Z","steps":["trace[1951178132] 'process raft request' (duration: 259.776578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:28.020775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.556018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:04:28.020912Z","caller":"traceutil/trace.go:171","msg":"trace[1128762680] transaction","detail":"{read_only:false; response_revision:27380; number_of_response:1; }","duration":"210.856753ms","start":"2026-09-19T07:04:27.810032Z","end":"2026-09-19T07:04:28.020889Z","steps":["trace[1128762680] 'process raft request' (duration: 97.061269ms)","trace[1128762680] 'compare' (duration: 113.364464ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:34.598917Z","caller":"traceutil/trace.go:171","msg":"trace[552237427] transaction","detail":"{read_only:false; response_revision:27430; number_of_response:1; }","duration":"120.329652ms","start":"2026-09-19T07:04:34.478561Z","end":"2026-09-19T07:04:34.598891Z","steps":["trace[552237427] 'process raft request' (duration: 112.766349ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:34.599336Z","caller":"traceutil/trace.go:171","msg":"trace[782565804] transaction","detail":"{read_only:false; response_revision:27431; number_of_response:1; }","duration":"118.066228ms","start":"2026-09-19T07:04:34.48124Z","end":"2026-09-19T07:04:34.599306Z","steps":["trace[782565804] 'process raft request' (duration: 117.608066ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:34.599407Z","caller":"traceutil/trace.go:171","msg":"trace[1487118315] transaction","detail":"{read_only:false; response_revision:27432; number_of_response:1; }","duration":"116.875289ms","start":"2026-09-19T07:04:34.482478Z","end":"2026-09-19T07:04:34.599353Z","steps":["trace[1487118315] 'process raft request' (duration: 116.670463ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:34.599587Z","caller":"traceutil/trace.go:171","msg":"trace[718222622] transaction","detail":"{read_only:false; response_revision:27433; number_of_response:1; }","duration":"116.111751ms","start":"2026-09-19T07:04:34.483461Z","end":"2026-09-19T07:04:34.599573Z","steps":["trace[718222622] 'process raft request' (duration: 115.842455ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:34.599706Z","caller":"traceutil/trace.go:171","msg":"trace[134164908] transaction","detail":"{read_only:false; response_revision:27434; number_of_response:1; }","duration":"116.043008ms","start":"2026-09-19T07:04:34.48365Z","end":"2026-09-19T07:04:34.599693Z","steps":["trace[134164908] 'process raft request' (duration: 115.775752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.415078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.38687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-19T07:04:40.415194Z","caller":"traceutil/trace.go:171","msg":"trace[1869701819] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27473; }","duration":"112.552954ms","start":"2026-09-19T07:04:40.302603Z","end":"2026-09-19T07:04:40.415156Z","steps":["trace[1869701819] 'range keys from in-memory index tree' (duration: 112.022421ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.608448Z","caller":"traceutil/trace.go:171","msg":"trace[1069851896] linearizableReadLoop","detail":"{readStateIndex:28386; appliedIndex:28385; }","duration":"232.323633ms","start":"2026-09-19T07:04:40.376093Z","end":"2026-09-19T07:04:40.608416Z","steps":["trace[1069851896] 'read index received' (duration: 232.18945ms)","trace[1069851896] 'applied index is now lower than readState.Index' (duration: 132.773µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:40.608546Z","caller":"traceutil/trace.go:171","msg":"trace[152407776] transaction","detail":"{read_only:false; response_revision:27474; number_of_response:1; }","duration":"259.150672ms","start":"2026-09-19T07:04:40.34934Z","end":"2026-09-19T07:04:40.608491Z","steps":["trace[152407776] 'process raft request' (duration: 258.868175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.608638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.517487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:04:40.608688Z","caller":"traceutil/trace.go:171","msg":"trace[1872354674] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:27474; }","duration":"232.61626ms","start":"2026-09-19T07:04:40.376053Z","end":"2026-09-19T07:04:40.608669Z","steps":["trace[1872354674] 'agreement among raft nodes before linearized reading' (duration: 232.512617ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.734738Z","caller":"traceutil/trace.go:171","msg":"trace[133502934] linearizableReadLoop","detail":"{readStateIndex:28387; appliedIndex:28386; }","duration":"126.174193ms","start":"2026-09-19T07:04:40.608532Z","end":"2026-09-19T07:04:40.734707Z","steps":["trace[133502934] 'read index received' (duration: 49.092798ms)","trace[133502934] 'applied index is now lower than readState.Index' (duration: 77.053064ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:40.734735Z","caller":"traceutil/trace.go:171","msg":"trace[188013586] transaction","detail":"{read_only:false; response_revision:27475; number_of_response:1; }","duration":"159.08926ms","start":"2026-09-19T07:04:40.575604Z","end":"2026-09-19T07:04:40.734693Z","steps":["trace[188013586] 'process raft request' (duration: 82.024385ms)","trace[188013586] 'compare' (duration: 76.902151ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:04:40.734901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.332864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:04:40.734959Z","caller":"traceutil/trace.go:171","msg":"trace[1252065226] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:27475; }","duration":"344.424995ms","start":"2026-09-19T07:04:40.390518Z","end":"2026-09-19T07:04:40.734943Z","steps":["trace[1252065226] 'agreement among raft nodes before linearized reading' (duration: 344.303253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.734949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.41174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-19T07:04:40.735002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:04:40.390508Z","time spent":"344.483207ms","remote":"127.0.0.1:39830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"info","ts":"2026-09-19T07:04:40.735005Z","caller":"traceutil/trace.go:171","msg":"trace[1187827395] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27475; }","duration":"314.506582ms","start":"2026-09-19T07:04:40.420485Z","end":"2026-09-19T07:04:40.734992Z","steps":["trace[1187827395] 'agreement among raft nodes before linearized reading' (duration: 314.200383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.735047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:04:40.42046Z","time spent":"314.579154ms","remote":"127.0.0.1:41610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-19T07:04:40.803518Z","caller":"traceutil/trace.go:171","msg":"trace[1556703871] transaction","detail":"{read_only:false; response_revision:27476; number_of_response:1; }","duration":"185.227962ms","start":"2026-09-19T07:04:40.618268Z","end":"2026-09-19T07:04:40.803496Z","steps":["trace[1556703871] 'process raft request' (duration: 184.720321ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.803688Z","caller":"traceutil/trace.go:171","msg":"trace[1179516610] transaction","detail":"{read_only:false; response_revision:27479; number_of_response:1; }","duration":"181.719708ms","start":"2026-09-19T07:04:40.621928Z","end":"2026-09-19T07:04:40.803648Z","steps":["trace[1179516610] 'process raft request' (duration: 181.262657ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.803968Z","caller":"traceutil/trace.go:171","msg":"trace[179858598] transaction","detail":"{read_only:false; response_revision:27477; number_of_response:1; }","duration":"185.417888ms","start":"2026-09-19T07:04:40.618533Z","end":"2026-09-19T07:04:40.80395Z","steps":["trace[179858598] 'process raft request' (duration: 184.576367ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.804094Z","caller":"traceutil/trace.go:171","msg":"trace[1396073119] transaction","detail":"{read_only:false; response_revision:27478; number_of_response:1; }","duration":"184.144058ms","start":"2026-09-19T07:04:40.619938Z","end":"2026-09-19T07:04:40.804082Z","steps":["trace[1396073119] 'process raft request' (duration: 183.214805ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.803762Z","caller":"traceutil/trace.go:171","msg":"trace[1697888090] transaction","detail":"{read_only:false; response_revision:27480; number_of_response:1; }","duration":"180.255402ms","start":"2026-09-19T07:04:40.623491Z","end":"2026-09-19T07:04:40.803746Z","steps":["trace[1697888090] 'process raft request' (duration: 179.73859ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.937506Z","caller":"traceutil/trace.go:171","msg":"trace[233509848] linearizableReadLoop","detail":"{readStateIndex:28393; appliedIndex:28392; }","duration":"134.205428ms","start":"2026-09-19T07:04:40.803262Z","end":"2026-09-19T07:04:40.937468Z","steps":["trace[233509848] 'read index received' (duration: 78.003577ms)","trace[233509848] 'applied index is now lower than readState.Index' (duration: 56.200361ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:04:40.937852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.173936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T07:04:40.937929Z","caller":"traceutil/trace.go:171","msg":"trace[675101997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27481; }","duration":"148.289679ms","start":"2026-09-19T07:04:40.789622Z","end":"2026-09-19T07:04:40.937911Z","steps":["trace[675101997] 'agreement among raft nodes before linearized reading' (duration: 148.048354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.937993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.867692ms","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-09-19T07:04:40.938057Z","caller":"traceutil/trace.go:171","msg":"trace[1197455558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27481; }","duration":"149.084007ms","start":"2026-09-19T07:04:40.788958Z","end":"2026-09-19T07:04:40.938042Z","steps":["trace[1197455558] 'agreement among raft nodes before linearized reading' (duration: 148.927414ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:40.938075Z","caller":"traceutil/trace.go:171","msg":"trace[939464215] transaction","detail":"{read_only:false; response_revision:27481; number_of_response:1; }","duration":"166.53095ms","start":"2026-09-19T07:04:40.77152Z","end":"2026-09-19T07:04:40.938051Z","steps":["trace[939464215] 'process raft request' (duration: 109.787866ms)","trace[939464215] 'compare' (duration: 54.356396ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:04:40.938283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.047981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-19T07:04:40.938352Z","caller":"traceutil/trace.go:171","msg":"trace[307903015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27481; }","duration":"143.134515ms","start":"2026-09-19T07:04:40.795199Z","end":"2026-09-19T07:04:40.938334Z","steps":["trace[307903015] 'agreement among raft nodes before linearized reading' (duration: 142.982671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.938312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.477228ms","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-09-19T07:04:40.93853Z","caller":"traceutil/trace.go:171","msg":"trace[430751501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27481; }","duration":"185.724865ms","start":"2026-09-19T07:04:40.752791Z","end":"2026-09-19T07:04:40.938516Z","steps":["trace[430751501] 'agreement among raft nodes before linearized reading' (duration: 185.457568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:40.937978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.810975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-19T07:04:40.938695Z","caller":"traceutil/trace.go:171","msg":"trace[25214508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27481; }","duration":"138.573624ms","start":"2026-09-19T07:04:40.800104Z","end":"2026-09-19T07:04:40.938678Z","steps":["trace[25214508] 'agreement among raft nodes before linearized reading' (duration: 137.624231ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:41.129422Z","caller":"traceutil/trace.go:171","msg":"trace[332058953] transaction","detail":"{read_only:false; response_revision:27484; number_of_response:1; }","duration":"184.064265ms","start":"2026-09-19T07:04:40.945332Z","end":"2026-09-19T07:04:41.129396Z","steps":["trace[332058953] 'process raft request' (duration: 183.984053ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:41.129747Z","caller":"traceutil/trace.go:171","msg":"trace[1756324657] transaction","detail":"{read_only:false; response_revision:27485; number_of_response:1; }","duration":"183.712557ms","start":"2026-09-19T07:04:40.946006Z","end":"2026-09-19T07:04:41.129718Z","steps":["trace[1756324657] 'process raft request' (duration: 183.345657ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:41.129836Z","caller":"traceutil/trace.go:171","msg":"trace[1118091726] transaction","detail":"{read_only:false; response_revision:27483; number_of_response:1; }","duration":"185.52344ms","start":"2026-09-19T07:04:40.944299Z","end":"2026-09-19T07:04:41.129822Z","steps":["trace[1118091726] 'process raft request' (duration: 184.841304ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:41.130129Z","caller":"traceutil/trace.go:171","msg":"trace[1915004514] linearizableReadLoop","detail":"{readStateIndex:28398; appliedIndex:28393; }","duration":"182.200259ms","start":"2026-09-19T07:04:40.947918Z","end":"2026-09-19T07:04:41.130118Z","steps":["trace[1915004514] 'read index received' (duration: 35.478269ms)","trace[1915004514] 'applied index is now lower than readState.Index' (duration: 146.72095ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:41.130278Z","caller":"traceutil/trace.go:171","msg":"trace[1562060340] transaction","detail":"{read_only:false; response_revision:27486; number_of_response:1; }","duration":"183.156903ms","start":"2026-09-19T07:04:40.947109Z","end":"2026-09-19T07:04:41.130266Z","steps":["trace[1562060340] 'process raft request' (duration: 182.522287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:41.13049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.556878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-19T07:04:41.130535Z","caller":"traceutil/trace.go:171","msg":"trace[1727411901] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27486; }","duration":"182.738192ms","start":"2026-09-19T07:04:40.947787Z","end":"2026-09-19T07:04:41.130525Z","steps":["trace[1727411901] 'agreement among raft nodes before linearized reading' (duration: 182.476116ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:44.784322Z","caller":"traceutil/trace.go:171","msg":"trace[1130473235] linearizableReadLoop","detail":"{readStateIndex:28417; appliedIndex:28416; }","duration":"138.338458ms","start":"2026-09-19T07:04:44.645958Z","end":"2026-09-19T07:04:44.784296Z","steps":["trace[1130473235] 'read index received' (duration: 138.233786ms)","trace[1130473235] 'applied index is now lower than readState.Index' (duration: 103.732µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:04:44.784475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.508622ms","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-09-19T07:04:44.784501Z","caller":"traceutil/trace.go:171","msg":"trace[1142402989] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:27504; }","duration":"138.551983ms","start":"2026-09-19T07:04:44.64594Z","end":"2026-09-19T07:04:44.784492Z","steps":["trace[1142402989] 'agreement among raft nodes before linearized reading' (duration: 138.465801ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:44.784477Z","caller":"traceutil/trace.go:171","msg":"trace[637557836] transaction","detail":"{read_only:false; response_revision:27504; number_of_response:1; }","duration":"143.093562ms","start":"2026-09-19T07:04:44.641346Z","end":"2026-09-19T07:04:44.78444Z","steps":["trace[637557836] 'process raft request' (duration: 142.759555ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:44.876062Z","caller":"traceutil/trace.go:171","msg":"trace[29626648] transaction","detail":"{read_only:false; response_revision:27505; number_of_response:1; }","duration":"121.241764ms","start":"2026-09-19T07:04:44.754765Z","end":"2026-09-19T07:04:44.876007Z","steps":["trace[29626648] 'process raft request' (duration: 68.728664ms)","trace[29626648] 'compare' (duration: 52.06662ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:44.952555Z","caller":"traceutil/trace.go:171","msg":"trace[1137209139] transaction","detail":"{read_only:false; response_revision:27506; number_of_response:1; }","duration":"132.301961ms","start":"2026-09-19T07:04:44.820211Z","end":"2026-09-19T07:04:44.952513Z","steps":["trace[1137209139] 'process raft request' (duration: 131.105673ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:44.952578Z","caller":"traceutil/trace.go:171","msg":"trace[1749337243] transaction","detail":"{read_only:false; response_revision:27507; number_of_response:1; }","duration":"130.461237ms","start":"2026-09-19T07:04:44.822079Z","end":"2026-09-19T07:04:44.952541Z","steps":["trace[1749337243] 'process raft request' (duration: 130.330374ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:44.952875Z","caller":"traceutil/trace.go:171","msg":"trace[177895664] transaction","detail":"{read_only:false; response_revision:27508; number_of_response:1; }","duration":"130.702053ms","start":"2026-09-19T07:04:44.822156Z","end":"2026-09-19T07:04:44.952858Z","steps":["trace[177895664] 'process raft request' (duration: 130.326034ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:44.952877Z","caller":"traceutil/trace.go:171","msg":"trace[1675958213] transaction","detail":"{read_only:false; response_revision:27509; number_of_response:1; }","duration":"130.541329ms","start":"2026-09-19T07:04:44.822304Z","end":"2026-09-19T07:04:44.952845Z","steps":["trace[1675958213] 'process raft request' (duration: 130.436415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:45.188154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.994086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:04:45.18852Z","caller":"traceutil/trace.go:171","msg":"trace[368657473] transaction","detail":"{read_only:false; response_revision:27510; number_of_response:1; }","duration":"364.554163ms","start":"2026-09-19T07:04:44.823941Z","end":"2026-09-19T07:04:45.188495Z","steps":["trace[368657473] 'process raft request' (duration: 196.100456ms)","trace[368657473] 'compare' (duration: 167.738899ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:04:45.188592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:04:44.823909Z","time spent":"364.654246ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T07:04:45.199538Z","caller":"traceutil/trace.go:171","msg":"trace[735998418] linearizableReadLoop","detail":"{readStateIndex:28424; appliedIndex:28422; }","duration":"197.342667ms","start":"2026-09-19T07:04:45.002163Z","end":"2026-09-19T07:04:45.199505Z","steps":["trace[735998418] 'read index received' (duration: 17.894434ms)","trace[735998418] 'applied index is now lower than readState.Index' (duration: 179.447003ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:45.19961Z","caller":"traceutil/trace.go:171","msg":"trace[134773911] transaction","detail":"{read_only:false; response_revision:27511; number_of_response:1; }","duration":"244.57105ms","start":"2026-09-19T07:04:44.955017Z","end":"2026-09-19T07:04:45.199588Z","steps":["trace[134773911] 'process raft request' (duration: 244.298863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:04:45.200342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.151857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-19T07:04:45.200465Z","caller":"traceutil/trace.go:171","msg":"trace[462061232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27511; }","duration":"198.383042ms","start":"2026-09-19T07:04:45.00205Z","end":"2026-09-19T07:04:45.200433Z","steps":["trace[462061232] 'agreement among raft nodes before linearized reading' (duration: 197.583643ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:45.450081Z","caller":"traceutil/trace.go:171","msg":"trace[1397103411] transaction","detail":"{read_only:false; response_revision:27513; number_of_response:1; }","duration":"244.733633ms","start":"2026-09-19T07:04:45.205281Z","end":"2026-09-19T07:04:45.450015Z","steps":["trace[1397103411] 'process raft request' (duration: 200.852101ms)","trace[1397103411] 'compare' (duration: 43.108233ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:45.450787Z","caller":"traceutil/trace.go:171","msg":"trace[1727194302] transaction","detail":"{read_only:false; response_revision:27514; number_of_response:1; }","duration":"245.465081ms","start":"2026-09-19T07:04:45.205283Z","end":"2026-09-19T07:04:45.450748Z","steps":["trace[1727194302] 'process raft request' (duration: 244.391705ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:45.450839Z","caller":"traceutil/trace.go:171","msg":"trace[1615606296] transaction","detail":"{read_only:false; response_revision:27515; number_of_response:1; }","duration":"244.975819ms","start":"2026-09-19T07:04:45.205845Z","end":"2026-09-19T07:04:45.450821Z","steps":["trace[1615606296] 'process raft request' (duration: 244.779694ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:45.451014Z","caller":"traceutil/trace.go:171","msg":"trace[1052538297] transaction","detail":"{read_only:false; response_revision:27516; number_of_response:1; }","duration":"245.05231ms","start":"2026-09-19T07:04:45.205928Z","end":"2026-09-19T07:04:45.45098Z","steps":["trace[1052538297] 'process raft request' (duration: 244.831065ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:51.036552Z","caller":"traceutil/trace.go:171","msg":"trace[684701347] transaction","detail":"{read_only:false; response_revision:27549; number_of_response:1; }","duration":"138.289627ms","start":"2026-09-19T07:04:50.898229Z","end":"2026-09-19T07:04:51.036519Z","steps":["trace[684701347] 'process raft request' (duration: 53.889175ms)","trace[684701347] 'compare' (duration: 84.275589ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:51.260761Z","caller":"traceutil/trace.go:171","msg":"trace[1691406010] transaction","detail":"{read_only:false; response_revision:27550; number_of_response:1; }","duration":"285.757106ms","start":"2026-09-19T07:04:50.974968Z","end":"2026-09-19T07:04:51.260725Z","steps":["trace[1691406010] 'process raft request' (duration: 192.993801ms)","trace[1691406010] 'compare' (duration: 92.383426ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:51.261051Z","caller":"traceutil/trace.go:171","msg":"trace[503053887] transaction","detail":"{read_only:false; response_revision:27551; number_of_response:1; }","duration":"284.901676ms","start":"2026-09-19T07:04:50.976119Z","end":"2026-09-19T07:04:51.26102Z","steps":["trace[503053887] 'process raft request' (duration: 284.510666ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:51.261288Z","caller":"traceutil/trace.go:171","msg":"trace[1399088531] transaction","detail":"{read_only:false; response_revision:27553; number_of_response:1; }","duration":"285.011519ms","start":"2026-09-19T07:04:50.976266Z","end":"2026-09-19T07:04:51.261277Z","steps":["trace[1399088531] 'process raft request' (duration: 284.703821ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:51.261393Z","caller":"traceutil/trace.go:171","msg":"trace[486871143] transaction","detail":"{read_only:false; response_revision:27552; number_of_response:1; }","duration":"285.129301ms","start":"2026-09-19T07:04:50.976206Z","end":"2026-09-19T07:04:51.261336Z","steps":["trace[486871143] 'process raft request' (duration: 284.662489ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:51.349454Z","caller":"traceutil/trace.go:171","msg":"trace[947205026] transaction","detail":"{read_only:false; response_revision:27555; number_of_response:1; }","duration":"125.801554ms","start":"2026-09-19T07:04:51.223616Z","end":"2026-09-19T07:04:51.349417Z","steps":["trace[947205026] 'process raft request' (duration: 125.539868ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:51.469861Z","caller":"traceutil/trace.go:171","msg":"trace[1383184559] linearizableReadLoop","detail":"{readStateIndex:28470; appliedIndex:28469; }","duration":"114.843069ms","start":"2026-09-19T07:04:51.354978Z","end":"2026-09-19T07:04:51.469821Z","steps":["trace[1383184559] 'read index received' (duration: 17.76892ms)","trace[1383184559] 'applied index is now lower than readState.Index' (duration: 97.072789ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:04:51.470023Z","caller":"traceutil/trace.go:171","msg":"trace[941132573] transaction","detail":"{read_only:false; response_revision:27556; number_of_response:1; }","duration":"115.582977ms","start":"2026-09-19T07:04:51.354378Z","end":"2026-09-19T07:04:51.46996Z","steps":["trace[941132573] 'process raft request' (duration: 18.419145ms)","trace[941132573] 'compare' (duration: 96.850904ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:04:51.470145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.142826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-09-19T07:04:51.470183Z","caller":"traceutil/trace.go:171","msg":"trace[2069463721] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27556; }","duration":"115.196318ms","start":"2026-09-19T07:04:51.354973Z","end":"2026-09-19T07:04:51.47017Z","steps":["trace[2069463721] 'agreement among raft nodes before linearized reading' (duration: 115.022473ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:04:51.698934Z","caller":"traceutil/trace.go:171","msg":"trace[2096539520] transaction","detail":"{read_only:false; response_revision:27560; number_of_response:1; }","duration":"168.439137ms","start":"2026-09-19T07:04:51.530451Z","end":"2026-09-19T07:04:51.69889Z","steps":["trace[2096539520] 'process raft request' (duration: 134.883385ms)","trace[2096539520] 'compare' (duration: 33.012389ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:05:03.871864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.024407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:05:03.872539Z","caller":"traceutil/trace.go:171","msg":"trace[440143102] transaction","detail":"{read_only:false; response_revision:27686; number_of_response:1; }","duration":"219.867729ms","start":"2026-09-19T07:05:03.652631Z","end":"2026-09-19T07:05:03.872499Z","steps":["trace[440143102] 'process raft request' (duration: 219.369807ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:05:03.872536Z","caller":"traceutil/trace.go:171","msg":"trace[554494697] transaction","detail":"{read_only:false; response_revision:27685; number_of_response:1; }","duration":"220.733839ms","start":"2026-09-19T07:05:03.651752Z","end":"2026-09-19T07:05:03.872486Z","steps":["trace[554494697] 'process raft request' (duration: 48.914083ms)","trace[554494697] 'compare' (duration: 170.74679ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:05:03.872656Z","caller":"traceutil/trace.go:171","msg":"trace[351861018] linearizableReadLoop","detail":"{readStateIndex:28602; appliedIndex:28600; }","duration":"115.726269ms","start":"2026-09-19T07:05:03.756897Z","end":"2026-09-19T07:05:03.872623Z","steps":["trace[351861018] 'read index received' (duration: 87.727212ms)","trace[351861018] 'applied index is now lower than readState.Index' (duration: 27.994447ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:05:03.872861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.956175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-19T07:05:03.872917Z","caller":"traceutil/trace.go:171","msg":"trace[1600300406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27686; }","duration":"116.049548ms","start":"2026-09-19T07:05:03.756848Z","end":"2026-09-19T07:05:03.872898Z","steps":["trace[1600300406] 'agreement among raft nodes before linearized reading' (duration: 115.862152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:05:04.233801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.921021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T07:05:04.234061Z","caller":"traceutil/trace.go:171","msg":"trace[1361114223] transaction","detail":"{read_only:false; response_revision:27688; number_of_response:1; }","duration":"356.163825ms","start":"2026-09-19T07:05:03.87783Z","end":"2026-09-19T07:05:04.233994Z","steps":["trace[1361114223] 'process raft request' (duration: 211.951467ms)","trace[1361114223] 'compare' (duration: 143.560243ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:05:04.234288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T07:05:03.877804Z","time spent":"356.371221ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T07:05:04.452794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.860684ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-19T07:05:04.452918Z","caller":"traceutil/trace.go:171","msg":"trace[1048086555] linearizableReadLoop","detail":"{readStateIndex:28605; appliedIndex:28603; }","duration":"262.720286ms","start":"2026-09-19T07:05:04.190174Z","end":"2026-09-19T07:05:04.452895Z","steps":["trace[1048086555] 'read index received' (duration: 3.384962ms)","trace[1048086555] 'applied index is now lower than readState.Index' (duration: 259.333734ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T07:05:04.453213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.038443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T07:05:04.453272Z","caller":"traceutil/trace.go:171","msg":"trace[588163624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27688; }","duration":"263.154896ms","start":"2026-09-19T07:05:04.190106Z","end":"2026-09-19T07:05:04.453261Z","steps":["trace[588163624] 'agreement among raft nodes before linearized reading' (duration: 263.054074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T07:05:04.453314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.935055ms","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-09-19T07:05:04.453406Z","caller":"traceutil/trace.go:171","msg":"trace[710582832] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27688; }","duration":"109.088009ms","start":"2026-09-19T07:05:04.344301Z","end":"2026-09-19T07:05:04.453389Z","steps":["trace[710582832] 'agreement among raft nodes before linearized reading' (duration: 108.946056ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:05:04.840788Z","caller":"traceutil/trace.go:171","msg":"trace[1379947826] transaction","detail":"{read_only:false; response_revision:27689; number_of_response:1; }","duration":"215.635446ms","start":"2026-09-19T07:05:04.625111Z","end":"2026-09-19T07:05:04.840747Z","steps":["trace[1379947826] 'process raft request' (duration: 215.434722ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:06:41.860866Z","caller":"traceutil/trace.go:171","msg":"trace[777318251] transaction","detail":"{read_only:false; response_revision:28710; number_of_response:1; }","duration":"127.074244ms","start":"2026-09-19T07:06:41.733112Z","end":"2026-09-19T07:06:41.860186Z","steps":["trace[777318251] 'process raft request' (duration: 83.968341ms)","trace[777318251] 'compare' (duration: 40.938401ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T07:06:41.86163Z","caller":"traceutil/trace.go:171","msg":"trace[177223064] transaction","detail":"{read_only:false; response_revision:28712; number_of_response:1; }","duration":"116.432906ms","start":"2026-09-19T07:06:41.74517Z","end":"2026-09-19T07:06:41.861603Z","steps":["trace[177223064] 'process raft request' (duration: 113.814493ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:06:41.861811Z","caller":"traceutil/trace.go:171","msg":"trace[771743887] transaction","detail":"{read_only:false; response_revision:28713; number_of_response:1; }","duration":"114.397358ms","start":"2026-09-19T07:06:41.747389Z","end":"2026-09-19T07:06:41.861786Z","steps":["trace[771743887] 'process raft request' (duration: 112.001669ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:06:41.861945Z","caller":"traceutil/trace.go:171","msg":"trace[1058847028] transaction","detail":"{read_only:false; response_revision:28711; number_of_response:1; }","duration":"122.318239ms","start":"2026-09-19T07:06:41.739614Z","end":"2026-09-19T07:06:41.861932Z","steps":["trace[1058847028] 'process raft request' (duration: 119.024489ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:07:25.239441Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":30012,"local-member-snapshot-index":20011,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T07:07:25.246071Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30012} {"level":"info","ts":"2026-09-19T07:07:25.246183Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25012} {"level":"info","ts":"2026-09-19T07:09:14.944115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27281} {"level":"info","ts":"2026-09-19T07:09:15.115115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27281,"took":"165.861873ms","hash":2346819448,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":36528128,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-19T07:09:15.115201Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2346819448,"revision":27281,"compact-revision":23797} {"level":"info","ts":"2026-09-19T07:14:14.951646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30185} {"level":"info","ts":"2026-09-19T07:14:15.097302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30185,"took":"141.074442ms","hash":1868071115,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":31674368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-19T07:14:15.097397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1868071115,"revision":30185,"compact-revision":27281} {"level":"info","ts":"2026-09-19T07:19:14.959043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32459} {"level":"info","ts":"2026-09-19T07:19:15.124968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32459,"took":"160.926212ms","hash":1946418013,"current-db-size-bytes":57278464,"current-db-size":"57 MB","current-db-size-in-use-bytes":30474240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-19T07:19:15.125041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1946418013,"revision":32459,"compact-revision":30185} {"level":"info","ts":"2026-09-19T07:24:14.966443Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34683} {"level":"info","ts":"2026-09-19T07:24:15.099159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34683,"took":"128.110259ms","hash":2296238643,"current-db-size-bytes":57278464,"current-db-size":"57 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-19T07:24:15.099231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2296238643,"revision":34683,"compact-revision":32459} {"level":"info","ts":"2026-09-19T07:26:25.819622Z","caller":"traceutil/trace.go:171","msg":"trace[2023534534] transaction","detail":"{read_only:false; response_revision:38015; number_of_response:1; }","duration":"107.49998ms","start":"2026-09-19T07:26:25.7121Z","end":"2026-09-19T07:26:25.8196Z","steps":["trace[2023534534] 'process raft request' (duration: 107.355537ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T07:28:11.247208Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":40013,"local-member-snapshot-index":30012,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T07:28:11.252756Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40013} {"level":"info","ts":"2026-09-19T07:28:11.252889Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35013} {"level":"info","ts":"2026-09-19T07:29:14.975179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36917} {"level":"info","ts":"2026-09-19T07:29:15.107738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36917,"took":"127.667389ms","hash":3065460654,"current-db-size-bytes":57278464,"current-db-size":"57 MB","current-db-size-in-use-bytes":31428608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T07:29:15.10789Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3065460654,"revision":36917,"compact-revision":34683} {"level":"info","ts":"2026-09-19T07:34:14.981784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39250} {"level":"info","ts":"2026-09-19T07:34:15.117746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39250,"took":"130.503437ms","hash":1525698741,"current-db-size-bytes":57278464,"current-db-size":"57 MB","current-db-size-in-use-bytes":30138368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-19T07:34:15.117837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1525698741,"revision":39250,"compact-revision":36917}