{"level":"warn","ts":"2026-10-03T09:14:37.418278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T09:14:37.418428Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.44: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.44:2380","--initial-cluster=instance=https://162.253.55.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-03T09:14:37.41854Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T09:14:37.41855Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-03T09:14:37.418591Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-03T09:14:37.419836Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"]} {"level":"info","ts":"2026-10-03T09:14:37.419956Z","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.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-03T09:14:37.426521Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.284888ms"} {"level":"info","ts":"2026-10-03T09:14:37.439157Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4cb6cb294c6743","cluster-id":"77f9414eebec887e"} {"level":"info","ts":"2026-10-03T09:14:37.439258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T09:14:37.439296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 0"} {"level":"info","ts":"2026-10-03T09:14:37.439312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4cb6cb294c6743 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T09:14:37.43933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 1"} {"level":"info","ts":"2026-10-03T09:14:37.439385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"warn","ts":"2026-10-03T09:14:37.445135Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T09:14:37.448964Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T09:14:37.451165Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-03T09:14:37.454464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4cb6cb294c6743","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T09:14:37.454673Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4cb6cb294c6743","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T09:14:37.454859Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T09:14:37.455035Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T09:14:37.455061Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T09:14:37.455879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"info","ts":"2026-10-03T09:14:37.456036Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","added-peer-id":"b4cb6cb294c6743","added-peer-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-03T09:14:37.45868Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-03T09:14:37.458787Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-03T09:14:37.458822Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-03T09:14:37.45922Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4cb6cb294c6743","initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T09:14:37.459323Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T09:14:37.840667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T09:14:37.840807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T09:14:37.840847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgPreVoteResp from b4cb6cb294c6743 at term 1"} {"level":"info","ts":"2026-10-03T09:14:37.840874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became candidate at term 2"} {"level":"info","ts":"2026-10-03T09:14:37.840887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgVoteResp from b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-03T09:14:37.840904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became leader at term 2"} {"level":"info","ts":"2026-10-03T09:14:37.840922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4cb6cb294c6743 elected leader b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-03T09:14:37.846806Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:37.84823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T09:14:37.848225Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4cb6cb294c6743","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.44:2379]}","request-path":"/0/members/b4cb6cb294c6743/attributes","cluster-id":"77f9414eebec887e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T09:14:37.848291Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T09:14:37.848702Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T09:14:37.848755Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T09:14:37.848861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:37.849015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:37.849062Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:37.851441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T09:14:37.852009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.44:2379"} {"level":"info","ts":"2026-10-03T09:14:41.02867Z","caller":"traceutil/trace.go:171","msg":"trace[1803101302] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"209.697912ms","start":"2026-10-03T09:14:40.818927Z","end":"2026-10-03T09:14:41.028625Z","steps":["trace[1803101302] 'process raft request' (duration: 163.854432ms)","trace[1803101302] 'compare' (duration: 45.518551ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:14:48.565823Z","caller":"traceutil/trace.go:171","msg":"trace[884620186] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"208.760415ms","start":"2026-10-03T09:14:48.35703Z","end":"2026-10-03T09:14:48.565791Z","steps":["trace[884620186] 'process raft request' (duration: 208.587741ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:14:48.915104Z","caller":"traceutil/trace.go:171","msg":"trace[1672477625] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"182.391063ms","start":"2026-10-03T09:14:48.732689Z","end":"2026-10-03T09:14:48.91508Z","steps":["trace[1672477625] 'read index received' (duration: 182.068174ms)","trace[1672477625] 'applied index is now lower than readState.Index' (duration: 321.789µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:14:48.91533Z","caller":"traceutil/trace.go:171","msg":"trace[1989584398] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"337.871899ms","start":"2026-10-03T09:14:48.577445Z","end":"2026-10-03T09:14:48.915317Z","steps":["trace[1989584398] 'process raft request' (duration: 337.425637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:14:48.915662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.908828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-03T09:14:48.915757Z","caller":"traceutil/trace.go:171","msg":"trace[1145017533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"183.106923ms","start":"2026-10-03T09:14:48.732629Z","end":"2026-10-03T09:14:48.915736Z","steps":["trace[1145017533] 'agreement among raft nodes before linearized reading' (duration: 182.923648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:14:48.919329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:14:48.577419Z","time spent":"338.054944ms","remote":"127.0.0.1:45286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:14:49.0244Z","caller":"traceutil/trace.go:171","msg":"trace[1149287154] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"100.931771ms","start":"2026-10-03T09:14:48.923446Z","end":"2026-10-03T09:14:49.024378Z","steps":["trace[1149287154] 'process raft request' (duration: 100.880229ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:14:49.024439Z","caller":"traceutil/trace.go:171","msg":"trace[1507424475] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"103.149694ms","start":"2026-10-03T09:14:48.92126Z","end":"2026-10-03T09:14:49.024409Z","steps":["trace[1507424475] 'process raft request' (duration: 101.727944ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:14:49.02466Z","caller":"traceutil/trace.go:171","msg":"trace[249616223] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"101.408795ms","start":"2026-10-03T09:14:48.923215Z","end":"2026-10-03T09:14:49.024624Z","steps":["trace[249616223] 'read index received' (duration: 99.786289ms)","trace[249616223] 'applied index is now lower than readState.Index' (duration: 1.618646ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:14:49.024899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.623661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-10-03T09:14:49.024944Z","caller":"traceutil/trace.go:171","msg":"trace[544676753] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:393; }","duration":"101.726493ms","start":"2026-10-03T09:14:48.923202Z","end":"2026-10-03T09:14:49.024929Z","steps":["trace[544676753] 'agreement among raft nodes before linearized reading' (duration: 101.530518ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:20.740227Z","caller":"traceutil/trace.go:171","msg":"trace[71427117] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"131.537432ms","start":"2026-10-03T09:15:20.608646Z","end":"2026-10-03T09:15:20.740184Z","steps":["trace[71427117] 'read index received' (duration: 130.160493ms)","trace[71427117] 'applied index is now lower than readState.Index' (duration: 1.375069ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:15:20.74029Z","caller":"traceutil/trace.go:171","msg":"trace[1745006996] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"161.001051ms","start":"2026-10-03T09:15:20.579237Z","end":"2026-10-03T09:15:20.740238Z","steps":["trace[1745006996] 'process raft request' (duration: 159.950572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:20.740446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.764928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:15:20.740551Z","caller":"traceutil/trace.go:171","msg":"trace[1116692014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"131.907652ms","start":"2026-10-03T09:15:20.608617Z","end":"2026-10-03T09:15:20.740525Z","steps":["trace[1116692014] 'agreement among raft nodes before linearized reading' (duration: 131.693606ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:21.052326Z","caller":"traceutil/trace.go:171","msg":"trace[1054565940] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"223.789749ms","start":"2026-10-03T09:15:20.828515Z","end":"2026-10-03T09:15:21.052305Z","steps":["trace[1054565940] 'process raft request' (duration: 223.620904ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:36.175319Z","caller":"traceutil/trace.go:171","msg":"trace[1958693770] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"110.7891ms","start":"2026-10-03T09:15:36.064503Z","end":"2026-10-03T09:15:36.175292Z","steps":["trace[1958693770] 'process raft request' (duration: 110.602504ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:10.221895Z","caller":"traceutil/trace.go:171","msg":"trace[1403332289] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"156.325874ms","start":"2026-10-03T09:16:10.065539Z","end":"2026-10-03T09:16:10.221865Z","steps":["trace[1403332289] 'process raft request' (duration: 143.517392ms)","trace[1403332289] 'compare' (duration: 12.681259ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:16:10.230865Z","caller":"traceutil/trace.go:171","msg":"trace[557911768] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"157.904919ms","start":"2026-10-03T09:16:10.072914Z","end":"2026-10-03T09:16:10.230818Z","steps":["trace[557911768] 'process raft request' (duration: 157.817847ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:10.231256Z","caller":"traceutil/trace.go:171","msg":"trace[771792158] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"164.505966ms","start":"2026-10-03T09:16:10.066658Z","end":"2026-10-03T09:16:10.231164Z","steps":["trace[771792158] 'process raft request' (duration: 163.923029ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:56.761764Z","caller":"traceutil/trace.go:171","msg":"trace[595076450] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"149.063217ms","start":"2026-10-03T09:16:56.612663Z","end":"2026-10-03T09:16:56.761726Z","steps":["trace[595076450] 'process raft request' (duration: 148.791719ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:56.790767Z","caller":"traceutil/trace.go:171","msg":"trace[1612607514] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"111.61494ms","start":"2026-10-03T09:16:56.679131Z","end":"2026-10-03T09:16:56.790746Z","steps":["trace[1612607514] 'process raft request' (duration: 111.386003ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:57.101362Z","caller":"traceutil/trace.go:171","msg":"trace[1069320418] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"134.621578ms","start":"2026-10-03T09:16:56.966715Z","end":"2026-10-03T09:16:57.101336Z","steps":["trace[1069320418] 'process raft request' (duration: 134.444173ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:57.147383Z","caller":"traceutil/trace.go:171","msg":"trace[234994068] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"179.094044ms","start":"2026-10-03T09:16:56.968266Z","end":"2026-10-03T09:16:57.14736Z","steps":["trace[234994068] 'process raft request' (duration: 178.894138ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:57.147559Z","caller":"traceutil/trace.go:171","msg":"trace[980613610] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1956; }","duration":"163.196825ms","start":"2026-10-03T09:16:56.984336Z","end":"2026-10-03T09:16:57.147532Z","steps":["trace[980613610] 'read index received' (duration: 116.901939ms)","trace[980613610] 'applied index is now lower than readState.Index' (duration: 46.293656ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:16:57.147593Z","caller":"traceutil/trace.go:171","msg":"trace[1879478745] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"136.481281ms","start":"2026-10-03T09:16:57.011081Z","end":"2026-10-03T09:16:57.147562Z","steps":["trace[1879478745] 'process raft request' (duration: 136.224494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:57.147821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.467073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31d61a82-ff98-4e66-bca0-50d7587d8873\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-10-03T09:16:57.147919Z","caller":"traceutil/trace.go:171","msg":"trace[1211913500] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31d61a82-ff98-4e66-bca0-50d7587d8873; range_end:; response_count:1; response_revision:1886; }","duration":"163.574576ms","start":"2026-10-03T09:16:56.984322Z","end":"2026-10-03T09:16:57.147896Z","steps":["trace[1211913500] 'agreement among raft nodes before linearized reading' (duration: 163.394091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:57.147951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.305859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-03T09:16:57.148098Z","caller":"traceutil/trace.go:171","msg":"trace[54696464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1886; }","duration":"101.457043ms","start":"2026-10-03T09:16:57.04658Z","end":"2026-10-03T09:16:57.148037Z","steps":["trace[54696464] 'agreement among raft nodes before linearized reading' (duration: 101.179965ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:17:09.388415Z","caller":"traceutil/trace.go:171","msg":"trace[731776685] linearizableReadLoop","detail":"{readStateIndex:2067; appliedIndex:2066; }","duration":"154.321855ms","start":"2026-10-03T09:17:09.234065Z","end":"2026-10-03T09:17:09.388387Z","steps":["trace[731776685] 'read index received' (duration: 70.433187ms)","trace[731776685] 'applied index is now lower than readState.Index' (duration: 83.887418ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:17:09.388506Z","caller":"traceutil/trace.go:171","msg":"trace[1001516530] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"167.95764ms","start":"2026-10-03T09:17:09.220507Z","end":"2026-10-03T09:17:09.388465Z","steps":["trace[1001516530] 'process raft request' (duration: 84.127844ms)","trace[1001516530] 'compare' (duration: 83.508567ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:17:09.388706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.606673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-03T09:17:09.388797Z","caller":"traceutil/trace.go:171","msg":"trace[314957399] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1992; }","duration":"154.775428ms","start":"2026-10-03T09:17:09.233998Z","end":"2026-10-03T09:17:09.388774Z","steps":["trace[314957399] 'agreement among raft nodes before linearized reading' (duration: 154.480569ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:17:47.886476Z","caller":"traceutil/trace.go:171","msg":"trace[1205303371] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"111.82054ms","start":"2026-10-03T09:17:47.774625Z","end":"2026-10-03T09:17:47.886446Z","steps":["trace[1205303371] 'process raft request' (duration: 111.662166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:17:48.161671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.196193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:17:48.161847Z","caller":"traceutil/trace.go:171","msg":"trace[1070416533] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"296.666321ms","start":"2026-10-03T09:17:47.865128Z","end":"2026-10-03T09:17:48.161795Z","steps":["trace[1070416533] 'process raft request' (duration: 168.065367ms)","trace[1070416533] 'compare' (duration: 128.09304ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:17:48.40118Z","caller":"traceutil/trace.go:171","msg":"trace[376157160] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"233.734337ms","start":"2026-10-03T09:17:48.167409Z","end":"2026-10-03T09:17:48.401144Z","steps":["trace[376157160] 'process raft request' (duration: 138.091211ms)","trace[376157160] 'compare' (duration: 95.512582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:19:12.310855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.615667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-03T09:19:12.310939Z","caller":"traceutil/trace.go:171","msg":"trace[1549292111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2857; }","duration":"211.750271ms","start":"2026-10-03T09:19:12.099172Z","end":"2026-10-03T09:19:12.310922Z","steps":["trace[1549292111] 'range keys from in-memory index tree' (duration: 211.403982ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:51.902635Z","caller":"traceutil/trace.go:171","msg":"trace[589722192] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"100.914848ms","start":"2026-10-03T09:23:51.801644Z","end":"2026-10-03T09:23:51.902559Z","steps":["trace[589722192] 'process raft request' (duration: 100.651021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:54.392248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.138249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531281"} {"level":"info","ts":"2026-10-03T09:23:54.392325Z","caller":"traceutil/trace.go:171","msg":"trace[1557177625] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5118; }","duration":"217.258532ms","start":"2026-10-03T09:23:54.17505Z","end":"2026-10-03T09:23:54.392309Z","steps":["trace[1557177625] 'range keys from in-memory index tree' (duration: 216.779848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:54.392799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.374719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-10-03T09:23:54.392877Z","caller":"traceutil/trace.go:171","msg":"trace[389791476] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5118; }","duration":"136.521344ms","start":"2026-10-03T09:23:54.256338Z","end":"2026-10-03T09:23:54.392859Z","steps":["trace[389791476] 'range keys from in-memory index tree' (duration: 135.475494ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:59.315309Z","caller":"traceutil/trace.go:171","msg":"trace[1055283516] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5284; }","duration":"156.364693ms","start":"2026-10-03T09:23:59.158918Z","end":"2026-10-03T09:23:59.315282Z","steps":["trace[1055283516] 'process raft request' (duration: 156.2545ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:59.32844Z","caller":"traceutil/trace.go:171","msg":"trace[1425697598] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"168.004142ms","start":"2026-10-03T09:23:59.160418Z","end":"2026-10-03T09:23:59.328422Z","steps":["trace[1425697598] 'process raft request' (duration: 167.525308ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:59.493459Z","caller":"traceutil/trace.go:171","msg":"trace[351101652] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"102.359819ms","start":"2026-10-03T09:23:59.391075Z","end":"2026-10-03T09:23:59.493435Z","steps":["trace[351101652] 'process raft request' (duration: 102.298177ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:59.493466Z","caller":"traceutil/trace.go:171","msg":"trace[634391320] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"107.659919ms","start":"2026-10-03T09:23:59.385773Z","end":"2026-10-03T09:23:59.493433Z","steps":["trace[634391320] 'process raft request' (duration: 35.368668ms)","trace[634391320] 'compare' (duration: 72.006453ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:24:14.358817Z","caller":"traceutil/trace.go:171","msg":"trace[569578226] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"331.095595ms","start":"2026-10-03T09:24:14.027687Z","end":"2026-10-03T09:24:14.358782Z","steps":["trace[569578226] 'process raft request' (duration: 266.228684ms)","trace[569578226] 'compare' (duration: 64.624344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:24:14.359051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:24:14.027674Z","time spent":"331.232928ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:24:37.874171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-10-03T09:24:37.958312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"82.737955ms","hash":1715459139,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T09:24:37.958383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1715459139,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-10-03T09:29:37.882774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5911} {"level":"info","ts":"2026-10-03T09:29:37.972107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5911,"took":"87.408174ms","hash":2779688170,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":26820608,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-03T09:29:37.972277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2779688170,"revision":5911,"compact-revision":3000} {"level":"info","ts":"2026-10-03T09:30:35.141548Z","caller":"traceutil/trace.go:171","msg":"trace[1798513397] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"166.127334ms","start":"2026-10-03T09:30:34.975396Z","end":"2026-10-03T09:30:35.141524Z","steps":["trace[1798513397] 'process raft request' (duration: 165.971099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:30:47.835261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.560651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:30:47.835385Z","caller":"traceutil/trace.go:171","msg":"trace[956138787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8656; }","duration":"225.738036ms","start":"2026-10-03T09:30:47.609615Z","end":"2026-10-03T09:30:47.835353Z","steps":["trace[956138787] 'range keys from in-memory index tree' (duration: 225.422088ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:30:48.327338Z","caller":"traceutil/trace.go:171","msg":"trace[404684537] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"123.778215ms","start":"2026-10-03T09:30:48.203529Z","end":"2026-10-03T09:30:48.327307Z","steps":["trace[404684537] 'process raft request' (duration: 50.464347ms)","trace[404684537] 'compare' (duration: 72.838414ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:31:17.54212Z","caller":"traceutil/trace.go:171","msg":"trace[941854588] transaction","detail":"{read_only:false; response_revision:8871; number_of_response:1; }","duration":"159.889418ms","start":"2026-10-03T09:31:17.382205Z","end":"2026-10-03T09:31:17.542094Z","steps":["trace[941854588] 'process raft request' (duration: 159.751474ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:31:25.970565Z","caller":"traceutil/trace.go:171","msg":"trace[882955367] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"107.995351ms","start":"2026-10-03T09:31:25.862538Z","end":"2026-10-03T09:31:25.970534Z","steps":["trace[882955367] 'process raft request' (duration: 107.807966ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:31:26.65383Z","caller":"traceutil/trace.go:171","msg":"trace[1524174307] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"139.100684ms","start":"2026-10-03T09:31:26.514669Z","end":"2026-10-03T09:31:26.653769Z","steps":["trace[1524174307] 'process raft request' (duration: 123.872546ms)","trace[1524174307] 'compare' (duration: 14.992581ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:31:26.836066Z","caller":"traceutil/trace.go:171","msg":"trace[1364116158] linearizableReadLoop","detail":"{readStateIndex:9234; appliedIndex:9233; }","duration":"108.769663ms","start":"2026-10-03T09:31:26.727269Z","end":"2026-10-03T09:31:26.836039Z","steps":["trace[1364116158] 'read index received' (duration: 56.404983ms)","trace[1364116158] 'applied index is now lower than readState.Index' (duration: 52.36295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:31:26.836294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.984319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:31:26.836423Z","caller":"traceutil/trace.go:171","msg":"trace[1937080215] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8919; }","duration":"109.145893ms","start":"2026-10-03T09:31:26.727248Z","end":"2026-10-03T09:31:26.836394Z","steps":["trace[1937080215] 'agreement among raft nodes before linearized reading' (duration: 108.898106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:31:31.215028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.725534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:31:31.215138Z","caller":"traceutil/trace.go:171","msg":"trace[1097243556] linearizableReadLoop","detail":"{readStateIndex:9271; appliedIndex:9269; }","duration":"139.629819ms","start":"2026-10-03T09:31:31.075474Z","end":"2026-10-03T09:31:31.215104Z","steps":["trace[1097243556] 'read index received' (duration: 5.932806ms)","trace[1097243556] 'applied index is now lower than readState.Index' (duration: 133.695322ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:31:31.21552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.023159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-kkm6c\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-10-03T09:31:31.215616Z","caller":"traceutil/trace.go:171","msg":"trace[1046486134] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-kkm6c; range_end:; response_count:1; response_revision:8956; }","duration":"140.152293ms","start":"2026-10-03T09:31:31.075443Z","end":"2026-10-03T09:31:31.215595Z","steps":["trace[1046486134] 'agreement among raft nodes before linearized reading' (duration: 139.994259ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:31:31.215787Z","caller":"traceutil/trace.go:171","msg":"trace[1896632764] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"151.637555ms","start":"2026-10-03T09:31:31.064104Z","end":"2026-10-03T09:31:31.215742Z","steps":["trace[1896632764] 'process raft request' (duration: 43.092259ms)","trace[1896632764] 'compare' (duration: 107.524048ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:31:32.292644Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002458.wal"} {"level":"info","ts":"2026-10-03T09:32:37.872156Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T09:32:37.878287Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-03T09:32:37.878391Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-03T09:34:37.890766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8077} {"level":"info","ts":"2026-10-03T09:34:37.969539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8077,"took":"75.510114ms","hash":238805252,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T09:34:37.969628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":238805252,"revision":8077,"compact-revision":5911} {"level":"info","ts":"2026-10-03T09:39:37.8987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10480} {"level":"info","ts":"2026-10-03T09:39:37.985709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10480,"took":"84.478275ms","hash":119120551,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":25972736,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-03T09:39:37.985793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":119120551,"revision":10480,"compact-revision":8077} {"level":"info","ts":"2026-10-03T09:44:21.684406Z","caller":"traceutil/trace.go:171","msg":"trace[1390398830] transaction","detail":"{read_only:false; response_revision:14583; number_of_response:1; }","duration":"100.898479ms","start":"2026-10-03T09:44:21.58346Z","end":"2026-10-03T09:44:21.684358Z","steps":["trace[1390398830] 'process raft request' (duration: 77.372507ms)","trace[1390398830] 'compare' (duration: 23.218914ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:44:37.908786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12652} {"level":"info","ts":"2026-10-03T09:44:38.00321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12652,"took":"91.69311ms","hash":3084570866,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":27611136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T09:44:38.003303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3084570866,"revision":12652,"compact-revision":10480} {"level":"info","ts":"2026-10-03T09:44:44.767335Z","caller":"traceutil/trace.go:171","msg":"trace[2127181786] transaction","detail":"{read_only:false; response_revision:14706; number_of_response:1; }","duration":"108.328878ms","start":"2026-10-03T09:44:44.658973Z","end":"2026-10-03T09:44:44.767302Z","steps":["trace[2127181786] 'process raft request' (duration: 108.146483ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:45:01.826692Z","caller":"traceutil/trace.go:171","msg":"trace[624262971] transaction","detail":"{read_only:false; response_revision:14813; number_of_response:1; }","duration":"181.249939ms","start":"2026-10-03T09:45:01.645426Z","end":"2026-10-03T09:45:01.826676Z","steps":["trace[624262971] 'process raft request' (duration: 181.149016ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:45:01.826782Z","caller":"traceutil/trace.go:171","msg":"trace[1392521605] linearizableReadLoop","detail":"{readStateIndex:15322; appliedIndex:15320; }","duration":"214.862806ms","start":"2026-10-03T09:45:01.6119Z","end":"2026-10-03T09:45:01.826763Z","steps":["trace[1392521605] 'read index received' (duration: 31.850336ms)","trace[1392521605] 'applied index is now lower than readState.Index' (duration: 183.009749ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:45:01.826663Z","caller":"traceutil/trace.go:171","msg":"trace[654759592] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"219.983459ms","start":"2026-10-03T09:45:01.606631Z","end":"2026-10-03T09:45:01.826615Z","steps":["trace[654759592] 'process raft request' (duration: 184.699057ms)","trace[654759592] 'compare' (duration: 35.018435ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:45:01.827029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.122633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:45:01.827091Z","caller":"traceutil/trace.go:171","msg":"trace[1219343407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14813; }","duration":"215.250736ms","start":"2026-10-03T09:45:01.611823Z","end":"2026-10-03T09:45:01.827073Z","steps":["trace[1219343407] 'agreement among raft nodes before linearized reading' (duration: 215.03395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:45:01.827246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.82405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-lkbk6\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-10-03T09:45:01.827373Z","caller":"traceutil/trace.go:171","msg":"trace[1721708355] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-lkbk6; range_end:; response_count:1; response_revision:14813; }","duration":"105.014415ms","start":"2026-10-03T09:45:01.722331Z","end":"2026-10-03T09:45:01.827345Z","steps":["trace[1721708355] 'agreement among raft nodes before linearized reading' (duration: 104.707546ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:45:01.944649Z","caller":"traceutil/trace.go:171","msg":"trace[310232545] transaction","detail":"{read_only:false; response_revision:14814; number_of_response:1; }","duration":"103.222914ms","start":"2026-10-03T09:45:01.841389Z","end":"2026-10-03T09:45:01.944612Z","steps":["trace[310232545] 'process raft request' (duration: 103.013589ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:45:01.946929Z","caller":"traceutil/trace.go:171","msg":"trace[2112578346] transaction","detail":"{read_only:false; response_revision:14815; number_of_response:1; }","duration":"102.530435ms","start":"2026-10-03T09:45:01.844337Z","end":"2026-10-03T09:45:01.946867Z","steps":["trace[2112578346] 'process raft request' (duration: 101.823445ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:45:26.315565Z","caller":"traceutil/trace.go:171","msg":"trace[1429575921] transaction","detail":"{read_only:false; response_revision:15131; number_of_response:1; }","duration":"285.649447ms","start":"2026-10-03T09:45:26.029879Z","end":"2026-10-03T09:45:26.315529Z","steps":["trace[1429575921] 'process raft request' (duration: 222.414408ms)","trace[1429575921] 'compare' (duration: 62.927111ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:45:26.315552Z","caller":"traceutil/trace.go:171","msg":"trace[1266722531] linearizableReadLoop","detail":"{readStateIndex:15656; appliedIndex:15655; }","duration":"247.940626ms","start":"2026-10-03T09:45:26.067578Z","end":"2026-10-03T09:45:26.315519Z","steps":["trace[1266722531] 'read index received' (duration: 184.683076ms)","trace[1266722531] 'applied index is now lower than readState.Index' (duration: 63.25631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:45:26.31583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.220704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8c253ff-4912-473b-9a88-0015d302f6c6\" ","response":"range_response_count:1 size:3636"} {"level":"warn","ts":"2026-10-03T09:45:26.315905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.002899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dfbmp\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-03T09:45:26.315945Z","caller":"traceutil/trace.go:171","msg":"trace[1061380405] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dfbmp; range_end:; response_count:1; response_revision:15131; }","duration":"242.100602ms","start":"2026-10-03T09:45:26.073831Z","end":"2026-10-03T09:45:26.315932Z","steps":["trace[1061380405] 'agreement among raft nodes before linearized reading' (duration: 241.891046ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:45:26.315944Z","caller":"traceutil/trace.go:171","msg":"trace[1516055157] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8c253ff-4912-473b-9a88-0015d302f6c6; range_end:; response_count:1; response_revision:15131; }","duration":"248.417929ms","start":"2026-10-03T09:45:26.067501Z","end":"2026-10-03T09:45:26.315919Z","steps":["trace[1516055157] 'agreement among raft nodes before linearized reading' (duration: 248.09682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:45:26.315968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.695077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qpbp7\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-10-03T09:45:26.316259Z","caller":"traceutil/trace.go:171","msg":"trace[1056967411] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qpbp7; range_end:; response_count:1; response_revision:15131; }","duration":"217.033716ms","start":"2026-10-03T09:45:26.099174Z","end":"2026-10-03T09:45:26.316208Z","steps":["trace[1056967411] 'agreement among raft nodes before linearized reading' (duration: 216.584594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:45:26.316439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.19129ms","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-10-03T09:45:26.316627Z","caller":"traceutil/trace.go:171","msg":"trace[495685607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15131; }","duration":"223.443977ms","start":"2026-10-03T09:45:26.09315Z","end":"2026-10-03T09:45:26.316594Z","steps":["trace[495685607] 'agreement among raft nodes before linearized reading' (duration: 222.854651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:46:52.522508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.382264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-03T09:46:52.523052Z","caller":"traceutil/trace.go:171","msg":"trace[1320235062] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15937; }","duration":"133.971301ms","start":"2026-10-03T09:46:52.389053Z","end":"2026-10-03T09:46:52.523025Z","steps":["trace[1320235062] 'range keys from in-memory index tree' (duration: 133.002473ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:49:37.916615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14674} {"level":"info","ts":"2026-10-03T09:49:38.007819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14674,"took":"86.837414ms","hash":3632635800,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T09:49:38.007898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3632635800,"revision":14674,"compact-revision":12652} {"level":"info","ts":"2026-10-03T09:52:50.17867Z","caller":"traceutil/trace.go:171","msg":"trace[627069626] transaction","detail":"{read_only:false; response_revision:18577; number_of_response:1; }","duration":"207.864132ms","start":"2026-10-03T09:52:49.970775Z","end":"2026-10-03T09:52:50.178639Z","steps":["trace[627069626] 'process raft request' (duration: 207.46023ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:52:50.213376Z","caller":"traceutil/trace.go:171","msg":"trace[1795790055] linearizableReadLoop","detail":"{readStateIndex:19216; appliedIndex:19215; }","duration":"218.000766ms","start":"2026-10-03T09:52:49.99535Z","end":"2026-10-03T09:52:50.213351Z","steps":["trace[1795790055] 'read index received' (duration: 183.819837ms)","trace[1795790055] 'applied index is now lower than readState.Index' (duration: 34.179819ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:52:50.213419Z","caller":"traceutil/trace.go:171","msg":"trace[1768992060] transaction","detail":"{read_only:false; response_revision:18578; number_of_response:1; }","duration":"241.096193ms","start":"2026-10-03T09:52:49.972291Z","end":"2026-10-03T09:52:50.213387Z","steps":["trace[1768992060] 'process raft request' (duration: 240.845626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:52:50.213595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.224672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T09:52:50.213653Z","caller":"traceutil/trace.go:171","msg":"trace[409137316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18579; }","duration":"218.352135ms","start":"2026-10-03T09:52:49.995284Z","end":"2026-10-03T09:52:50.213636Z","steps":["trace[409137316] 'agreement among raft nodes before linearized reading' (duration: 218.208361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:52:50.261612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.201934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T09:52:50.261694Z","caller":"traceutil/trace.go:171","msg":"trace[661108772] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18580; }","duration":"263.317387ms","start":"2026-10-03T09:52:49.998356Z","end":"2026-10-03T09:52:50.261674Z","steps":["trace[661108772] 'agreement among raft nodes before linearized reading' (duration: 263.06016ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:52:50.539427Z","caller":"traceutil/trace.go:171","msg":"trace[252837368] transaction","detail":"{read_only:false; response_revision:18581; number_of_response:1; }","duration":"268.004239ms","start":"2026-10-03T09:52:50.271392Z","end":"2026-10-03T09:52:50.539397Z","steps":["trace[252837368] 'process raft request' (duration: 267.837664ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:52:50.595843Z","caller":"traceutil/trace.go:171","msg":"trace[1354172567] linearizableReadLoop","detail":"{readStateIndex:19220; appliedIndex:19218; }","duration":"183.304593ms","start":"2026-10-03T09:52:50.412512Z","end":"2026-10-03T09:52:50.595817Z","steps":["trace[1354172567] 'read index received' (duration: 126.796367ms)","trace[1354172567] 'applied index is now lower than readState.Index' (duration: 56.507386ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:52:50.59614Z","caller":"traceutil/trace.go:171","msg":"trace[324901501] transaction","detail":"{read_only:false; response_revision:18582; number_of_response:1; }","duration":"305.583583ms","start":"2026-10-03T09:52:50.290537Z","end":"2026-10-03T09:52:50.59612Z","steps":["trace[324901501] 'process raft request' (duration: 305.086309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:52:50.596271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:52:50.290496Z","time spent":"305.685016ms","remote":"127.0.0.1:45286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:52:50.596127Z","caller":"traceutil/trace.go:171","msg":"trace[1118515605] transaction","detail":"{read_only:false; response_revision:18583; number_of_response:1; }","duration":"142.373184ms","start":"2026-10-03T09:52:50.453707Z","end":"2026-10-03T09:52:50.59608Z","steps":["trace[1118515605] 'process raft request' (duration: 142.056105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:52:50.596362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.831477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-03T09:52:50.59645Z","caller":"traceutil/trace.go:171","msg":"trace[1008510470] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:18583; }","duration":"183.967021ms","start":"2026-10-03T09:52:50.41246Z","end":"2026-10-03T09:52:50.596427Z","steps":["trace[1008510470] 'agreement among raft nodes before linearized reading' (duration: 183.663342ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:52:50.882546Z","caller":"traceutil/trace.go:171","msg":"trace[993596504] linearizableReadLoop","detail":"{readStateIndex:19222; appliedIndex:19221; }","duration":"182.724097ms","start":"2026-10-03T09:52:50.699781Z","end":"2026-10-03T09:52:50.882505Z","steps":["trace[993596504] 'read index received' (duration: 180.529555ms)","trace[993596504] 'applied index is now lower than readState.Index' (duration: 2.193202ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:52:50.882631Z","caller":"traceutil/trace.go:171","msg":"trace[2012267431] transaction","detail":"{read_only:false; response_revision:18584; number_of_response:1; }","duration":"226.818093ms","start":"2026-10-03T09:52:50.655779Z","end":"2026-10-03T09:52:50.882597Z","steps":["trace[2012267431] 'process raft request' (duration: 224.702134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:52:50.88284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.051695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7nv4x\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-03T09:52:50.882877Z","caller":"traceutil/trace.go:171","msg":"trace[139389944] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7nv4x; range_end:; response_count:1; response_revision:18584; }","duration":"183.107737ms","start":"2026-10-03T09:52:50.699757Z","end":"2026-10-03T09:52:50.882865Z","steps":["trace[139389944] 'agreement among raft nodes before linearized reading' (duration: 182.914541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:52:50.883062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.71376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-03T09:52:50.883125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.286889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T09:52:50.883205Z","caller":"traceutil/trace.go:171","msg":"trace[1230462451] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:18584; }","duration":"118.412542ms","start":"2026-10-03T09:52:50.764774Z","end":"2026-10-03T09:52:50.883187Z","steps":["trace[1230462451] 'agreement among raft nodes before linearized reading' (duration: 118.132944ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:52:50.883143Z","caller":"traceutil/trace.go:171","msg":"trace[902621692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18584; }","duration":"170.825122ms","start":"2026-10-03T09:52:50.712298Z","end":"2026-10-03T09:52:50.883123Z","steps":["trace[902621692] 'agreement among raft nodes before linearized reading' (duration: 170.529344ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:53:00.620075Z","caller":"traceutil/trace.go:171","msg":"trace[945003561] transaction","detail":"{read_only:false; response_revision:18779; number_of_response:1; }","duration":"106.112297ms","start":"2026-10-03T09:53:00.513916Z","end":"2026-10-03T09:53:00.620028Z","steps":["trace[945003561] 'process raft request' (duration: 105.85387ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:53:00.620353Z","caller":"traceutil/trace.go:171","msg":"trace[1831885066] linearizableReadLoop","detail":"{readStateIndex:19426; appliedIndex:19424; }","duration":"102.095485ms","start":"2026-10-03T09:53:00.518236Z","end":"2026-10-03T09:53:00.620332Z","steps":["trace[1831885066] 'read index received' (duration: 41.31411ms)","trace[1831885066] 'applied index is now lower than readState.Index' (duration: 60.780415ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:53:00.620637Z","caller":"traceutil/trace.go:171","msg":"trace[590494299] transaction","detail":"{read_only:false; response_revision:18780; number_of_response:1; }","duration":"102.401453ms","start":"2026-10-03T09:53:00.51822Z","end":"2026-10-03T09:53:00.620621Z","steps":["trace[590494299] 'process raft request' (duration: 101.717084ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:53:00.620827Z","caller":"traceutil/trace.go:171","msg":"trace[1051290015] transaction","detail":"{read_only:false; response_revision:18781; number_of_response:1; }","duration":"102.444154ms","start":"2026-10-03T09:53:00.518369Z","end":"2026-10-03T09:53:00.620814Z","steps":["trace[1051290015] 'process raft request' (duration: 101.888229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:53:00.621203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.949888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-10-03T09:53:00.621244Z","caller":"traceutil/trace.go:171","msg":"trace[615939339] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:18781; }","duration":"103.02118ms","start":"2026-10-03T09:53:00.518209Z","end":"2026-10-03T09:53:00.62123Z","steps":["trace[615939339] 'agreement among raft nodes before linearized reading' (duration: 102.913117ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:53:58.714481Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T09:53:58.724012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-03T09:53:58.724114Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-03T09:54:37.924138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17136} {"level":"info","ts":"2026-10-03T09:54:38.030694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17136,"took":"102.902067ms","hash":1737856100,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":33873920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-03T09:54:38.030769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1737856100,"revision":17136,"compact-revision":14674} {"level":"info","ts":"2026-10-03T09:58:13.724207Z","caller":"traceutil/trace.go:171","msg":"trace[752400469] transaction","detail":"{read_only:false; response_revision:21142; number_of_response:1; }","duration":"104.08179ms","start":"2026-10-03T09:58:13.620096Z","end":"2026-10-03T09:58:13.724178Z","steps":["trace[752400469] 'process raft request' (duration: 103.886825ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:58:24.180731Z","caller":"traceutil/trace.go:171","msg":"trace[1829443249] transaction","detail":"{read_only:false; response_revision:21195; number_of_response:1; }","duration":"128.548756ms","start":"2026-10-03T09:58:24.052143Z","end":"2026-10-03T09:58:24.180692Z","steps":["trace[1829443249] 'process raft request' (duration: 61.286849ms)","trace[1829443249] 'compare' (duration: 66.975319ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:58:30.076363Z","caller":"traceutil/trace.go:171","msg":"trace[1941886650] transaction","detail":"{read_only:false; response_revision:21223; number_of_response:1; }","duration":"287.281159ms","start":"2026-10-03T09:58:29.789056Z","end":"2026-10-03T09:58:30.076338Z","steps":["trace[1941886650] 'process raft request' (duration: 287.126985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:58:30.309077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.732829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T09:58:30.309171Z","caller":"traceutil/trace.go:171","msg":"trace[870365945] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:21224; }","duration":"119.923305ms","start":"2026-10-03T09:58:30.189228Z","end":"2026-10-03T09:58:30.309151Z","steps":["trace[870365945] 'count revisions from in-memory index tree' (duration: 119.607855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:58:30.309293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.198484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T09:58:30.309368Z","caller":"traceutil/trace.go:171","msg":"trace[1370827967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21224; }","duration":"109.352037ms","start":"2026-10-03T09:58:30.199997Z","end":"2026-10-03T09:58:30.309349Z","steps":["trace[1370827967] 'range keys from in-memory index tree' (duration: 109.001638ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:58:30.309532Z","caller":"traceutil/trace.go:171","msg":"trace[1913479059] transaction","detail":"{read_only:false; response_revision:21225; number_of_response:1; }","duration":"108.188145ms","start":"2026-10-03T09:58:30.201295Z","end":"2026-10-03T09:58:30.309483Z","steps":["trace[1913479059] 'process raft request' (duration: 86.177717ms)","trace[1913479059] 'compare' (duration: 21.623097ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:58:30.584919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.38401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T09:58:30.585444Z","caller":"traceutil/trace.go:171","msg":"trace[635408895] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21226; }","duration":"160.595926ms","start":"2026-10-03T09:58:30.424456Z","end":"2026-10-03T09:58:30.585052Z","steps":["trace[635408895] 'range keys from in-memory index tree' (duration: 160.192155ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:58:30.691686Z","caller":"traceutil/trace.go:171","msg":"trace[873155516] transaction","detail":"{read_only:false; response_revision:21227; number_of_response:1; }","duration":"100.146099ms","start":"2026-10-03T09:58:30.591501Z","end":"2026-10-03T09:58:30.691647Z","steps":["trace[873155516] 'process raft request' (duration: 99.751218ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:58:30.880546Z","caller":"traceutil/trace.go:171","msg":"trace[767040666] transaction","detail":"{read_only:false; response_revision:21228; number_of_response:1; }","duration":"114.528373ms","start":"2026-10-03T09:58:30.765975Z","end":"2026-10-03T09:58:30.880503Z","steps":["trace[767040666] 'process raft request' (duration: 114.248316ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:59:37.932332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19637} {"level":"info","ts":"2026-10-03T09:59:38.056153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19637,"took":"119.666847ms","hash":4062254577,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":29982720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T09:59:38.056232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4062254577,"revision":19637,"compact-revision":17136} {"level":"info","ts":"2026-10-03T10:02:27.152393Z","caller":"traceutil/trace.go:171","msg":"trace[573552765] transaction","detail":"{read_only:false; response_revision:23768; number_of_response:1; }","duration":"107.197231ms","start":"2026-10-03T10:02:27.045162Z","end":"2026-10-03T10:02:27.15236Z","steps":["trace[573552765] 'process raft request' (duration: 106.766019ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:37.940236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21844} {"level":"info","ts":"2026-10-03T10:04:38.067125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21844,"took":"120.787412ms","hash":743641021,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":44253184,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-03T10:04:38.067207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":743641021,"revision":21844,"compact-revision":19637} {"level":"info","ts":"2026-10-03T10:05:55.24572Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006862.wal"} {"level":"info","ts":"2026-10-03T10:05:59.609538Z","caller":"traceutil/trace.go:171","msg":"trace[913576207] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"106.876661ms","start":"2026-10-03T10:05:59.502639Z","end":"2026-10-03T10:05:59.609516Z","steps":["trace[913576207] 'process raft request' (duration: 106.744527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:04.802083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.277677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-03T10:06:04.802187Z","caller":"traceutil/trace.go:171","msg":"trace[1872447641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25973; }","duration":"131.462672ms","start":"2026-10-03T10:06:04.670704Z","end":"2026-10-03T10:06:04.802167Z","steps":["trace[1872447641] 'range keys from in-memory index tree' (duration: 131.105432ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:04.933365Z","caller":"traceutil/trace.go:171","msg":"trace[1087162710] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"188.118083ms","start":"2026-10-03T10:06:04.745219Z","end":"2026-10-03T10:06:04.933337Z","steps":["trace[1087162710] 'process raft request' (duration: 187.963219ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:04.961644Z","caller":"traceutil/trace.go:171","msg":"trace[2141430380] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"154.945561ms","start":"2026-10-03T10:06:04.806677Z","end":"2026-10-03T10:06:04.961622Z","steps":["trace[2141430380] 'process raft request' (duration: 154.801937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:05.500379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.941308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:05.500493Z","caller":"traceutil/trace.go:171","msg":"trace[2080403690] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25977; }","duration":"225.130373ms","start":"2026-10-03T10:06:05.275343Z","end":"2026-10-03T10:06:05.500473Z","steps":["trace[2080403690] 'count revisions from in-memory index tree' (duration: 224.793563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:05.500563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.400063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:06:05.500669Z","caller":"traceutil/trace.go:171","msg":"trace[1920326730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25977; }","duration":"182.569418ms","start":"2026-10-03T10:06:05.318071Z","end":"2026-10-03T10:06:05.50064Z","steps":["trace[1920326730] 'range keys from in-memory index tree' (duration: 182.168486ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:10.123786Z","caller":"traceutil/trace.go:171","msg":"trace[2128957361] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"152.347488ms","start":"2026-10-03T10:06:09.971405Z","end":"2026-10-03T10:06:10.123753Z","steps":["trace[2128957361] 'process raft request' (duration: 140.31515ms)","trace[2128957361] 'compare' (duration: 11.448882ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:10.310005Z","caller":"traceutil/trace.go:171","msg":"trace[30126818] linearizableReadLoop","detail":"{readStateIndex:26870; appliedIndex:26869; }","duration":"167.062452ms","start":"2026-10-03T10:06:10.142902Z","end":"2026-10-03T10:06:10.309965Z","steps":["trace[30126818] 'read index received' (duration: 108.60442ms)","trace[30126818] 'applied index is now lower than readState.Index' (duration: 58.457422ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:10.31003Z","caller":"traceutil/trace.go:171","msg":"trace[2027065128] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"181.870688ms","start":"2026-10-03T10:06:10.128116Z","end":"2026-10-03T10:06:10.309986Z","steps":["trace[2027065128] 'process raft request' (duration: 123.315903ms)","trace[2027065128] 'compare' (duration: 58.365719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:10.310281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.34126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:06:10.310374Z","caller":"traceutil/trace.go:171","msg":"trace[217407479] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26013; }","duration":"167.535515ms","start":"2026-10-03T10:06:10.142818Z","end":"2026-10-03T10:06:10.310354Z","steps":["trace[217407479] 'agreement among raft nodes before linearized reading' (duration: 167.257817ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:10.719313Z","caller":"traceutil/trace.go:171","msg":"trace[491564143] linearizableReadLoop","detail":"{readStateIndex:26874; appliedIndex:26873; }","duration":"109.059582ms","start":"2026-10-03T10:06:10.610228Z","end":"2026-10-03T10:06:10.719288Z","steps":["trace[491564143] 'read index received' (duration: 108.857036ms)","trace[491564143] 'applied index is now lower than readState.Index' (duration: 201.726µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:10.719463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.215836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:10.719502Z","caller":"traceutil/trace.go:171","msg":"trace[583556581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26016; }","duration":"109.307039ms","start":"2026-10-03T10:06:10.610186Z","end":"2026-10-03T10:06:10.719493Z","steps":["trace[583556581] 'agreement among raft nodes before linearized reading' (duration: 109.200826ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:10.720027Z","caller":"traceutil/trace.go:171","msg":"trace[1191480742] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"129.928008ms","start":"2026-10-03T10:06:10.590088Z","end":"2026-10-03T10:06:10.720016Z","steps":["trace[1191480742] 'process raft request' (duration: 129.063384ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:11.227336Z","caller":"traceutil/trace.go:171","msg":"trace[1084615895] linearizableReadLoop","detail":"{readStateIndex:26875; appliedIndex:26874; }","duration":"229.166416ms","start":"2026-10-03T10:06:10.998145Z","end":"2026-10-03T10:06:11.227311Z","steps":["trace[1084615895] 'read index received' (duration: 229.057103ms)","trace[1084615895] 'applied index is now lower than readState.Index' (duration: 108.153µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:11.22745Z","caller":"traceutil/trace.go:171","msg":"trace[371435814] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"254.116587ms","start":"2026-10-03T10:06:10.973285Z","end":"2026-10-03T10:06:11.227402Z","steps":["trace[371435814] 'process raft request' (duration: 253.8477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.227645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.490985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-03T10:06:11.227728Z","caller":"traceutil/trace.go:171","msg":"trace[432742616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26017; }","duration":"229.682151ms","start":"2026-10-03T10:06:10.998021Z","end":"2026-10-03T10:06:11.227704Z","steps":["trace[432742616] 'agreement among raft nodes before linearized reading' (duration: 229.405653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.23907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.916965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T10:06:11.239183Z","caller":"traceutil/trace.go:171","msg":"trace[1426971235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26018; }","duration":"216.143921ms","start":"2026-10-03T10:06:11.023018Z","end":"2026-10-03T10:06:11.239162Z","steps":["trace[1426971235] 'agreement among raft nodes before linearized reading' (duration: 215.846182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.40501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.415615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:11.405157Z","caller":"traceutil/trace.go:171","msg":"trace[1781813209] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"172.786103ms","start":"2026-10-03T10:06:11.232345Z","end":"2026-10-03T10:06:11.405131Z","steps":["trace[1781813209] 'process raft request' (duration: 38.132301ms)","trace[1781813209] 'compare' (duration: 134.265281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:11.761364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.492844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:11.761646Z","caller":"traceutil/trace.go:171","msg":"trace[216759914] linearizableReadLoop","detail":"{readStateIndex:26879; appliedIndex:26877; }","duration":"390.132327ms","start":"2026-10-03T10:06:11.37149Z","end":"2026-10-03T10:06:11.761623Z","steps":["trace[216759914] 'read index received' (duration: 133.214411ms)","trace[216759914] 'applied index is now lower than readState.Index' (duration: 256.915996ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:11.761697Z","caller":"traceutil/trace.go:171","msg":"trace[1564522100] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"517.129654ms","start":"2026-10-03T10:06:11.244454Z","end":"2026-10-03T10:06:11.761584Z","steps":["trace[1564522100] 'process raft request' (duration: 260.28354ms)","trace[1564522100] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:415; } (duration: 255.853486ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:11.761796Z","caller":"traceutil/trace.go:171","msg":"trace[1125454549] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"492.08965ms","start":"2026-10-03T10:06:11.26968Z","end":"2026-10-03T10:06:11.761769Z","steps":["trace[1125454549] 'process raft request' (duration: 491.813982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.761886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.364253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:06:11.761941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.269651Z","time spent":"492.192643ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7897,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:06:11.76197Z","caller":"traceutil/trace.go:171","msg":"trace[1086778808] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26021; }","duration":"390.514588ms","start":"2026-10-03T10:06:11.37143Z","end":"2026-10-03T10:06:11.761945Z","steps":["trace[1086778808] 'agreement among raft nodes before linearized reading' (duration: 390.343623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.761946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.244433Z","time spent":"517.446542ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:11.762025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.37141Z","time spent":"390.60521ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:06:11.763939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.536475ms","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-10-03T10:06:11.764029Z","caller":"traceutil/trace.go:171","msg":"trace[891155595] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26022; }","duration":"256.694449ms","start":"2026-10-03T10:06:11.507315Z","end":"2026-10-03T10:06:11.76401Z","steps":["trace[891155595] 'agreement among raft nodes before linearized reading' (duration: 256.537664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.764373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.067034ms","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":"warn","ts":"2026-10-03T10:06:11.764534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.522723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.44\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-10-03T10:06:11.764561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.816788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:06:11.764573Z","caller":"traceutil/trace.go:171","msg":"trace[1230899111] range","detail":"{range_begin:/registry/masterleases/162.253.55.44; range_end:; response_count:1; response_revision:26022; }","duration":"168.600465ms","start":"2026-10-03T10:06:11.595963Z","end":"2026-10-03T10:06:11.764564Z","steps":["trace[1230899111] 'agreement among raft nodes before linearized reading' (duration: 168.338068ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:11.764607Z","caller":"traceutil/trace.go:171","msg":"trace[1721916029] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26022; }","duration":"102.89841ms","start":"2026-10-03T10:06:11.661695Z","end":"2026-10-03T10:06:11.764593Z","steps":["trace[1721916029] 'agreement among raft nodes before linearized reading' (duration: 102.724575ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:11.764469Z","caller":"traceutil/trace.go:171","msg":"trace[1367357602] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26022; }","duration":"166.175817ms","start":"2026-10-03T10:06:11.598275Z","end":"2026-10-03T10:06:11.764451Z","steps":["trace[1367357602] 'agreement among raft nodes before linearized reading' (duration: 166.015993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:11.764404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.083953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:06:11.764743Z","caller":"traceutil/trace.go:171","msg":"trace[1131541683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26022; }","duration":"219.476064ms","start":"2026-10-03T10:06:11.545257Z","end":"2026-10-03T10:06:11.764733Z","steps":["trace[1131541683] 'agreement among raft nodes before linearized reading' (duration: 219.059042ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:12.191384Z","caller":"traceutil/trace.go:171","msg":"trace[448397732] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"420.440568ms","start":"2026-10-03T10:06:11.770915Z","end":"2026-10-03T10:06:12.191355Z","steps":["trace[448397732] 'process raft request' (duration: 420.108749ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:12.191507Z","caller":"traceutil/trace.go:171","msg":"trace[250679367] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"420.488789ms","start":"2026-10-03T10:06:11.770977Z","end":"2026-10-03T10:06:12.191466Z","steps":["trace[250679367] 'process raft request' (duration: 420.291934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.191552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.770881Z","time spent":"420.564492ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:12.191594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.363766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-10-03T10:06:12.191363Z","caller":"traceutil/trace.go:171","msg":"trace[1888321678] linearizableReadLoop","detail":"{readStateIndex:26883; appliedIndex:26880; }","duration":"420.111779ms","start":"2026-10-03T10:06:11.771223Z","end":"2026-10-03T10:06:12.191335Z","steps":["trace[1888321678] 'read index received' (duration: 22.605484ms)","trace[1888321678] 'applied index is now lower than readState.Index' (duration: 397.505014ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:12.191657Z","caller":"traceutil/trace.go:171","msg":"trace[1115456018] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26024; }","duration":"420.468709ms","start":"2026-10-03T10:06:11.771173Z","end":"2026-10-03T10:06:12.191642Z","steps":["trace[1115456018] 'agreement among raft nodes before linearized reading' (duration: 420.244663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.191691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.770931Z","time spent":"420.657794ms","remote":"127.0.0.1:45350","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-10-03T10:06:12.191706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.77116Z","time spent":"420.534301ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":19050,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-10-03T10:06:12.212299Z","caller":"traceutil/trace.go:171","msg":"trace[1127217264] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"239.998061ms","start":"2026-10-03T10:06:11.972278Z","end":"2026-10-03T10:06:12.212276Z","steps":["trace[1127217264] 'process raft request' (duration: 239.874627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.212334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.426371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:06:12.212403Z","caller":"traceutil/trace.go:171","msg":"trace[528148941] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26026; }","duration":"366.570826ms","start":"2026-10-03T10:06:11.845815Z","end":"2026-10-03T10:06:12.212386Z","steps":["trace[528148941] 'agreement among raft nodes before linearized reading' (duration: 366.402361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.212446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.845795Z","time spent":"366.641447ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-10-03T10:06:12.212544Z","caller":"traceutil/trace.go:171","msg":"trace[1193025433] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"417.388872ms","start":"2026-10-03T10:06:11.795099Z","end":"2026-10-03T10:06:12.212487Z","steps":["trace[1193025433] 'process raft request' (duration: 416.879768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.212781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:11.795079Z","time spent":"417.575858ms","remote":"127.0.0.1:44608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:06:12.393377Z","caller":"traceutil/trace.go:171","msg":"trace[1960917397] linearizableReadLoop","detail":"{readStateIndex:26886; appliedIndex:26885; }","duration":"175.032826ms","start":"2026-10-03T10:06:12.218316Z","end":"2026-10-03T10:06:12.393349Z","steps":["trace[1960917397] 'read index received' (duration: 151.30171ms)","trace[1960917397] 'applied index is now lower than readState.Index' (duration: 23.729756ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:12.393644Z","caller":"traceutil/trace.go:171","msg":"trace[138799884] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"176.462446ms","start":"2026-10-03T10:06:12.217163Z","end":"2026-10-03T10:06:12.393625Z","steps":["trace[138799884] 'process raft request' (duration: 152.332688ms)","trace[138799884] 'compare' (duration: 23.312265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:12.394204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.854179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-03T10:06:12.394287Z","caller":"traceutil/trace.go:171","msg":"trace[1370982671] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26027; }","duration":"175.999523ms","start":"2026-10-03T10:06:12.218265Z","end":"2026-10-03T10:06:12.394265Z","steps":["trace[1370982671] 'agreement among raft nodes before linearized reading' (duration: 175.56229ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:12.572879Z","caller":"traceutil/trace.go:171","msg":"trace[304484541] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"254.303223ms","start":"2026-10-03T10:06:12.318554Z","end":"2026-10-03T10:06:12.572858Z","steps":["trace[304484541] 'process raft request' (duration: 204.071712ms)","trace[304484541] 'compare' (duration: 50.071226ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:12.573141Z","caller":"traceutil/trace.go:171","msg":"trace[1609547689] linearizableReadLoop","detail":"{readStateIndex:26889; appliedIndex:26886; }","duration":"179.671536ms","start":"2026-10-03T10:06:12.393444Z","end":"2026-10-03T10:06:12.573115Z","steps":["trace[1609547689] 'read index received' (duration: 129.178428ms)","trace[1609547689] 'applied index is now lower than readState.Index' (duration: 50.492028ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:12.573171Z","caller":"traceutil/trace.go:171","msg":"trace[1356272441] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"251.37837ms","start":"2026-10-03T10:06:12.321785Z","end":"2026-10-03T10:06:12.573163Z","steps":["trace[1356272441] 'process raft request' (duration: 251.235326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.573283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.036405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:12.573329Z","caller":"traceutil/trace.go:171","msg":"trace[1982009271] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26030; }","duration":"353.144539ms","start":"2026-10-03T10:06:12.22017Z","end":"2026-10-03T10:06:12.573315Z","steps":["trace[1982009271] 'agreement among raft nodes before linearized reading' (duration: 353.056916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.57335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.323586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-03T10:06:12.573286Z","caller":"traceutil/trace.go:171","msg":"trace[1069649001] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"253.928732ms","start":"2026-10-03T10:06:12.319296Z","end":"2026-10-03T10:06:12.573224Z","steps":["trace[1069649001] 'process raft request' (duration: 253.524281ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:12.573384Z","caller":"traceutil/trace.go:171","msg":"trace[2128676001] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:26030; }","duration":"221.377387ms","start":"2026-10-03T10:06:12.351993Z","end":"2026-10-03T10:06:12.573371Z","steps":["trace[2128676001] 'agreement among raft nodes before linearized reading' (duration: 221.315025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.573364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:12.220147Z","time spent":"353.20755ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-03T10:06:12.573296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.824933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-03T10:06:12.573595Z","caller":"traceutil/trace.go:171","msg":"trace[337160875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26030; }","duration":"257.129002ms","start":"2026-10-03T10:06:12.316416Z","end":"2026-10-03T10:06:12.573545Z","steps":["trace[337160875] 'agreement among raft nodes before linearized reading' (duration: 256.804713ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:12.683961Z","caller":"traceutil/trace.go:171","msg":"trace[247615243] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"103.834656ms","start":"2026-10-03T10:06:12.580096Z","end":"2026-10-03T10:06:12.68393Z","steps":["trace[247615243] 'process raft request' (duration: 101.975204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:12.993116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.12242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:12.993233Z","caller":"traceutil/trace.go:171","msg":"trace[1645137452] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"266.398052ms","start":"2026-10-03T10:06:12.726809Z","end":"2026-10-03T10:06:12.993207Z","steps":["trace[1645137452] 'process raft request' (duration: 118.058916ms)","trace[1645137452] 'compare' (duration: 147.997757ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:13.630828Z","caller":"traceutil/trace.go:171","msg":"trace[89547510] linearizableReadLoop","detail":"{readStateIndex:26894; appliedIndex:26891; }","duration":"349.255119ms","start":"2026-10-03T10:06:13.281548Z","end":"2026-10-03T10:06:13.630803Z","steps":["trace[89547510] 'read index received' (duration: 4.948819ms)","trace[89547510] 'applied index is now lower than readState.Index' (duration: 344.30188ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:13.630924Z","caller":"traceutil/trace.go:171","msg":"trace[635144596] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"387.348899ms","start":"2026-10-03T10:06:13.243551Z","end":"2026-10-03T10:06:13.6309Z","steps":["trace[635144596] 'process raft request' (duration: 324.405281ms)","trace[635144596] 'compare' (duration: 62.628229ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:13.630914Z","caller":"traceutil/trace.go:171","msg":"trace[490741726] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"363.658873ms","start":"2026-10-03T10:06:13.267212Z","end":"2026-10-03T10:06:13.630871Z","steps":["trace[490741726] 'process raft request' (duration: 363.528509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:13.631067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.482475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:13.631109Z","caller":"traceutil/trace.go:171","msg":"trace[1944735559] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26035; }","duration":"349.624079ms","start":"2026-10-03T10:06:13.281473Z","end":"2026-10-03T10:06:13.631097Z","steps":["trace[1944735559] 'agreement among raft nodes before linearized reading' (duration: 349.504376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:13.631113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:13.243521Z","time spent":"387.460262ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:13.631116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:13.267184Z","time spent":"363.814917ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:13.631145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:13.281441Z","time spent":"349.692821ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:06:13.631452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.17652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-03T10:06:13.631489Z","caller":"traceutil/trace.go:171","msg":"trace[1090129696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26035; }","duration":"342.255312ms","start":"2026-10-03T10:06:13.289221Z","end":"2026-10-03T10:06:13.631477Z","steps":["trace[1090129696] 'agreement among raft nodes before linearized reading' (duration: 342.115918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:13.631514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:13.2892Z","time spent":"342.307374ms","remote":"127.0.0.1:45286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-03T10:06:13.631549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.713757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-03T10:06:13.631529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.489361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:06:13.631512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.086449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:06:13.631588Z","caller":"traceutil/trace.go:171","msg":"trace[1533155247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26035; }","duration":"221.774409ms","start":"2026-10-03T10:06:13.409802Z","end":"2026-10-03T10:06:13.631577Z","steps":["trace[1533155247] 'agreement among raft nodes before linearized reading' (duration: 221.627344ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:13.631615Z","caller":"traceutil/trace.go:171","msg":"trace[1070592669] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26035; }","duration":"273.623145ms","start":"2026-10-03T10:06:13.357971Z","end":"2026-10-03T10:06:13.631594Z","steps":["trace[1070592669] 'agreement among raft nodes before linearized reading' (duration: 273.45286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:13.631575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.300988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:13.631642Z","caller":"traceutil/trace.go:171","msg":"trace[1429848766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26035; }","duration":"179.257225ms","start":"2026-10-03T10:06:13.452355Z","end":"2026-10-03T10:06:13.631612Z","steps":["trace[1429848766] 'agreement among raft nodes before linearized reading' (duration: 179.043739ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:13.631743Z","caller":"traceutil/trace.go:171","msg":"trace[1821972956] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26035; }","duration":"303.506944ms","start":"2026-10-03T10:06:13.328184Z","end":"2026-10-03T10:06:13.631691Z","steps":["trace[1821972956] 'agreement among raft nodes before linearized reading' (duration: 303.260807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:13.631859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:13.328168Z","time spent":"303.670978ms","remote":"127.0.0.1:37782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"info","ts":"2026-10-03T10:06:13.903101Z","caller":"traceutil/trace.go:171","msg":"trace[1756921353] linearizableReadLoop","detail":"{readStateIndex:26895; appliedIndex:26894; }","duration":"192.143866ms","start":"2026-10-03T10:06:13.710919Z","end":"2026-10-03T10:06:13.903063Z","steps":["trace[1756921353] 'read index received' (duration: 148.606044ms)","trace[1756921353] 'applied index is now lower than readState.Index' (duration: 43.505591ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:13.903166Z","caller":"traceutil/trace.go:171","msg":"trace[1242028974] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"266.881795ms","start":"2026-10-03T10:06:13.636254Z","end":"2026-10-03T10:06:13.903136Z","steps":["trace[1242028974] 'process raft request' (duration: 223.293101ms)","trace[1242028974] 'compare' (duration: 43.235235ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:13.903321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.379043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:13.903402Z","caller":"traceutil/trace.go:171","msg":"trace[1727636007] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26036; }","duration":"192.507556ms","start":"2026-10-03T10:06:13.710873Z","end":"2026-10-03T10:06:13.90338Z","steps":["trace[1727636007] 'agreement among raft nodes before linearized reading' (duration: 192.374323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:13.903984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.01722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T10:06:13.904058Z","caller":"traceutil/trace.go:171","msg":"trace[1074404479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26036; }","duration":"136.138563ms","start":"2026-10-03T10:06:13.767888Z","end":"2026-10-03T10:06:13.904026Z","steps":["trace[1074404479] 'agreement among raft nodes before linearized reading' (duration: 135.381712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:14.144607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.345274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:14.144716Z","caller":"traceutil/trace.go:171","msg":"trace[838821754] linearizableReadLoop","detail":"{readStateIndex:26896; appliedIndex:26895; }","duration":"233.469977ms","start":"2026-10-03T10:06:13.911226Z","end":"2026-10-03T10:06:14.144696Z","steps":["trace[838821754] 'read index received' (duration: 109.851115ms)","trace[838821754] 'applied index is now lower than readState.Index' (duration: 123.616972ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:14.144823Z","caller":"traceutil/trace.go:171","msg":"trace[1430413036] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"236.43832ms","start":"2026-10-03T10:06:13.908344Z","end":"2026-10-03T10:06:14.144782Z","steps":["trace[1430413036] 'process raft request' (duration: 112.817148ms)","trace[1430413036] 'compare' (duration: 122.991854ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:14.14495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.716655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-03T10:06:14.144983Z","caller":"traceutil/trace.go:171","msg":"trace[1096632230] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26037; }","duration":"233.769875ms","start":"2026-10-03T10:06:13.911203Z","end":"2026-10-03T10:06:14.144973Z","steps":["trace[1096632230] 'agreement among raft nodes before linearized reading' (duration: 233.592811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:14.145366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.108331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T10:06:14.145394Z","caller":"traceutil/trace.go:171","msg":"trace[1018883521] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26037; }","duration":"163.194923ms","start":"2026-10-03T10:06:13.98219Z","end":"2026-10-03T10:06:14.145385Z","steps":["trace[1018883521] 'agreement among raft nodes before linearized reading' (duration: 163.038909ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:14.501436Z","caller":"traceutil/trace.go:171","msg":"trace[934208327] linearizableReadLoop","detail":"{readStateIndex:26900; appliedIndex:26899; }","duration":"143.340456ms","start":"2026-10-03T10:06:14.358071Z","end":"2026-10-03T10:06:14.501411Z","steps":["trace[934208327] 'read index received' (duration: 143.075599ms)","trace[934208327] 'applied index is now lower than readState.Index' (duration: 263.767µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:14.501477Z","caller":"traceutil/trace.go:171","msg":"trace[2094384901] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"173.800811ms","start":"2026-10-03T10:06:14.327642Z","end":"2026-10-03T10:06:14.501443Z","steps":["trace[2094384901] 'process raft request' (duration: 173.610666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:14.501668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.545471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:14.501817Z","caller":"traceutil/trace.go:171","msg":"trace[1957124939] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26041; }","duration":"143.802048ms","start":"2026-10-03T10:06:14.357982Z","end":"2026-10-03T10:06:14.501784Z","steps":["trace[1957124939] 'agreement among raft nodes before linearized reading' (duration: 143.523411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:14.501723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.47254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-03T10:06:14.502115Z","caller":"traceutil/trace.go:171","msg":"trace[2141617113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26041; }","duration":"101.909442ms","start":"2026-10-03T10:06:14.400173Z","end":"2026-10-03T10:06:14.502083Z","steps":["trace[2141617113] 'agreement among raft nodes before linearized reading' (duration: 101.399598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:14.665001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.532201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:14.665308Z","caller":"traceutil/trace.go:171","msg":"trace[189006631] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"157.349169ms","start":"2026-10-03T10:06:14.507932Z","end":"2026-10-03T10:06:14.665281Z","steps":["trace[189006631] 'process raft request' (duration: 29.433547ms)","trace[189006631] 'compare' (duration: 127.424198ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:14.665358Z","caller":"traceutil/trace.go:171","msg":"trace[794193248] linearizableReadLoop","detail":"{readStateIndex:26901; appliedIndex:26900; }","duration":"108.347613ms","start":"2026-10-03T10:06:14.556958Z","end":"2026-10-03T10:06:14.665306Z","steps":["trace[794193248] 'read index received' (duration: 27.541µs)","trace[794193248] 'applied index is now lower than readState.Index' (duration: 108.310952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:14.665657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.640241ms","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-10-03T10:06:14.665702Z","caller":"traceutil/trace.go:171","msg":"trace[1594711572] 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:26043; }","duration":"108.766845ms","start":"2026-10-03T10:06:14.556917Z","end":"2026-10-03T10:06:14.665684Z","steps":["trace[1594711572] 'agreement among raft nodes before linearized reading' (duration: 108.58611ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:14.749387Z","caller":"traceutil/trace.go:171","msg":"trace[235223848] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"170.250552ms","start":"2026-10-03T10:06:14.579101Z","end":"2026-10-03T10:06:14.749351Z","steps":["trace[235223848] 'process raft request' (duration: 170.022355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:14.749465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.706384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:14.749529Z","caller":"traceutil/trace.go:171","msg":"trace[1679404189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26045; }","duration":"139.792636ms","start":"2026-10-03T10:06:14.609721Z","end":"2026-10-03T10:06:14.749514Z","steps":["trace[1679404189] 'agreement among raft nodes before linearized reading' (duration: 139.641632ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:14.749403Z","caller":"traceutil/trace.go:171","msg":"trace[515151179] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"170.184109ms","start":"2026-10-03T10:06:14.579192Z","end":"2026-10-03T10:06:14.749376Z","steps":["trace[515151179] 'process raft request' (duration: 170.083236ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:15.044468Z","caller":"traceutil/trace.go:171","msg":"trace[311783353] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"288.273886ms","start":"2026-10-03T10:06:14.75614Z","end":"2026-10-03T10:06:15.044414Z","steps":["trace[311783353] 'process raft request' (duration: 288.064751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:15.335573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.328311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:15.33569Z","caller":"traceutil/trace.go:171","msg":"trace[1642186208] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26047; }","duration":"187.514596ms","start":"2026-10-03T10:06:15.148154Z","end":"2026-10-03T10:06:15.335669Z","steps":["trace[1642186208] 'count revisions from in-memory index tree' (duration: 187.255749ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:24.253283Z","caller":"traceutil/trace.go:171","msg":"trace[608489651] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"282.393921ms","start":"2026-10-03T10:06:23.970861Z","end":"2026-10-03T10:06:24.253255Z","steps":["trace[608489651] 'process raft request' (duration: 282.146654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:24.778463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440968035202178664,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-03T10:06:24.854495Z","caller":"traceutil/trace.go:171","msg":"trace[2099264482] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"597.289434ms","start":"2026-10-03T10:06:24.25718Z","end":"2026-10-03T10:06:24.85447Z","steps":["trace[2099264482] 'process raft request' (duration: 597.102839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:24.854651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.257157Z","time spent":"597.409598ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:25.2369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.21893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:25.237195Z","caller":"traceutil/trace.go:171","msg":"trace[72609293] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"453.953048ms","start":"2026-10-03T10:06:24.783225Z","end":"2026-10-03T10:06:25.237178Z","steps":["trace[72609293] 'process raft request' (duration: 453.880866ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:25.237207Z","caller":"traceutil/trace.go:171","msg":"trace[1533402971] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"977.982306ms","start":"2026-10-03T10:06:24.25919Z","end":"2026-10-03T10:06:25.237173Z","steps":["trace[1533402971] 'process raft request' (duration: 692.401775ms)","trace[1533402971] 'compare' (duration: 284.83464ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:25.237267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.78321Z","time spent":"454.025501ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:06:25.237256Z","caller":"traceutil/trace.go:171","msg":"trace[396942858] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"534.11997ms","start":"2026-10-03T10:06:24.703095Z","end":"2026-10-03T10:06:25.237215Z","steps":["trace[396942858] 'process raft request' (duration: 533.903474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.237368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.25917Z","time spent":"978.104119ms","remote":"127.0.0.1:45350","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-10-03T10:06:25.237401Z","caller":"traceutil/trace.go:171","msg":"trace[1584502059] linearizableReadLoop","detail":"{readStateIndex:26973; appliedIndex:26970; }","duration":"960.020842ms","start":"2026-10-03T10:06:24.277359Z","end":"2026-10-03T10:06:25.23738Z","steps":["trace[1584502059] 'read index received' (duration: 576.910393ms)","trace[1584502059] 'applied index is now lower than readState.Index' (duration: 383.109489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:25.237462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.703078Z","time spent":"534.263203ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:06:25.237481Z","caller":"traceutil/trace.go:171","msg":"trace[1128321494] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"452.60504ms","start":"2026-10-03T10:06:24.784866Z","end":"2026-10-03T10:06:25.237471Z","steps":["trace[1128321494] 'process raft request' (duration: 452.273561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.237949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.784857Z","time spent":"453.047743ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:25.239202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.651393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:06:25.239285Z","caller":"traceutil/trace.go:171","msg":"trace[1301530981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26115; }","duration":"958.775326ms","start":"2026-10-03T10:06:24.280487Z","end":"2026-10-03T10:06:25.239263Z","steps":["trace[1301530981] 'agreement among raft nodes before linearized reading' (duration: 958.499709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.239364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.28047Z","time spent":"958.878979ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-03T10:06:25.239481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.013231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-03T10:06:25.239537Z","caller":"traceutil/trace.go:171","msg":"trace[1522013231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26115; }","duration":"151.165666ms","start":"2026-10-03T10:06:25.088353Z","end":"2026-10-03T10:06:25.239518Z","steps":["trace[1522013231] 'agreement among raft nodes before linearized reading' (duration: 150.723973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.23956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.241457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:06:25.239608Z","caller":"traceutil/trace.go:171","msg":"trace[886780425] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26115; }","duration":"469.32458ms","start":"2026-10-03T10:06:24.770264Z","end":"2026-10-03T10:06:25.239588Z","steps":["trace[886780425] 'agreement among raft nodes before linearized reading' (duration: 469.194846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.239647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.770246Z","time spent":"469.387562ms","remote":"127.0.0.1:45606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:06:25.239674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.308936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-03T10:06:25.239719Z","caller":"traceutil/trace.go:171","msg":"trace[1395701346] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26115; }","duration":"962.398088ms","start":"2026-10-03T10:06:24.277308Z","end":"2026-10-03T10:06:25.239706Z","steps":["trace[1395701346] 'agreement among raft nodes before linearized reading' (duration: 960.139624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.239755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.277289Z","time spent":"962.4588ms","remote":"127.0.0.1:45286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-03T10:06:25.239781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.218569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T10:06:25.239833Z","caller":"traceutil/trace.go:171","msg":"trace[1186212836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26115; }","duration":"531.26775ms","start":"2026-10-03T10:06:24.708552Z","end":"2026-10-03T10:06:25.23982Z","steps":["trace[1186212836] 'agreement among raft nodes before linearized reading' (duration: 531.142767ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:25.239802Z","caller":"traceutil/trace.go:171","msg":"trace[1992832045] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"110.951066ms","start":"2026-10-03T10:06:25.128831Z","end":"2026-10-03T10:06:25.239782Z","steps":["trace[1992832045] 'process raft request' (duration: 108.465346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.239875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.70852Z","time spent":"531.344582ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T10:06:25.239997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.32082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:25.240067Z","caller":"traceutil/trace.go:171","msg":"trace[290550583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26115; }","duration":"631.426333ms","start":"2026-10-03T10:06:24.608628Z","end":"2026-10-03T10:06:25.240055Z","steps":["trace[290550583] 'agreement among raft nodes before linearized reading' (duration: 631.3348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.240108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.608605Z","time spent":"631.492645ms","remote":"127.0.0.1:44568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T10:06:25.240398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.025933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:06:25.240442Z","caller":"traceutil/trace.go:171","msg":"trace[521319594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26115; }","duration":"959.084055ms","start":"2026-10-03T10:06:24.281347Z","end":"2026-10-03T10:06:25.240431Z","steps":["trace[521319594] 'agreement among raft nodes before linearized reading' (duration: 958.984472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.240569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:24.28134Z","time spent":"959.218779ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-03T10:06:25.646775Z","caller":"traceutil/trace.go:171","msg":"trace[1611083702] linearizableReadLoop","detail":"{readStateIndex:26977; appliedIndex:26976; }","duration":"400.129228ms","start":"2026-10-03T10:06:25.246623Z","end":"2026-10-03T10:06:25.646752Z","steps":["trace[1611083702] 'read index received' (duration: 394.476829ms)","trace[1611083702] 'applied index is now lower than readState.Index' (duration: 5.651559ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:25.646897Z","caller":"traceutil/trace.go:171","msg":"trace[2092947456] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"401.161277ms","start":"2026-10-03T10:06:25.245698Z","end":"2026-10-03T10:06:25.64686Z","steps":["trace[2092947456] 'process raft request' (duration: 395.386885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.647117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.245618Z","time spent":"401.377803ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:25.647115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.468647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-10-03T10:06:25.647461Z","caller":"traceutil/trace.go:171","msg":"trace[737186875] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26116; }","duration":"400.814887ms","start":"2026-10-03T10:06:25.246583Z","end":"2026-10-03T10:06:25.647398Z","steps":["trace[737186875] 'agreement among raft nodes before linearized reading' (duration: 400.307933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.647506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.246567Z","time spent":"400.925601ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19050,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-03T10:06:25.660136Z","caller":"traceutil/trace.go:171","msg":"trace[480902948] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"413.285127ms","start":"2026-10-03T10:06:25.246834Z","end":"2026-10-03T10:06:25.660119Z","steps":["trace[480902948] 'process raft request' (duration: 413.141603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.660245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.246812Z","time spent":"413.36694ms","remote":"127.0.0.1:45350","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-10-03T10:06:25.660391Z","caller":"traceutil/trace.go:171","msg":"trace[864082277] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"413.416ms","start":"2026-10-03T10:06:25.246965Z","end":"2026-10-03T10:06:25.660381Z","steps":["trace[864082277] 'process raft request' (duration: 413.110052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.660461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.24693Z","time spent":"413.498143ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:06:25.660753Z","caller":"traceutil/trace.go:171","msg":"trace[1755717584] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"413.515793ms","start":"2026-10-03T10:06:25.247217Z","end":"2026-10-03T10:06:25.660733Z","steps":["trace[1755717584] 'process raft request' (duration: 413.128663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:25.660889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.247199Z","time spent":"413.612207ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:06:25.66111Z","caller":"traceutil/trace.go:171","msg":"trace[1859723613] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"291.442776ms","start":"2026-10-03T10:06:25.369653Z","end":"2026-10-03T10:06:25.661096Z","steps":["trace[1859723613] 'process raft request' (duration: 291.006073ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:26.019941Z","caller":"traceutil/trace.go:171","msg":"trace[2081481412] linearizableReadLoop","detail":"{readStateIndex:26982; appliedIndex:26981; }","duration":"299.674946ms","start":"2026-10-03T10:06:25.720245Z","end":"2026-10-03T10:06:26.01992Z","steps":["trace[2081481412] 'read index received' (duration: 299.497531ms)","trace[2081481412] 'applied index is now lower than readState.Index' (duration: 176.575µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:26.020095Z","caller":"traceutil/trace.go:171","msg":"trace[946579495] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"302.185696ms","start":"2026-10-03T10:06:25.717853Z","end":"2026-10-03T10:06:26.020039Z","steps":["trace[946579495] 'process raft request' (duration: 301.933959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:26.020139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.848801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:26.020182Z","caller":"traceutil/trace.go:171","msg":"trace[2008521274] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26121; }","duration":"299.966174ms","start":"2026-10-03T10:06:25.720202Z","end":"2026-10-03T10:06:26.020168Z","steps":["trace[2008521274] 'agreement among raft nodes before linearized reading' (duration: 299.801929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:26.02023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.720182Z","time spent":"300.036727ms","remote":"127.0.0.1:45526","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-10-03T10:06:26.020294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:25.71783Z","time spent":"302.338971ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:26.462423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.902087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:26.462509Z","caller":"traceutil/trace.go:171","msg":"trace[41721063] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:26121; }","duration":"162.058492ms","start":"2026-10-03T10:06:26.300429Z","end":"2026-10-03T10:06:26.462487Z","steps":["trace[41721063] 'count revisions from in-memory index tree' (duration: 161.792084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:26.462757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.83205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:26.462865Z","caller":"traceutil/trace.go:171","msg":"trace[487776733] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:26121; }","duration":"238.004344ms","start":"2026-10-03T10:06:26.224829Z","end":"2026-10-03T10:06:26.462834Z","steps":["trace[487776733] 'count revisions from in-memory index tree' (duration: 237.704605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:26.462994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.154969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-03T10:06:26.46309Z","caller":"traceutil/trace.go:171","msg":"trace[1326780662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26121; }","duration":"206.333445ms","start":"2026-10-03T10:06:26.256736Z","end":"2026-10-03T10:06:26.46307Z","steps":["trace[1326780662] 'range keys from in-memory index tree' (duration: 205.320357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:26.835106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.240316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:26.835199Z","caller":"traceutil/trace.go:171","msg":"trace[2068978673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26122; }","duration":"225.398801ms","start":"2026-10-03T10:06:26.609776Z","end":"2026-10-03T10:06:26.835175Z","steps":["trace[2068978673] 'range keys from in-memory index tree' (duration: 225.139463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:29.617121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.384405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:29.61748Z","caller":"traceutil/trace.go:171","msg":"trace[303035662] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"360.097814ms","start":"2026-10-03T10:06:29.257327Z","end":"2026-10-03T10:06:29.617425Z","steps":["trace[303035662] 'process raft request' (duration: 359.894668ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:29.617618Z","caller":"traceutil/trace.go:171","msg":"trace[2091492192] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"360.492884ms","start":"2026-10-03T10:06:29.257099Z","end":"2026-10-03T10:06:29.617592Z","steps":["trace[2091492192] 'process raft request' (duration: 236.501202ms)","trace[2091492192] 'compare' (duration: 123.077797ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:29.617457Z","caller":"traceutil/trace.go:171","msg":"trace[1190005422] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"140.425714ms","start":"2026-10-03T10:06:29.477012Z","end":"2026-10-03T10:06:29.617437Z","steps":["trace[1190005422] 'process raft request' (duration: 140.375912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:29.617645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:29.257317Z","time spent":"360.226887ms","remote":"127.0.0.1:45350","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-10-03T10:06:29.617642Z","caller":"traceutil/trace.go:171","msg":"trace[1402619136] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"360.083693ms","start":"2026-10-03T10:06:29.257539Z","end":"2026-10-03T10:06:29.617622Z","steps":["trace[1402619136] 'process raft request' (duration: 359.771114ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:29.61766Z","caller":"traceutil/trace.go:171","msg":"trace[1040951816] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"359.667612ms","start":"2026-10-03T10:06:29.257946Z","end":"2026-10-03T10:06:29.617614Z","steps":["trace[1040951816] 'process raft request' (duration: 359.395474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:29.617712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:29.257067Z","time spent":"360.591927ms","remote":"127.0.0.1:45350","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-10-03T10:06:29.617634Z","caller":"traceutil/trace.go:171","msg":"trace[1834472099] linearizableReadLoop","detail":"{readStateIndex:27063; appliedIndex:27059; }","duration":"333.805926ms","start":"2026-10-03T10:06:29.283795Z","end":"2026-10-03T10:06:29.617601Z","steps":["trace[1834472099] 'read index received' (duration: 67.314781ms)","trace[1834472099] 'applied index is now lower than readState.Index' (duration: 266.486144ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:29.617778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:29.257527Z","time spent":"360.169215ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:29.617781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.97382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:06:29.617824Z","caller":"traceutil/trace.go:171","msg":"trace[1372124959] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26201; }","duration":"334.087593ms","start":"2026-10-03T10:06:29.283724Z","end":"2026-10-03T10:06:29.617811Z","steps":["trace[1372124959] 'agreement among raft nodes before linearized reading' (duration: 333.96025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:29.617826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:29.257901Z","time spent":"359.827526ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:29.617856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:29.283711Z","time spent":"334.135054ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":54,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:06:29.61796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.703437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-03T10:06:29.617999Z","caller":"traceutil/trace.go:171","msg":"trace[1945692257] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26201; }","duration":"111.768919ms","start":"2026-10-03T10:06:29.506216Z","end":"2026-10-03T10:06:29.617985Z","steps":["trace[1945692257] 'agreement among raft nodes before linearized reading' (duration: 111.622875ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:31.950434Z","caller":"traceutil/trace.go:171","msg":"trace[1302245145] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"109.194017ms","start":"2026-10-03T10:06:31.841196Z","end":"2026-10-03T10:06:31.95039Z","steps":["trace[1302245145] 'process raft request' (duration: 108.921479ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:32.147361Z","caller":"traceutil/trace.go:171","msg":"trace[436595097] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"107.034765ms","start":"2026-10-03T10:06:32.040293Z","end":"2026-10-03T10:06:32.147327Z","steps":["trace[436595097] 'process raft request' (duration: 106.812369ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:35.761051Z","caller":"traceutil/trace.go:171","msg":"trace[960063243] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"123.652441ms","start":"2026-10-03T10:06:35.637379Z","end":"2026-10-03T10:06:35.761032Z","steps":["trace[960063243] 'process raft request' (duration: 123.504867ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:35.800954Z","caller":"traceutil/trace.go:171","msg":"trace[1763701633] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"158.183992ms","start":"2026-10-03T10:06:35.642744Z","end":"2026-10-03T10:06:35.800928Z","steps":["trace[1763701633] 'process raft request' (duration: 157.953545ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:35.801216Z","caller":"traceutil/trace.go:171","msg":"trace[1841586998] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"157.585745ms","start":"2026-10-03T10:06:35.643598Z","end":"2026-10-03T10:06:35.801184Z","steps":["trace[1841586998] 'process raft request' (duration: 157.277106ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:35.801251Z","caller":"traceutil/trace.go:171","msg":"trace[1343760779] linearizableReadLoop","detail":"{readStateIndex:27132; appliedIndex:27128; }","duration":"126.291917ms","start":"2026-10-03T10:06:35.674933Z","end":"2026-10-03T10:06:35.801225Z","steps":["trace[1343760779] 'read index received' (duration: 85.976725ms)","trace[1343760779] 'applied index is now lower than readState.Index' (duration: 40.314032ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:35.801361Z","caller":"traceutil/trace.go:171","msg":"trace[109448564] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"155.486315ms","start":"2026-10-03T10:06:35.64586Z","end":"2026-10-03T10:06:35.801347Z","steps":["trace[109448564] 'process raft request' (duration: 155.248859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:35.80142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.462952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:35.80147Z","caller":"traceutil/trace.go:171","msg":"trace[227081966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26268; }","duration":"126.574985ms","start":"2026-10-03T10:06:35.674883Z","end":"2026-10-03T10:06:35.801458Z","steps":["trace[227081966] 'agreement among raft nodes before linearized reading' (duration: 126.474562ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:43.516323Z","caller":"traceutil/trace.go:171","msg":"trace[1812374151] linearizableReadLoop","detail":"{readStateIndex:27199; appliedIndex:27198; }","duration":"264.109767ms","start":"2026-10-03T10:06:43.25219Z","end":"2026-10-03T10:06:43.5163Z","steps":["trace[1812374151] 'read index received' (duration: 263.928962ms)","trace[1812374151] 'applied index is now lower than readState.Index' (duration: 179.885µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:43.516367Z","caller":"traceutil/trace.go:171","msg":"trace[209697693] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"271.150515ms","start":"2026-10-03T10:06:43.245188Z","end":"2026-10-03T10:06:43.516338Z","steps":["trace[209697693] 'process raft request' (duration: 270.94523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:43.516527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.313763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-10-03T10:06:43.51656Z","caller":"traceutil/trace.go:171","msg":"trace[895242557] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26333; }","duration":"264.386935ms","start":"2026-10-03T10:06:43.252163Z","end":"2026-10-03T10:06:43.516549Z","steps":["trace[895242557] 'agreement among raft nodes before linearized reading' (duration: 264.231951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:43.751675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.996202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:06:43.75179Z","caller":"traceutil/trace.go:171","msg":"trace[944175905] linearizableReadLoop","detail":"{readStateIndex:27200; appliedIndex:27199; }","duration":"231.192613ms","start":"2026-10-03T10:06:43.520575Z","end":"2026-10-03T10:06:43.751768Z","steps":["trace[944175905] 'read index received' (duration: 59.910762ms)","trace[944175905] 'applied index is now lower than readState.Index' (duration: 171.280601ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:43.751863Z","caller":"traceutil/trace.go:171","msg":"trace[42309183] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"322.331303ms","start":"2026-10-03T10:06:43.429518Z","end":"2026-10-03T10:06:43.75185Z","steps":["trace[42309183] 'process raft request' (duration: 151.062263ms)","trace[42309183] 'compare' (duration: 170.848518ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:43.75192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:06:43.42949Z","time spent":"322.400735ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:06:43.752084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.499452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18dafd0250bd38a0\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-10-03T10:06:43.752162Z","caller":"traceutil/trace.go:171","msg":"trace[173034327] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18dafd0250bd38a0; range_end:; response_count:1; response_revision:26334; }","duration":"231.625925ms","start":"2026-10-03T10:06:43.520513Z","end":"2026-10-03T10:06:43.752139Z","steps":["trace[173034327] 'agreement among raft nodes before linearized reading' (duration: 231.357978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:43.75455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.324066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:43.754608Z","caller":"traceutil/trace.go:171","msg":"trace[112682709] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26335; }","duration":"179.42778ms","start":"2026-10-03T10:06:43.575167Z","end":"2026-10-03T10:06:43.754595Z","steps":["trace[112682709] 'agreement among raft nodes before linearized reading' (duration: 179.315557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:43.754602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.093046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:43.754672Z","caller":"traceutil/trace.go:171","msg":"trace[109292108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26335; }","duration":"145.219839ms","start":"2026-10-03T10:06:43.609435Z","end":"2026-10-03T10:06:43.754655Z","steps":["trace[109292108] 'agreement among raft nodes before linearized reading' (duration: 145.102266ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:43.977685Z","caller":"traceutil/trace.go:171","msg":"trace[1578772042] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"187.007552ms","start":"2026-10-03T10:06:43.790638Z","end":"2026-10-03T10:06:43.977646Z","steps":["trace[1578772042] 'process raft request' (duration: 120.458963ms)","trace[1578772042] 'compare' (duration: 66.365044ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:44.047266Z","caller":"traceutil/trace.go:171","msg":"trace[874071070] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"218.595619ms","start":"2026-10-03T10:06:43.828649Z","end":"2026-10-03T10:06:44.047244Z","steps":["trace[874071070] 'process raft request' (duration: 218.414254ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.047672Z","caller":"traceutil/trace.go:171","msg":"trace[1880872815] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"215.238545ms","start":"2026-10-03T10:06:43.832406Z","end":"2026-10-03T10:06:44.047645Z","steps":["trace[1880872815] 'process raft request' (duration: 214.787432ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.047724Z","caller":"traceutil/trace.go:171","msg":"trace[1574802638] linearizableReadLoop","detail":"{readStateIndex:27207; appliedIndex:27202; }","duration":"205.044149ms","start":"2026-10-03T10:06:43.842653Z","end":"2026-10-03T10:06:44.047698Z","steps":["trace[1574802638] 'read index received' (duration: 68.459193ms)","trace[1574802638] 'applied index is now lower than readState.Index' (duration: 136.581625ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:44.047787Z","caller":"traceutil/trace.go:171","msg":"trace[869841580] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"214.809753ms","start":"2026-10-03T10:06:43.832943Z","end":"2026-10-03T10:06:44.047753Z","steps":["trace[869841580] 'process raft request' (duration: 214.574597ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.04803Z","caller":"traceutil/trace.go:171","msg":"trace[907418204] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"214.605297ms","start":"2026-10-03T10:06:43.833388Z","end":"2026-10-03T10:06:44.047994Z","steps":["trace[907418204] 'process raft request' (duration: 214.247277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:44.048103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.421169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-03T10:06:44.048138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.374237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:06:44.048212Z","caller":"traceutil/trace.go:171","msg":"trace[2146085170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26341; }","duration":"205.586763ms","start":"2026-10-03T10:06:43.842602Z","end":"2026-10-03T10:06:44.048189Z","steps":["trace[2146085170] 'agreement among raft nodes before linearized reading' (duration: 205.302815ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.048244Z","caller":"traceutil/trace.go:171","msg":"trace[598505756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26341; }","duration":"200.535602ms","start":"2026-10-03T10:06:43.847665Z","end":"2026-10-03T10:06:44.048201Z","steps":["trace[598505756] 'agreement among raft nodes before linearized reading' (duration: 200.248414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:44.048314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.72286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:06:44.048393Z","caller":"traceutil/trace.go:171","msg":"trace[1596470106] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26341; }","duration":"120.863905ms","start":"2026-10-03T10:06:43.927511Z","end":"2026-10-03T10:06:44.048375Z","steps":["trace[1596470106] 'agreement among raft nodes before linearized reading' (duration: 120.596177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:44.048579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.241744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:06:44.048634Z","caller":"traceutil/trace.go:171","msg":"trace[815151549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26341; }","duration":"200.324816ms","start":"2026-10-03T10:06:43.848292Z","end":"2026-10-03T10:06:44.048617Z","steps":["trace[815151549] 'agreement among raft nodes before linearized reading' (duration: 200.130151ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.231986Z","caller":"traceutil/trace.go:171","msg":"trace[1368960995] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"176.201999ms","start":"2026-10-03T10:06:44.055754Z","end":"2026-10-03T10:06:44.231956Z","steps":["trace[1368960995] 'process raft request' (duration: 138.652184ms)","trace[1368960995] 'compare' (duration: 37.062671ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:44.258274Z","caller":"traceutil/trace.go:171","msg":"trace[570131113] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"201.951042ms","start":"2026-10-03T10:06:44.056296Z","end":"2026-10-03T10:06:44.258247Z","steps":["trace[570131113] 'process raft request' (duration: 201.87386ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.258297Z","caller":"traceutil/trace.go:171","msg":"trace[90898213] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"202.166827ms","start":"2026-10-03T10:06:44.056095Z","end":"2026-10-03T10:06:44.258261Z","steps":["trace[90898213] 'process raft request' (duration: 201.956741ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.258476Z","caller":"traceutil/trace.go:171","msg":"trace[151774873] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"202.109906ms","start":"2026-10-03T10:06:44.056332Z","end":"2026-10-03T10:06:44.258442Z","steps":["trace[151774873] 'process raft request' (duration: 201.88041ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:44.801626Z","caller":"traceutil/trace.go:171","msg":"trace[293468647] linearizableReadLoop","detail":"{readStateIndex:27214; appliedIndex:27213; }","duration":"125.230118ms","start":"2026-10-03T10:06:44.676349Z","end":"2026-10-03T10:06:44.80158Z","steps":["trace[293468647] 'read index received' (duration: 81.777347ms)","trace[293468647] 'applied index is now lower than readState.Index' (duration: 43.45107ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:44.801636Z","caller":"traceutil/trace.go:171","msg":"trace[351587600] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"141.112183ms","start":"2026-10-03T10:06:44.660488Z","end":"2026-10-03T10:06:44.8016Z","steps":["trace[351587600] 'process raft request' (duration: 97.701544ms)","trace[351587600] 'compare' (duration: 43.107681ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:06:44.801819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.436173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:44.801891Z","caller":"traceutil/trace.go:171","msg":"trace[918283357] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26348; }","duration":"125.581627ms","start":"2026-10-03T10:06:44.676291Z","end":"2026-10-03T10:06:44.801872Z","steps":["trace[918283357] 'agreement among raft nodes before linearized reading' (duration: 125.403892ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:46.35268Z","caller":"traceutil/trace.go:171","msg":"trace[1480764596] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"108.937099ms","start":"2026-10-03T10:06:46.243699Z","end":"2026-10-03T10:06:46.352636Z","steps":["trace[1480764596] 'process raft request' (duration: 108.628051ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:46.396346Z","caller":"traceutil/trace.go:171","msg":"trace[1739651033] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"127.080499ms","start":"2026-10-03T10:06:46.269241Z","end":"2026-10-03T10:06:46.396322Z","steps":["trace[1739651033] 'process raft request' (duration: 126.879413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:46.396738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.086976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:06:46.396822Z","caller":"traceutil/trace.go:171","msg":"trace[63538095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26357; }","duration":"134.199818ms","start":"2026-10-03T10:06:46.262603Z","end":"2026-10-03T10:06:46.396803Z","steps":["trace[63538095] 'agreement among raft nodes before linearized reading' (duration: 133.970802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:46.396868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.638681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:06:46.397122Z","caller":"traceutil/trace.go:171","msg":"trace[1502824373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26357; }","duration":"134.914129ms","start":"2026-10-03T10:06:46.262181Z","end":"2026-10-03T10:06:46.397095Z","steps":["trace[1502824373] 'agreement among raft nodes before linearized reading' (duration: 134.556509ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:47.097917Z","caller":"traceutil/trace.go:171","msg":"trace[1864545133] transaction","detail":"{read_only:false; response_revision:26364; number_of_response:1; }","duration":"120.72192ms","start":"2026-10-03T10:06:46.977122Z","end":"2026-10-03T10:06:47.097844Z","steps":["trace[1864545133] 'process raft request' (duration: 84.39974ms)","trace[1864545133] 'compare' (duration: 36.096864ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:57.947169Z","caller":"traceutil/trace.go:171","msg":"trace[974927096] linearizableReadLoop","detail":"{readStateIndex:27320; appliedIndex:27319; }","duration":"116.77624ms","start":"2026-10-03T10:06:57.830366Z","end":"2026-10-03T10:06:57.947142Z","steps":["trace[974927096] 'read index received' (duration: 116.558554ms)","trace[974927096] 'applied index is now lower than readState.Index' (duration: 216.506µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:57.947225Z","caller":"traceutil/trace.go:171","msg":"trace[1537464380] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"120.292398ms","start":"2026-10-03T10:06:57.826901Z","end":"2026-10-03T10:06:57.947193Z","steps":["trace[1537464380] 'process raft request' (duration: 120.069692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:06:57.947318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.921263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:06:57.947356Z","caller":"traceutil/trace.go:171","msg":"trace[1232868264] 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:26451; }","duration":"117.023726ms","start":"2026-10-03T10:06:57.830321Z","end":"2026-10-03T10:06:57.947345Z","steps":["trace[1232868264] 'agreement among raft nodes before linearized reading' (duration: 116.921144ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:58.236125Z","caller":"traceutil/trace.go:171","msg":"trace[1591458150] transaction","detail":"{read_only:false; response_revision:26453; number_of_response:1; }","duration":"133.247412ms","start":"2026-10-03T10:06:58.102854Z","end":"2026-10-03T10:06:58.236101Z","steps":["trace[1591458150] 'process raft request' (duration: 68.588666ms)","trace[1591458150] 'compare' (duration: 64.530593ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:06:58.256331Z","caller":"traceutil/trace.go:171","msg":"trace[338925099] transaction","detail":"{read_only:false; response_revision:26454; number_of_response:1; }","duration":"141.131354ms","start":"2026-10-03T10:06:58.115182Z","end":"2026-10-03T10:06:58.256314Z","steps":["trace[338925099] 'process raft request' (duration: 140.906538ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:58.256343Z","caller":"traceutil/trace.go:171","msg":"trace[765174751] transaction","detail":"{read_only:false; response_revision:26455; number_of_response:1; }","duration":"102.17744ms","start":"2026-10-03T10:06:58.154136Z","end":"2026-10-03T10:06:58.256313Z","steps":["trace[765174751] 'process raft request' (duration: 102.091667ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:58.256606Z","caller":"traceutil/trace.go:171","msg":"trace[1038735297] transaction","detail":"{read_only:false; response_revision:26456; number_of_response:1; }","duration":"102.396746ms","start":"2026-10-03T10:06:58.154197Z","end":"2026-10-03T10:06:58.256594Z","steps":["trace[1038735297] 'process raft request' (duration: 102.076717ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:58.256642Z","caller":"traceutil/trace.go:171","msg":"trace[1613347587] transaction","detail":"{read_only:false; response_revision:26457; number_of_response:1; }","duration":"102.093987ms","start":"2026-10-03T10:06:58.154525Z","end":"2026-10-03T10:06:58.256619Z","steps":["trace[1613347587] 'process raft request' (duration: 101.987674ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:14.945779Z","caller":"traceutil/trace.go:171","msg":"trace[1130261127] transaction","detail":"{read_only:false; response_revision:26582; number_of_response:1; }","duration":"119.99189ms","start":"2026-10-03T10:07:14.825753Z","end":"2026-10-03T10:07:14.945745Z","steps":["trace[1130261127] 'process raft request' (duration: 59.432059ms)","trace[1130261127] 'compare' (duration: 60.380166ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:07:19.494824Z","caller":"traceutil/trace.go:171","msg":"trace[543268475] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"126.687488ms","start":"2026-10-03T10:07:19.368066Z","end":"2026-10-03T10:07:19.494753Z","steps":["trace[543268475] 'process raft request' (duration: 126.303447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:19.789107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.561467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:07:19.789191Z","caller":"traceutil/trace.go:171","msg":"trace[1949588172] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26612; }","duration":"177.695321ms","start":"2026-10-03T10:07:19.611475Z","end":"2026-10-03T10:07:19.789171Z","steps":["trace[1949588172] 'count revisions from in-memory index tree' (duration: 177.491875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:19.789381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.310596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:07:19.789402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.092073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T10:07:19.789459Z","caller":"traceutil/trace.go:171","msg":"trace[1028278348] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26612; }","duration":"172.193026ms","start":"2026-10-03T10:07:19.617252Z","end":"2026-10-03T10:07:19.789445Z","steps":["trace[1028278348] 'count revisions from in-memory index tree' (duration: 171.98369ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:19.78946Z","caller":"traceutil/trace.go:171","msg":"trace[1764914736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26612; }","duration":"179.439329ms","start":"2026-10-03T10:07:19.609998Z","end":"2026-10-03T10:07:19.789437Z","steps":["trace[1764914736] 'range keys from in-memory index tree' (duration: 179.209243ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.419522Z","caller":"traceutil/trace.go:171","msg":"trace[1703139843] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"256.00843ms","start":"2026-10-03T10:07:20.163488Z","end":"2026-10-03T10:07:20.419496Z","steps":["trace[1703139843] 'process raft request' (duration: 255.829965ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.629622Z","caller":"traceutil/trace.go:171","msg":"trace[565120918] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"281.446854ms","start":"2026-10-03T10:07:20.348143Z","end":"2026-10-03T10:07:20.62959Z","steps":["trace[565120918] 'process raft request' (duration: 281.172166ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.629674Z","caller":"traceutil/trace.go:171","msg":"trace[981978505] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"246.372499ms","start":"2026-10-03T10:07:20.383265Z","end":"2026-10-03T10:07:20.629637Z","steps":["trace[981978505] 'process raft request' (duration: 246.204515ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.629622Z","caller":"traceutil/trace.go:171","msg":"trace[1531951791] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"246.214315ms","start":"2026-10-03T10:07:20.383383Z","end":"2026-10-03T10:07:20.629597Z","steps":["trace[1531951791] 'process raft request' (duration: 246.142023ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.629925Z","caller":"traceutil/trace.go:171","msg":"trace[131677635] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"246.462052ms","start":"2026-10-03T10:07:20.383428Z","end":"2026-10-03T10:07:20.62989Z","steps":["trace[131677635] 'process raft request' (duration: 246.132603ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.630061Z","caller":"traceutil/trace.go:171","msg":"trace[341838286] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"246.38131ms","start":"2026-10-03T10:07:20.383647Z","end":"2026-10-03T10:07:20.630028Z","steps":["trace[341838286] 'process raft request' (duration: 246.178745ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:20.63006Z","caller":"traceutil/trace.go:171","msg":"trace[1979584384] linearizableReadLoop","detail":"{readStateIndex:27491; appliedIndex:27486; }","duration":"120.997338ms","start":"2026-10-03T10:07:20.509019Z","end":"2026-10-03T10:07:20.630016Z","steps":["trace[1979584384] 'read index received' (duration: 120.297438ms)","trace[1979584384] 'applied index is now lower than readState.Index' (duration: 698.92µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:07:20.630235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.188924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:07:20.630292Z","caller":"traceutil/trace.go:171","msg":"trace[1409595373] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26618; }","duration":"121.331907ms","start":"2026-10-03T10:07:20.508945Z","end":"2026-10-03T10:07:20.630277Z","steps":["trace[1409595373] 'agreement among raft nodes before linearized reading' (duration: 121.206074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:20.823115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.430431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:07:20.823254Z","caller":"traceutil/trace.go:171","msg":"trace[1859804533] linearizableReadLoop","detail":"{readStateIndex:27492; appliedIndex:27491; }","duration":"193.088973ms","start":"2026-10-03T10:07:20.630147Z","end":"2026-10-03T10:07:20.823236Z","steps":["trace[1859804533] 'read index received' (duration: 45.327612ms)","trace[1859804533] 'applied index is now lower than readState.Index' (duration: 147.759951ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:07:20.823355Z","caller":"traceutil/trace.go:171","msg":"trace[1920181657] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"303.73935ms","start":"2026-10-03T10:07:20.519517Z","end":"2026-10-03T10:07:20.823256Z","steps":["trace[1920181657] 'process raft request' (duration: 156.053472ms)","trace[1920181657] 'compare' (duration: 146.771173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:07:20.823488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.462837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-03T10:07:20.823571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:07:20.519492Z","time spent":"303.970467ms","remote":"127.0.0.1:45350","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-10-03T10:07:20.823629Z","caller":"traceutil/trace.go:171","msg":"trace[879679243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26619; }","duration":"301.629051ms","start":"2026-10-03T10:07:20.521987Z","end":"2026-10-03T10:07:20.823616Z","steps":["trace[879679243] 'agreement among raft nodes before linearized reading' (duration: 301.318123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:20.823671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:07:20.521957Z","time spent":"301.702124ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T10:07:20.825872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.926526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:07:20.825947Z","caller":"traceutil/trace.go:171","msg":"trace[609370979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26620; }","duration":"189.051359ms","start":"2026-10-03T10:07:20.636877Z","end":"2026-10-03T10:07:20.825929Z","steps":["trace[609370979] 'agreement among raft nodes before linearized reading' (duration: 188.843743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:20.826192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.73836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:07:20.826272Z","caller":"traceutil/trace.go:171","msg":"trace[139346829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26620; }","duration":"183.843463ms","start":"2026-10-03T10:07:20.64241Z","end":"2026-10-03T10:07:20.826253Z","steps":["trace[139346829] 'agreement among raft nodes before linearized reading' (duration: 183.614397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:20.826455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.009991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:07:20.826551Z","caller":"traceutil/trace.go:171","msg":"trace[1919324595] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26620; }","duration":"177.137015ms","start":"2026-10-03T10:07:20.649393Z","end":"2026-10-03T10:07:20.82653Z","steps":["trace[1919324595] 'agreement among raft nodes before linearized reading' (duration: 176.59689ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:21.051224Z","caller":"traceutil/trace.go:171","msg":"trace[1036091577] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"221.943903ms","start":"2026-10-03T10:07:20.82924Z","end":"2026-10-03T10:07:21.051184Z","steps":["trace[1036091577] 'process raft request' (duration: 158.288556ms)","trace[1036091577] 'compare' (duration: 63.286137ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:07:21.243316Z","caller":"traceutil/trace.go:171","msg":"trace[1338324721] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"411.762595ms","start":"2026-10-03T10:07:20.831521Z","end":"2026-10-03T10:07:21.243283Z","steps":["trace[1338324721] 'process raft request' (duration: 402.770092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:21.243507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:07:20.831502Z","time spent":"411.901948ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:07:21.243603Z","caller":"traceutil/trace.go:171","msg":"trace[299178309] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"411.60958ms","start":"2026-10-03T10:07:20.831963Z","end":"2026-10-03T10:07:21.243573Z","steps":["trace[299178309] 'process raft request' (duration: 411.25568ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:21.243677Z","caller":"traceutil/trace.go:171","msg":"trace[469162848] linearizableReadLoop","detail":"{readStateIndex:27497; appliedIndex:27493; }","duration":"273.012008ms","start":"2026-10-03T10:07:20.970633Z","end":"2026-10-03T10:07:21.243645Z","steps":["trace[469162848] 'read index received' (duration: 16.982347ms)","trace[469162848] 'applied index is now lower than readState.Index' (duration: 256.028711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:07:21.243749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:07:20.831948Z","time spent":"411.717053ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:07:21.243782Z","caller":"traceutil/trace.go:171","msg":"trace[134096978] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"411.048674ms","start":"2026-10-03T10:07:20.83271Z","end":"2026-10-03T10:07:21.243759Z","steps":["trace[134096978] 'process raft request' (duration: 410.805247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:21.243883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.232644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-03T10:07:21.24395Z","caller":"traceutil/trace.go:171","msg":"trace[157414106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26624; }","duration":"273.355157ms","start":"2026-10-03T10:07:20.970581Z","end":"2026-10-03T10:07:21.243936Z","steps":["trace[157414106] 'agreement among raft nodes before linearized reading' (duration: 273.167482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:21.243888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:07:20.832689Z","time spent":"411.140797ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:07:36.987116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.950161ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-10-03T10:07:37.413923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.953875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274823"} {"level":"info","ts":"2026-10-03T10:07:37.41407Z","caller":"traceutil/trace.go:171","msg":"trace[71972626] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26749; }","duration":"179.149952ms","start":"2026-10-03T10:07:37.2349Z","end":"2026-10-03T10:07:37.41405Z","steps":["trace[71972626] 'range keys from in-memory index tree' (duration: 178.456452ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:37.550856Z","caller":"traceutil/trace.go:171","msg":"trace[961806725] transaction","detail":"{read_only:false; response_revision:26750; number_of_response:1; }","duration":"132.707477ms","start":"2026-10-03T10:07:37.418082Z","end":"2026-10-03T10:07:37.550762Z","steps":["trace[961806725] 'process raft request' (duration: 132.47095ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:37.659911Z","caller":"traceutil/trace.go:171","msg":"trace[43529982] transaction","detail":"{read_only:false; response_revision:26751; number_of_response:1; }","duration":"241.468032ms","start":"2026-10-03T10:07:37.418412Z","end":"2026-10-03T10:07:37.65988Z","steps":["trace[43529982] 'process raft request' (duration: 240.67849ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:37.660034Z","caller":"traceutil/trace.go:171","msg":"trace[548615671] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"241.492093ms","start":"2026-10-03T10:07:37.418491Z","end":"2026-10-03T10:07:37.659983Z","steps":["trace[548615671] 'process raft request' (duration: 241.265806ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:07:37.66047Z","caller":"traceutil/trace.go:171","msg":"trace[1748852045] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"241.746909ms","start":"2026-10-03T10:07:37.418715Z","end":"2026-10-03T10:07:37.660462Z","steps":["trace[1748852045] 'process raft request' (duration: 241.111611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:07:37.879442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.653493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:07:37.879545Z","caller":"traceutil/trace.go:171","msg":"trace[1306800369] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26754; }","duration":"118.822307ms","start":"2026-10-03T10:07:37.760698Z","end":"2026-10-03T10:07:37.87952Z","steps":["trace[1306800369] 'range keys from in-memory index tree' (duration: 118.55768ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:09:37.95098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25065} {"level":"info","ts":"2026-10-03T10:09:38.120057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25065,"took":"164.571882ms","hash":1055764490,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":37261312,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-03T10:09:38.120632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1055764490,"revision":25065,"compact-revision":21844} {"level":"info","ts":"2026-10-03T10:10:39.482708Z","caller":"traceutil/trace.go:171","msg":"trace[1694871420] transaction","detail":"{read_only:false; response_revision:28630; number_of_response:1; }","duration":"118.181069ms","start":"2026-10-03T10:10:39.364496Z","end":"2026-10-03T10:10:39.482677Z","steps":["trace[1694871420] 'process raft request' (duration: 92.328883ms)","trace[1694871420] 'compare' (duration: 25.392603ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:10:39.483309Z","caller":"traceutil/trace.go:171","msg":"trace[874609203] transaction","detail":"{read_only:false; response_revision:28631; number_of_response:1; }","duration":"115.601337ms","start":"2026-10-03T10:10:39.36769Z","end":"2026-10-03T10:10:39.483291Z","steps":["trace[874609203] 'process raft request' (duration: 114.63875ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:10:39.497412Z","caller":"traceutil/trace.go:171","msg":"trace[1605603477] transaction","detail":"{read_only:false; response_revision:28632; number_of_response:1; }","duration":"121.836362ms","start":"2026-10-03T10:10:39.375555Z","end":"2026-10-03T10:10:39.497391Z","steps":["trace[1605603477] 'process raft request' (duration: 121.513823ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:10:39.49758Z","caller":"traceutil/trace.go:171","msg":"trace[1681206188] transaction","detail":"{read_only:false; response_revision:28633; number_of_response:1; }","duration":"120.837424ms","start":"2026-10-03T10:10:39.376554Z","end":"2026-10-03T10:10:39.497392Z","steps":["trace[1681206188] 'process raft request' (duration: 120.632759ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:10:39.497751Z","caller":"traceutil/trace.go:171","msg":"trace[859658937] transaction","detail":"{read_only:false; response_revision:28634; number_of_response:1; }","duration":"103.425925ms","start":"2026-10-03T10:10:39.394319Z","end":"2026-10-03T10:10:39.497745Z","steps":["trace[859658937] 'process raft request' (duration: 103.018734ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:11:19.986125Z","caller":"traceutil/trace.go:171","msg":"trace[81835241] transaction","detail":"{read_only:false; response_revision:29037; number_of_response:1; }","duration":"114.846595ms","start":"2026-10-03T10:11:19.871228Z","end":"2026-10-03T10:11:19.986075Z","steps":["trace[81835241] 'process raft request' (duration: 114.606909ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:11:23.486545Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T10:11:23.495463Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-03T10:11:23.495566Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-03T10:14:37.960701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27937} {"level":"info","ts":"2026-10-03T10:14:38.106375Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27937,"took":"141.34185ms","hash":4120232014,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":33435648,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T10:14:38.106443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4120232014,"revision":27937,"compact-revision":25065} {"level":"info","ts":"2026-10-03T10:19:37.966619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30503} {"level":"info","ts":"2026-10-03T10:19:38.114411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30503,"took":"143.565762ms","hash":2315016732,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":30621696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T10:19:38.114482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2315016732,"revision":30503,"compact-revision":27937} {"level":"info","ts":"2026-10-03T10:24:37.974412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32759} {"level":"info","ts":"2026-10-03T10:24:38.132517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32759,"took":"153.917802ms","hash":872894824,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T10:24:38.132588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":872894824,"revision":32759,"compact-revision":30503} {"level":"info","ts":"2026-10-03T10:29:37.980591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34984} {"level":"info","ts":"2026-10-03T10:29:38.128322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34984,"took":"136.615817ms","hash":4177718047,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":31604736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T10:29:38.128696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4177718047,"revision":34984,"compact-revision":32759} {"level":"info","ts":"2026-10-03T10:32:52.978121Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b4cb6cb294c6743","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T10:32:52.984401Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-03T10:32:52.984591Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-03T10:34:37.986972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37280} {"level":"info","ts":"2026-10-03T10:34:38.11172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37280,"took":"121.717496ms","hash":59600327,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":33132544,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T10:34:38.111785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":59600327,"revision":37280,"compact-revision":34984} {"level":"info","ts":"2026-10-03T10:39:37.993471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39555} {"level":"info","ts":"2026-10-03T10:39:38.131117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39555,"took":"132.993612ms","hash":3569234217,"current-db-size-bytes":60846080,"current-db-size":"61 MB","current-db-size-in-use-bytes":31223808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T10:39:38.13118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3569234217,"revision":39555,"compact-revision":37280}