{"level":"warn","ts":"2026-09-12T06:34:43.042631Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-12T06:34:43.042726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.147: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.147:2380","--initial-cluster=instance=https://162.253.55.147:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.147:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.147:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-12T06:34:43.042812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-12T06:34:43.042843Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.147:2380"]} {"level":"info","ts":"2026-09-12T06:34:43.042865Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-12T06:34:43.043472Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"]} {"level":"info","ts":"2026-09-12T06:34:43.043559Z","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.147:2380"],"listen-peer-urls":["https://162.253.55.147:2380"],"advertise-client-urls":["https://162.253.55.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.147:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-12T06:34:43.049828Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.018918ms"} {"level":"info","ts":"2026-09-12T06:34:43.057954Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4d4c1b1786e4e6dd","cluster-id":"c387147481076682"} {"level":"info","ts":"2026-09-12T06:34:43.058076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=()"} {"level":"info","ts":"2026-09-12T06:34:43.058099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became follower at term 0"} {"level":"info","ts":"2026-09-12T06:34:43.058471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4d4c1b1786e4e6dd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-12T06:34:43.058495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became follower at term 1"} {"level":"info","ts":"2026-09-12T06:34:43.058598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=(5569856627011806941)"} {"level":"warn","ts":"2026-09-12T06:34:43.064268Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-12T06:34:43.065819Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-12T06:34:43.067293Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-12T06:34:43.070485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4d4c1b1786e4e6dd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-12T06:34:43.070614Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4d4c1b1786e4e6dd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-12T06:34:43.070812Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-12T06:34:43.070861Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-12T06:34:43.070868Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-12T06:34:43.072778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=(5569856627011806941)"} {"level":"info","ts":"2026-09-12T06:34:43.073696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c387147481076682","local-member-id":"4d4c1b1786e4e6dd","added-peer-id":"4d4c1b1786e4e6dd","added-peer-peer-urls":["https://162.253.55.147:2380"]} {"level":"info","ts":"2026-09-12T06:34:43.07553Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-12T06:34:43.075728Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.147:2380"} {"level":"info","ts":"2026-09-12T06:34:43.075784Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.147:2380"} {"level":"info","ts":"2026-09-12T06:34:43.075823Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4d4c1b1786e4e6dd","initial-advertise-peer-urls":["https://162.253.55.147:2380"],"listen-peer-urls":["https://162.253.55.147:2380"],"advertise-client-urls":["https://162.253.55.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-12T06:34:43.07587Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-12T06:34:43.15909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd is starting a new election at term 1"} {"level":"info","ts":"2026-09-12T06:34:43.159143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-12T06:34:43.159162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd received MsgPreVoteResp from 4d4c1b1786e4e6dd at term 1"} {"level":"info","ts":"2026-09-12T06:34:43.159177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became candidate at term 2"} {"level":"info","ts":"2026-09-12T06:34:43.159334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd received MsgVoteResp from 4d4c1b1786e4e6dd at term 2"} {"level":"info","ts":"2026-09-12T06:34:43.159357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became leader at term 2"} {"level":"info","ts":"2026-09-12T06:34:43.159368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4d4c1b1786e4e6dd elected leader 4d4c1b1786e4e6dd at term 2"} {"level":"info","ts":"2026-09-12T06:34:43.160847Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T06:34:43.162264Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c387147481076682","local-member-id":"4d4c1b1786e4e6dd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T06:34:43.162618Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T06:34:43.16265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T06:34:43.162811Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4d4c1b1786e4e6dd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.147:2379]}","request-path":"/0/members/4d4c1b1786e4e6dd/attributes","cluster-id":"c387147481076682","publish-timeout":"7s"} {"level":"info","ts":"2026-09-12T06:34:43.164719Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T06:34:43.164756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T06:34:43.164999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-12T06:34:43.165018Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-12T06:34:43.166888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.147:2379"} {"level":"info","ts":"2026-09-12T06:34:43.168346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-12T06:35:02.193584Z","caller":"traceutil/trace.go:171","msg":"trace[1260135480] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"281.445839ms","start":"2026-09-12T06:35:01.912121Z","end":"2026-09-12T06:35:02.193566Z","steps":["trace[1260135480] 'read index received' (duration: 281.262295ms)","trace[1260135480] 'applied index is now lower than readState.Index' (duration: 182.534µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:35:02.193648Z","caller":"traceutil/trace.go:171","msg":"trace[61578650] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"299.2623ms","start":"2026-09-12T06:35:01.89436Z","end":"2026-09-12T06:35:02.193622Z","steps":["trace[61578650] 'process raft request' (duration: 299.092207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:35:02.19371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.547322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-12T06:35:02.193759Z","caller":"traceutil/trace.go:171","msg":"trace[1191347517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:378; }","duration":"281.652744ms","start":"2026-09-12T06:35:01.912096Z","end":"2026-09-12T06:35:02.193749Z","steps":["trace[1191347517] 'agreement among raft nodes before linearized reading' (duration: 281.546652ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:35:32.290663Z","caller":"traceutil/trace.go:171","msg":"trace[67908415] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"173.838749ms","start":"2026-09-12T06:35:32.116743Z","end":"2026-09-12T06:35:32.290582Z","steps":["trace[67908415] 'process raft request' (duration: 172.373998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:35:32.857472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.863838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-12T06:35:32.857598Z","caller":"traceutil/trace.go:171","msg":"trace[544082382] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"473.600866ms","start":"2026-09-12T06:35:32.383968Z","end":"2026-09-12T06:35:32.857569Z","steps":["trace[544082382] 'read index received' (duration: 255.9296ms)","trace[544082382] 'applied index is now lower than readState.Index' (duration: 217.669806ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T06:35:32.857865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.886802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-12T06:35:32.857921Z","caller":"traceutil/trace.go:171","msg":"trace[495622927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"473.982324ms","start":"2026-09-12T06:35:32.383924Z","end":"2026-09-12T06:35:32.857906Z","steps":["trace[495622927] 'agreement among raft nodes before linearized reading' (duration: 473.757269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:35:32.857964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T06:35:32.3839Z","time spent":"474.052126ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-12T06:35:32.857722Z","caller":"traceutil/trace.go:171","msg":"trace[668223196] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"481.339062ms","start":"2026-09-12T06:35:32.376257Z","end":"2026-09-12T06:35:32.857596Z","steps":["trace[668223196] 'process raft request' (duration: 263.715478ms)","trace[668223196] 'compare' (duration: 216.564741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T06:35:32.858277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.404575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-txztg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-12T06:35:32.858326Z","caller":"traceutil/trace.go:171","msg":"trace[214953242] range","detail":"{range_begin:/registry/pods/kube-system/cilium-txztg; range_end:; response_count:1; response_revision:608; }","duration":"367.486887ms","start":"2026-09-12T06:35:32.490827Z","end":"2026-09-12T06:35:32.858314Z","steps":["trace[214953242] 'agreement among raft nodes before linearized reading' (duration: 367.354024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:35:32.858362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T06:35:32.490805Z","time spent":"367.546598ms","remote":"127.0.0.1:56790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-txztg\" "} {"level":"warn","ts":"2026-09-12T06:35:32.858408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T06:35:32.376227Z","time spent":"481.984046ms","remote":"127.0.0.1:57098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-12T06:35:32.858669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.241794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-12T06:35:32.858776Z","caller":"traceutil/trace.go:171","msg":"trace[1267507515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"209.362837ms","start":"2026-09-12T06:35:32.649386Z","end":"2026-09-12T06:35:32.858749Z","steps":["trace[1267507515] 'agreement among raft nodes before linearized reading' (duration: 209.02061ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:35:49.482599Z","caller":"traceutil/trace.go:171","msg":"trace[138279840] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"226.836643ms","start":"2026-09-12T06:35:49.255738Z","end":"2026-09-12T06:35:49.482575Z","steps":["trace[138279840] 'process raft request' (duration: 226.642849ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:07.543976Z","caller":"traceutil/trace.go:171","msg":"trace[363666699] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"125.381049ms","start":"2026-09-12T06:37:07.418567Z","end":"2026-09-12T06:37:07.543948Z","steps":["trace[363666699] 'process raft request' (duration: 125.173174ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:07.941712Z","caller":"traceutil/trace.go:171","msg":"trace[1226816136] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"100.409286ms","start":"2026-09-12T06:37:07.841276Z","end":"2026-09-12T06:37:07.941685Z","steps":["trace[1226816136] 'read index received' (duration: 90.036624ms)","trace[1226816136] 'applied index is now lower than readState.Index' (duration: 10.371722ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T06:37:07.941916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.62489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d47f6550d7643c\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-12T06:37:07.94195Z","caller":"traceutil/trace.go:171","msg":"trace[1831257715] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d47f6550d7643c; range_end:; response_count:1; response_revision:1866; }","duration":"100.714522ms","start":"2026-09-12T06:37:07.841225Z","end":"2026-09-12T06:37:07.94194Z","steps":["trace[1831257715] 'agreement among raft nodes before linearized reading' (duration: 100.554349ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:07.942016Z","caller":"traceutil/trace.go:171","msg":"trace[878097800] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"181.223972ms","start":"2026-09-12T06:37:07.760723Z","end":"2026-09-12T06:37:07.941947Z","steps":["trace[878097800] 'process raft request' (duration: 170.683597ms)","trace[878097800] 'compare' (duration: 10.124786ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:37:14.806729Z","caller":"traceutil/trace.go:171","msg":"trace[1678965918] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"142.364442ms","start":"2026-09-12T06:37:14.664336Z","end":"2026-09-12T06:37:14.8067Z","steps":["trace[1678965918] 'read index received' (duration: 142.164288ms)","trace[1678965918] 'applied index is now lower than readState.Index' (duration: 198.924µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:37:14.806803Z","caller":"traceutil/trace.go:171","msg":"trace[2097784461] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"144.668801ms","start":"2026-09-12T06:37:14.662095Z","end":"2026-09-12T06:37:14.806764Z","steps":["trace[2097784461] 'process raft request' (duration: 144.419935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:37:14.807118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.77021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-12T06:37:14.80724Z","caller":"traceutil/trace.go:171","msg":"trace[307705166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1934; }","duration":"142.926954ms","start":"2026-09-12T06:37:14.664279Z","end":"2026-09-12T06:37:14.807206Z","steps":["trace[307705166] 'agreement among raft nodes before linearized reading' (duration: 142.630068ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:14.821693Z","caller":"traceutil/trace.go:171","msg":"trace[571351628] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"135.384053ms","start":"2026-09-12T06:37:14.686286Z","end":"2026-09-12T06:37:14.82167Z","steps":["trace[571351628] 'process raft request' (duration: 135.24196ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:19.312307Z","caller":"traceutil/trace.go:171","msg":"trace[882468687] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"127.2173ms","start":"2026-09-12T06:37:19.185063Z","end":"2026-09-12T06:37:19.31228Z","steps":["trace[882468687] 'process raft request' (duration: 127.039057ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:19.74373Z","caller":"traceutil/trace.go:171","msg":"trace[448289607] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"107.837368ms","start":"2026-09-12T06:37:19.635853Z","end":"2026-09-12T06:37:19.743691Z","steps":["trace[448289607] 'process raft request' (duration: 107.664415ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:56.900209Z","caller":"traceutil/trace.go:171","msg":"trace[916701056] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"177.119973ms","start":"2026-09-12T06:37:56.723053Z","end":"2026-09-12T06:37:56.900173Z","steps":["trace[916701056] 'process raft request' (duration: 176.874608ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:56.914345Z","caller":"traceutil/trace.go:171","msg":"trace[1031242903] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2267; }","duration":"107.99713ms","start":"2026-09-12T06:37:56.806321Z","end":"2026-09-12T06:37:56.914318Z","steps":["trace[1031242903] 'read index received' (duration: 93.736657ms)","trace[1031242903] 'applied index is now lower than readState.Index' (duration: 14.258873ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:37:56.914441Z","caller":"traceutil/trace.go:171","msg":"trace[590061011] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"130.945989ms","start":"2026-09-12T06:37:56.783443Z","end":"2026-09-12T06:37:56.914389Z","steps":["trace[590061011] 'process raft request' (duration: 130.673323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:37:56.914605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.260996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T06:37:56.914686Z","caller":"traceutil/trace.go:171","msg":"trace[348798826] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2184; }","duration":"108.395459ms","start":"2026-09-12T06:37:56.806269Z","end":"2026-09-12T06:37:56.914664Z","steps":["trace[348798826] 'agreement among raft nodes before linearized reading' (duration: 108.196714ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:57.135214Z","caller":"traceutil/trace.go:171","msg":"trace[1694874876] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"142.809743ms","start":"2026-09-12T06:37:56.992367Z","end":"2026-09-12T06:37:57.135176Z","steps":["trace[1694874876] 'process raft request' (duration: 142.445315ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:37:57.259524Z","caller":"traceutil/trace.go:171","msg":"trace[246250332] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"113.675602ms","start":"2026-09-12T06:37:57.145723Z","end":"2026-09-12T06:37:57.259399Z","steps":["trace[246250332] 'process raft request' (duration: 94.81713ms)","trace[246250332] 'compare' (duration: 18.731488ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:37:57.771502Z","caller":"traceutil/trace.go:171","msg":"trace[2080515094] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"288.069306ms","start":"2026-09-12T06:37:57.483407Z","end":"2026-09-12T06:37:57.771477Z","steps":["trace[2080515094] 'process raft request' (duration: 287.867292ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:38:03.055067Z","caller":"traceutil/trace.go:171","msg":"trace[855888101] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"133.693078ms","start":"2026-09-12T06:38:02.921347Z","end":"2026-09-12T06:38:03.055041Z","steps":["trace[855888101] 'process raft request' (duration: 133.518504ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:38:03.058613Z","caller":"traceutil/trace.go:171","msg":"trace[364739012] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"125.639937ms","start":"2026-09-12T06:38:02.932939Z","end":"2026-09-12T06:38:03.058579Z","steps":["trace[364739012] 'process raft request' (duration: 125.413352ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:39:24.670628Z","caller":"traceutil/trace.go:171","msg":"trace[3145372] linearizableReadLoop","detail":"{readStateIndex:2957; appliedIndex:2954; }","duration":"220.310213ms","start":"2026-09-12T06:39:24.450289Z","end":"2026-09-12T06:39:24.670599Z","steps":["trace[3145372] 'read index received' (duration: 16.811438ms)","trace[3145372] 'applied index is now lower than readState.Index' (duration: 203.497505ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:39:24.670707Z","caller":"traceutil/trace.go:171","msg":"trace[1841607025] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"285.050913ms","start":"2026-09-12T06:39:24.385608Z","end":"2026-09-12T06:39:24.670659Z","steps":["trace[1841607025] 'process raft request' (duration: 244.293005ms)","trace[1841607025] 'compare' (duration: 40.435051ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:39:24.670756Z","caller":"traceutil/trace.go:171","msg":"trace[21305204] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"253.246495ms","start":"2026-09-12T06:39:24.417462Z","end":"2026-09-12T06:39:24.670708Z","steps":["trace[21305204] 'process raft request' (duration: 253.059162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:39:24.670849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.903052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T06:39:24.670897Z","caller":"traceutil/trace.go:171","msg":"trace[1166070930] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2846; }","duration":"118.021054ms","start":"2026-09-12T06:39:24.552863Z","end":"2026-09-12T06:39:24.670884Z","steps":["trace[1166070930] 'agreement among raft nodes before linearized reading' (duration: 117.909812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:39:24.670876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.570239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-12T06:39:24.670962Z","caller":"traceutil/trace.go:171","msg":"trace[276090187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2846; }","duration":"220.703982ms","start":"2026-09-12T06:39:24.450236Z","end":"2026-09-12T06:39:24.67094Z","steps":["trace[276090187] 'agreement among raft nodes before linearized reading' (duration: 220.470887ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:39:30.357854Z","caller":"traceutil/trace.go:171","msg":"trace[427662155] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"133.431537ms","start":"2026-09-12T06:39:30.224376Z","end":"2026-09-12T06:39:30.357807Z","steps":["trace[427662155] 'process raft request' (duration: 133.219802ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:44:07.592732Z","caller":"traceutil/trace.go:171","msg":"trace[883210981] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"131.51825ms","start":"2026-09-12T06:44:07.461157Z","end":"2026-09-12T06:44:07.592675Z","steps":["trace[883210981] 'process raft request' (duration: 131.322146ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:44:31.625324Z","caller":"traceutil/trace.go:171","msg":"trace[948357662] linearizableReadLoop","detail":"{readStateIndex:5961; appliedIndex:5960; }","duration":"147.388678ms","start":"2026-09-12T06:44:31.477887Z","end":"2026-09-12T06:44:31.625276Z","steps":["trace[948357662] 'read index received' (duration: 142.028534ms)","trace[948357662] 'applied index is now lower than readState.Index' (duration: 5.358944ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:44:31.625437Z","caller":"traceutil/trace.go:171","msg":"trace[1496502456] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"157.747439ms","start":"2026-09-12T06:44:31.467647Z","end":"2026-09-12T06:44:31.625394Z","steps":["trace[1496502456] 'process raft request' (duration: 152.272173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:44:31.625566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.651234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-12T06:44:31.626127Z","caller":"traceutil/trace.go:171","msg":"trace[1945091232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5744; }","duration":"148.250586ms","start":"2026-09-12T06:44:31.477861Z","end":"2026-09-12T06:44:31.626111Z","steps":["trace[1945091232] 'agreement among raft nodes before linearized reading' (duration: 147.543091ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:44:31.825767Z","caller":"traceutil/trace.go:171","msg":"trace[186935871] transaction","detail":"{read_only:false; response_revision:5747; number_of_response:1; }","duration":"121.299933ms","start":"2026-09-12T06:44:31.704448Z","end":"2026-09-12T06:44:31.825748Z","steps":["trace[186935871] 'process raft request' (duration: 121.241901ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:44:31.825766Z","caller":"traceutil/trace.go:171","msg":"trace[2056282756] transaction","detail":"{read_only:false; response_revision:5746; number_of_response:1; }","duration":"185.346646ms","start":"2026-09-12T06:44:31.640395Z","end":"2026-09-12T06:44:31.825742Z","steps":["trace[2056282756] 'process raft request' (duration: 174.572966ms)","trace[2056282756] 'compare' (duration: 10.580946ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:44:43.419869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-09-12T06:44:43.496843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"75.446256ms","hash":560679707,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":32227328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T06:44:43.496902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":560679707,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-09-12T06:45:24.106455Z","caller":"traceutil/trace.go:171","msg":"trace[1398559094] transaction","detail":"{read_only:false; response_revision:6206; number_of_response:1; }","duration":"113.061869ms","start":"2026-09-12T06:45:23.993365Z","end":"2026-09-12T06:45:24.106427Z","steps":["trace[1398559094] 'process raft request' (duration: 40.798469ms)","trace[1398559094] 'compare' (duration: 72.068106ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:49:43.427082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5841} {"level":"info","ts":"2026-09-12T06:49:43.517795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5841,"took":"88.703889ms","hash":3971853792,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":26402816,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-12T06:49:43.517897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3971853792,"revision":5841,"compact-revision":2950} {"level":"warn","ts":"2026-09-12T06:51:11.750489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.760631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T06:51:11.750613Z","caller":"traceutil/trace.go:171","msg":"trace[803247287] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8704; }","duration":"210.994066ms","start":"2026-09-12T06:51:11.539595Z","end":"2026-09-12T06:51:11.750589Z","steps":["trace[803247287] 'range keys from in-memory index tree' (duration: 210.625388ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:51:35.509002Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e6.wal"} {"level":"info","ts":"2026-09-12T06:51:39.82367Z","caller":"traceutil/trace.go:171","msg":"trace[1186832720] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9228; }","duration":"176.920551ms","start":"2026-09-12T06:51:39.646721Z","end":"2026-09-12T06:51:39.823642Z","steps":["trace[1186832720] 'read index received' (duration: 176.714567ms)","trace[1186832720] 'applied index is now lower than readState.Index' (duration: 205.044µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T06:51:39.823861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.119885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ftv9n\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-12T06:51:39.823863Z","caller":"traceutil/trace.go:171","msg":"trace[945599883] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"234.083236ms","start":"2026-09-12T06:51:39.589762Z","end":"2026-09-12T06:51:39.823846Z","steps":["trace[945599883] 'process raft request' (duration: 233.730609ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:51:39.823887Z","caller":"traceutil/trace.go:171","msg":"trace[685203226] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ftv9n; range_end:; response_count:1; response_revision:8906; }","duration":"177.208927ms","start":"2026-09-12T06:51:39.646669Z","end":"2026-09-12T06:51:39.823878Z","steps":["trace[685203226] 'agreement among raft nodes before linearized reading' (duration: 177.073704ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:51:42.995011Z","caller":"traceutil/trace.go:171","msg":"trace[445818693] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"111.076402ms","start":"2026-09-12T06:51:42.883897Z","end":"2026-09-12T06:51:42.994973Z","steps":["trace[445818693] 'process raft request' (duration: 99.354973ms)","trace[445818693] 'compare' (duration: 11.563135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T06:51:52.530869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.664216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T06:51:52.531051Z","caller":"traceutil/trace.go:171","msg":"trace[449717034] transaction","detail":"{read_only:false; response_revision:8974; number_of_response:1; }","duration":"204.335133ms","start":"2026-09-12T06:51:52.326697Z","end":"2026-09-12T06:51:52.531032Z","steps":["trace[449717034] 'process raft request' (duration: 204.282812ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:51:52.531128Z","caller":"traceutil/trace.go:171","msg":"trace[1163679320] transaction","detail":"{read_only:false; response_revision:8973; number_of_response:1; }","duration":"208.121744ms","start":"2026-09-12T06:51:52.32295Z","end":"2026-09-12T06:51:52.531072Z","steps":["trace[1163679320] 'process raft request' (duration: 55.003179ms)","trace[1163679320] 'compare' (duration: 152.559644ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:51:52.594066Z","caller":"traceutil/trace.go:171","msg":"trace[1476857873] transaction","detail":"{read_only:false; response_revision:8975; number_of_response:1; }","duration":"153.487504ms","start":"2026-09-12T06:51:52.440527Z","end":"2026-09-12T06:51:52.594014Z","steps":["trace[1476857873] 'process raft request' (duration: 153.216627ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:52:06.753567Z","caller":"traceutil/trace.go:171","msg":"trace[1317872297] transaction","detail":"{read_only:false; response_revision:9124; number_of_response:1; }","duration":"108.723172ms","start":"2026-09-12T06:52:06.644795Z","end":"2026-09-12T06:52:06.753518Z","steps":["trace[1317872297] 'process raft request' (duration: 108.452616ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:52:55.254235Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T06:52:55.265147Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-12T06:52:55.265312Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-12T06:53:22.106094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.371387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-12T06:54:43.43668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8004} {"level":"info","ts":"2026-09-12T06:54:43.504315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8004,"took":"65.869467ms","hash":1384543530,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":27889664,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-12T06:54:43.504403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1384543530,"revision":8004,"compact-revision":5841} {"level":"info","ts":"2026-09-12T06:59:01.772215Z","caller":"traceutil/trace.go:171","msg":"trace[150052723] linearizableReadLoop","detail":"{readStateIndex:12708; appliedIndex:12707; }","duration":"160.538852ms","start":"2026-09-12T06:59:01.611642Z","end":"2026-09-12T06:59:01.772181Z","steps":["trace[150052723] 'read index received' (duration: 160.226144ms)","trace[150052723] 'applied index is now lower than readState.Index' (duration: 310.998µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T06:59:01.772249Z","caller":"traceutil/trace.go:171","msg":"trace[723405858] transaction","detail":"{read_only:false; response_revision:12276; number_of_response:1; }","duration":"163.158828ms","start":"2026-09-12T06:59:01.60905Z","end":"2026-09-12T06:59:01.772209Z","steps":["trace[723405858] 'process raft request' (duration: 162.935083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T06:59:01.772409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.781647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T06:59:01.772538Z","caller":"traceutil/trace.go:171","msg":"trace[1639530777] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12276; }","duration":"160.951741ms","start":"2026-09-12T06:59:01.611568Z","end":"2026-09-12T06:59:01.772519Z","steps":["trace[1639530777] 'agreement among raft nodes before linearized reading' (duration: 160.750947ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T06:59:43.444025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10372} {"level":"info","ts":"2026-09-12T06:59:43.527518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10372,"took":"81.060578ms","hash":1287615068,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":25989120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-12T06:59:43.527575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1287615068,"revision":10372,"compact-revision":8004} {"level":"info","ts":"2026-09-12T07:04:43.457212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12579} {"level":"info","ts":"2026-09-12T07:04:43.538374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12579,"took":"79.134842ms","hash":2896926584,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":27742208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-12T07:04:43.538452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2896926584,"revision":12579,"compact-revision":10372} {"level":"info","ts":"2026-09-12T07:05:35.609225Z","caller":"traceutil/trace.go:171","msg":"trace[1164634408] transaction","detail":"{read_only:false; response_revision:14929; number_of_response:1; }","duration":"193.21473ms","start":"2026-09-12T07:05:35.415974Z","end":"2026-09-12T07:05:35.609188Z","steps":["trace[1164634408] 'process raft request' (duration: 192.984745ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:05:43.737015Z","caller":"traceutil/trace.go:171","msg":"trace[573242716] transaction","detail":"{read_only:false; response_revision:14972; number_of_response:1; }","duration":"102.532564ms","start":"2026-09-12T07:05:43.634442Z","end":"2026-09-12T07:05:43.736974Z","steps":["trace[573242716] 'process raft request' (duration: 98.007669ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:05:51.566726Z","caller":"traceutil/trace.go:171","msg":"trace[1253122158] linearizableReadLoop","detail":"{readStateIndex:15556; appliedIndex:15555; }","duration":"113.427473ms","start":"2026-09-12T07:05:51.453275Z","end":"2026-09-12T07:05:51.566702Z","steps":["trace[1253122158] 'read index received' (duration: 113.143387ms)","trace[1253122158] 'applied index is now lower than readState.Index' (duration: 282.956µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:05:51.56683Z","caller":"traceutil/trace.go:171","msg":"trace[1850286771] transaction","detail":"{read_only:false; response_revision:15027; number_of_response:1; }","duration":"192.546845ms","start":"2026-09-12T07:05:51.374263Z","end":"2026-09-12T07:05:51.56681Z","steps":["trace[1850286771] 'process raft request' (duration: 192.27259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:05:51.566871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.572276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T07:05:51.566902Z","caller":"traceutil/trace.go:171","msg":"trace[943373506] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:15027; }","duration":"113.650707ms","start":"2026-09-12T07:05:51.453242Z","end":"2026-09-12T07:05:51.566892Z","steps":["trace[943373506] 'agreement among raft nodes before linearized reading' (duration: 113.557075ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:07:43.866173Z","caller":"traceutil/trace.go:171","msg":"trace[296284801] transaction","detail":"{read_only:false; response_revision:16293; number_of_response:1; }","duration":"103.977855ms","start":"2026-09-12T07:07:43.761991Z","end":"2026-09-12T07:07:43.865969Z","steps":["trace[296284801] 'process raft request' (duration: 103.810361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:07:44.089528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.171428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T07:07:44.089634Z","caller":"traceutil/trace.go:171","msg":"trace[1935073048] linearizableReadLoop","detail":"{readStateIndex:16862; appliedIndex:16861; }","duration":"213.865924ms","start":"2026-09-12T07:07:43.875752Z","end":"2026-09-12T07:07:44.089618Z","steps":["trace[1935073048] 'read index received' (duration: 47.43221ms)","trace[1935073048] 'applied index is now lower than readState.Index' (duration: 166.429534ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:07:44.089705Z","caller":"traceutil/trace.go:171","msg":"trace[1892088502] transaction","detail":"{read_only:false; response_revision:16294; number_of_response:1; }","duration":"298.1651ms","start":"2026-09-12T07:07:43.791513Z","end":"2026-09-12T07:07:44.089679Z","steps":["trace[1892088502] 'process raft request' (duration: 131.756216ms)","trace[1892088502] 'compare' (duration: 166.022076ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:07:44.089796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.042618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-12T07:07:44.089825Z","caller":"traceutil/trace.go:171","msg":"trace[1230425533] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16294; }","duration":"214.11258ms","start":"2026-09-12T07:07:43.875705Z","end":"2026-09-12T07:07:44.089817Z","steps":["trace[1230425533] 'agreement among raft nodes before linearized reading' (duration: 213.992347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:07:44.09002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.182791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T07:07:44.0901Z","caller":"traceutil/trace.go:171","msg":"trace[2133954871] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16294; }","duration":"214.285884ms","start":"2026-09-12T07:07:43.875796Z","end":"2026-09-12T07:07:44.090082Z","steps":["trace[2133954871] 'agreement among raft nodes before linearized reading' (duration: 214.151211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:07:44.09019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.976772ms","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-09-12T07:07:44.09025Z","caller":"traceutil/trace.go:171","msg":"trace[470059856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16294; }","duration":"132.049333ms","start":"2026-09-12T07:07:43.958186Z","end":"2026-09-12T07:07:44.090236Z","steps":["trace[470059856] 'agreement among raft nodes before linearized reading' (duration: 131.837449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:07:44.090293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.09702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-12T07:07:44.090425Z","caller":"traceutil/trace.go:171","msg":"trace[785762073] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16294; }","duration":"214.375186ms","start":"2026-09-12T07:07:43.876017Z","end":"2026-09-12T07:07:44.090393Z","steps":["trace[785762073] 'agreement among raft nodes before linearized reading' (duration: 214.019768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:07:44.424896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.789529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T07:07:44.425014Z","caller":"traceutil/trace.go:171","msg":"trace[1808476777] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:16297; }","duration":"117.928402ms","start":"2026-09-12T07:07:44.307063Z","end":"2026-09-12T07:07:44.424991Z","steps":["trace[1808476777] 'count revisions from in-memory index tree' (duration: 117.725128ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:07:44.647406Z","caller":"traceutil/trace.go:171","msg":"trace[1520032567] transaction","detail":"{read_only:false; response_revision:16299; number_of_response:1; }","duration":"171.432691ms","start":"2026-09-12T07:07:44.475929Z","end":"2026-09-12T07:07:44.647362Z","steps":["trace[1520032567] 'process raft request' (duration: 171.270977ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:09:43.463848Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14555} {"level":"info","ts":"2026-09-12T07:09:43.550316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14555,"took":"83.823815ms","hash":4214168957,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T07:09:43.550391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4214168957,"revision":14555,"compact-revision":12579} {"level":"info","ts":"2026-09-12T07:13:21.248643Z","caller":"traceutil/trace.go:171","msg":"trace[785214394] transaction","detail":"{read_only:false; response_revision:18748; number_of_response:1; }","duration":"115.759975ms","start":"2026-09-12T07:13:21.132825Z","end":"2026-09-12T07:13:21.248585Z","steps":["trace[785214394] 'process raft request' (duration: 58.72245ms)","trace[785214394] 'compare' (duration: 56.790389ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:13:21.587968Z","caller":"traceutil/trace.go:171","msg":"trace[341260707] transaction","detail":"{read_only:false; response_revision:18750; number_of_response:1; }","duration":"134.046875ms","start":"2026-09-12T07:13:21.453893Z","end":"2026-09-12T07:13:21.58794Z","steps":["trace[341260707] 'process raft request' (duration: 133.838121ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:13:21.611903Z","caller":"traceutil/trace.go:171","msg":"trace[180298106] transaction","detail":"{read_only:false; response_revision:18752; number_of_response:1; }","duration":"108.43517ms","start":"2026-09-12T07:13:21.503445Z","end":"2026-09-12T07:13:21.61188Z","steps":["trace[180298106] 'process raft request' (duration: 108.390729ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:13:21.611965Z","caller":"traceutil/trace.go:171","msg":"trace[590522012] transaction","detail":"{read_only:false; response_revision:18751; number_of_response:1; }","duration":"157.210088ms","start":"2026-09-12T07:13:21.454726Z","end":"2026-09-12T07:13:21.611936Z","steps":["trace[590522012] 'process raft request' (duration: 156.992593ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:13:26.833504Z","caller":"traceutil/trace.go:171","msg":"trace[1194953947] transaction","detail":"{read_only:false; response_revision:18806; number_of_response:1; }","duration":"148.760698ms","start":"2026-09-12T07:13:26.684707Z","end":"2026-09-12T07:13:26.833468Z","steps":["trace[1194953947] 'process raft request' (duration: 105.272992ms)","trace[1194953947] 'compare' (duration: 43.267151ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:13:26.833574Z","caller":"traceutil/trace.go:171","msg":"trace[1071817342] linearizableReadLoop","detail":"{readStateIndex:19457; appliedIndex:19456; }","duration":"147.273766ms","start":"2026-09-12T07:13:26.686287Z","end":"2026-09-12T07:13:26.833561Z","steps":["trace[1071817342] 'read index received' (duration: 102.662276ms)","trace[1071817342] 'applied index is now lower than readState.Index' (duration: 44.61055ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:13:26.834778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.473442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:948209"} {"level":"info","ts":"2026-09-12T07:13:26.834851Z","caller":"traceutil/trace.go:171","msg":"trace[1587172454] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18806; }","duration":"148.584735ms","start":"2026-09-12T07:13:26.686252Z","end":"2026-09-12T07:13:26.834837Z","steps":["trace[1587172454] 'agreement among raft nodes before linearized reading' (duration: 147.395419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:13:26.848185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.274546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.147\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-12T07:13:26.848268Z","caller":"traceutil/trace.go:171","msg":"trace[1642197027] range","detail":"{range_begin:/registry/masterleases/162.253.55.147; range_end:; response_count:1; response_revision:18807; }","duration":"154.405109ms","start":"2026-09-12T07:13:26.693843Z","end":"2026-09-12T07:13:26.848249Z","steps":["trace[1642197027] 'agreement among raft nodes before linearized reading' (duration: 154.191194ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:14:17.548355Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":20013,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T07:14:17.56126Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20013} {"level":"info","ts":"2026-09-12T07:14:17.561369Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15013} {"level":"info","ts":"2026-09-12T07:14:43.470106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17041} {"level":"info","ts":"2026-09-12T07:14:43.574408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17041,"took":"100.865078ms","hash":558651556,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":34283520,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-12T07:14:43.574498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":558651556,"revision":17041,"compact-revision":14555} {"level":"info","ts":"2026-09-12T07:19:43.477363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19652} {"level":"info","ts":"2026-09-12T07:19:43.601887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19652,"took":"120.646519ms","hash":51560789,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":30072832,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-12T07:19:43.601939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":51560789,"revision":19652,"compact-revision":17041} {"level":"info","ts":"2026-09-12T07:19:55.592293Z","caller":"traceutil/trace.go:171","msg":"trace[548146809] transaction","detail":"{read_only:false; response_revision:21739; number_of_response:1; }","duration":"110.724919ms","start":"2026-09-12T07:19:55.481538Z","end":"2026-09-12T07:19:55.592263Z","steps":["trace[548146809] 'process raft request' (duration: 110.527955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:55.809184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.358264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:19:55.80946Z","caller":"traceutil/trace.go:171","msg":"trace[573743323] transaction","detail":"{read_only:false; response_revision:21740; number_of_response:1; }","duration":"260.11602ms","start":"2026-09-12T07:19:55.549323Z","end":"2026-09-12T07:19:55.809439Z","steps":["trace[573743323] 'process raft request' (duration: 77.400589ms)","trace[573743323] 'compare' (duration: 182.254301ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:19:56.073896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.979629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19221"} {"level":"info","ts":"2026-09-12T07:19:56.073971Z","caller":"traceutil/trace.go:171","msg":"trace[1843600620] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21741; }","duration":"123.104402ms","start":"2026-09-12T07:19:55.950848Z","end":"2026-09-12T07:19:56.073953Z","steps":["trace[1843600620] 'range keys from in-memory index tree' (duration: 122.806875ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:19:56.412132Z","caller":"traceutil/trace.go:171","msg":"trace[520183568] linearizableReadLoop","detail":"{readStateIndex:22491; appliedIndex:22490; }","duration":"332.903601ms","start":"2026-09-12T07:19:56.079201Z","end":"2026-09-12T07:19:56.412104Z","steps":["trace[520183568] 'read index received' (duration: 332.736847ms)","trace[520183568] 'applied index is now lower than readState.Index' (duration: 165.444µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:19:56.41216Z","caller":"traceutil/trace.go:171","msg":"trace[508932698] transaction","detail":"{read_only:false; response_revision:21742; number_of_response:1; }","duration":"333.230177ms","start":"2026-09-12T07:19:56.078902Z","end":"2026-09-12T07:19:56.412133Z","steps":["trace[508932698] 'process raft request' (duration: 333.033633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.412286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:19:56.078874Z","time spent":"333.34539ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:19:56.412746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.492554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-09-12T07:19:56.41281Z","caller":"traceutil/trace.go:171","msg":"trace[2030779262] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:21742; }","duration":"333.638097ms","start":"2026-09-12T07:19:56.079155Z","end":"2026-09-12T07:19:56.412793Z","steps":["trace[2030779262] 'agreement among raft nodes before linearized reading' (duration: 333.074554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.412861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:19:56.07914Z","time spent":"333.707378ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":1160,"request content":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" "} {"level":"info","ts":"2026-09-12T07:19:56.556444Z","caller":"traceutil/trace.go:171","msg":"trace[255346133] linearizableReadLoop","detail":"{readStateIndex:22492; appliedIndex:22491; }","duration":"144.216192ms","start":"2026-09-12T07:19:56.412202Z","end":"2026-09-12T07:19:56.556418Z","steps":["trace[255346133] 'read index received' (duration: 124.625935ms)","trace[255346133] 'applied index is now lower than readState.Index' (duration: 19.589427ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:19:56.556507Z","caller":"traceutil/trace.go:171","msg":"trace[2015404105] transaction","detail":"{read_only:false; response_revision:21743; number_of_response:1; }","duration":"236.284272ms","start":"2026-09-12T07:19:56.320205Z","end":"2026-09-12T07:19:56.556489Z","steps":["trace[2015404105] 'process raft request' (duration: 216.631704ms)","trace[2015404105] 'compare' (duration: 19.472475ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:19:56.556604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.76681ms","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-09-12T07:19:56.556644Z","caller":"traceutil/trace.go:171","msg":"trace[464454851] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:21743; }","duration":"257.841061ms","start":"2026-09-12T07:19:56.298794Z","end":"2026-09-12T07:19:56.556635Z","steps":["trace[464454851] 'agreement among raft nodes before linearized reading' (duration: 257.740729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.556821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.905156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:19:56.556887Z","caller":"traceutil/trace.go:171","msg":"trace[1741809456] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:21743; }","duration":"137.009649ms","start":"2026-09-12T07:19:56.419867Z","end":"2026-09-12T07:19:56.556877Z","steps":["trace[1741809456] 'agreement among raft nodes before linearized reading' (duration: 136.923947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.556844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.670113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:19:56.556946Z","caller":"traceutil/trace.go:171","msg":"trace[664443679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21743; }","duration":"284.811886ms","start":"2026-09-12T07:19:56.27211Z","end":"2026-09-12T07:19:56.556922Z","steps":["trace[664443679] 'agreement among raft nodes before linearized reading' (duration: 284.615912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.557113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.615069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-12T07:19:56.5577Z","caller":"traceutil/trace.go:171","msg":"trace[511239838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21743; }","duration":"313.295772ms","start":"2026-09-12T07:19:56.244385Z","end":"2026-09-12T07:19:56.557681Z","steps":["trace[511239838] 'agreement among raft nodes before linearized reading' (duration: 312.252201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.557735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:19:56.244368Z","time spent":"313.358624ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-12T07:19:56.868034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.726637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:19:56.868156Z","caller":"traceutil/trace.go:171","msg":"trace[1073412441] linearizableReadLoop","detail":"{readStateIndex:22493; appliedIndex:22492; }","duration":"304.609868ms","start":"2026-09-12T07:19:56.563527Z","end":"2026-09-12T07:19:56.868137Z","steps":["trace[1073412441] 'read index received' (duration: 140.614686ms)","trace[1073412441] 'applied index is now lower than readState.Index' (duration: 163.993402ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:19:56.868225Z","caller":"traceutil/trace.go:171","msg":"trace[1549259275] transaction","detail":"{read_only:false; response_revision:21744; number_of_response:1; }","duration":"306.6151ms","start":"2026-09-12T07:19:56.561564Z","end":"2026-09-12T07:19:56.868179Z","steps":["trace[1549259275] 'process raft request' (duration: 142.663318ms)","trace[1549259275] 'compare' (duration: 163.541993ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:19:56.868394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.858073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:685"} {"level":"info","ts":"2026-09-12T07:19:56.868432Z","caller":"traceutil/trace.go:171","msg":"trace[2031572190] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:21744; }","duration":"304.901454ms","start":"2026-09-12T07:19:56.563516Z","end":"2026-09-12T07:19:56.868417Z","steps":["trace[2031572190] 'agreement among raft nodes before linearized reading' (duration: 304.72542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.868477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:19:56.56351Z","time spent":"304.959025ms","remote":"127.0.0.1:56764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":708,"request content":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" "} {"level":"warn","ts":"2026-09-12T07:19:56.86848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.443279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.147\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-12T07:19:56.868568Z","caller":"traceutil/trace.go:171","msg":"trace[653920116] range","detail":"{range_begin:/registry/masterleases/162.253.55.147; range_end:; response_count:1; response_revision:21744; }","duration":"154.580472ms","start":"2026-09-12T07:19:56.713963Z","end":"2026-09-12T07:19:56.868543Z","steps":["trace[653920116] 'agreement among raft nodes before linearized reading' (duration: 154.343128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:19:56.86891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:19:56.561545Z","time spent":"306.743523ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:19:57.030986Z","caller":"traceutil/trace.go:171","msg":"trace[2042795724] transaction","detail":"{read_only:false; response_revision:21745; number_of_response:1; }","duration":"148.945582ms","start":"2026-09-12T07:19:56.882009Z","end":"2026-09-12T07:19:57.030954Z","steps":["trace[2042795724] 'process raft request' (duration: 123.710334ms)","trace[2042795724] 'compare' (duration: 25.095255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:19:57.349412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.462076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:19:57.349535Z","caller":"traceutil/trace.go:171","msg":"trace[1526397167] linearizableReadLoop","detail":"{readStateIndex:22496; appliedIndex:22495; }","duration":"164.218798ms","start":"2026-09-12T07:19:57.185295Z","end":"2026-09-12T07:19:57.349513Z","steps":["trace[1526397167] 'read index received' (duration: 27.565437ms)","trace[1526397167] 'applied index is now lower than readState.Index' (duration: 136.651801ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:19:57.349605Z","caller":"traceutil/trace.go:171","msg":"trace[1174886274] transaction","detail":"{read_only:false; response_revision:21746; number_of_response:1; }","duration":"170.215446ms","start":"2026-09-12T07:19:57.179354Z","end":"2026-09-12T07:19:57.349569Z","steps":["trace[1174886274] 'process raft request' (duration: 33.491194ms)","trace[1174886274] 'compare' (duration: 136.332194ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:19:57.349715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.409372ms","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-09-12T07:19:57.349778Z","caller":"traceutil/trace.go:171","msg":"trace[783993344] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:21746; }","duration":"164.508774ms","start":"2026-09-12T07:19:57.185248Z","end":"2026-09-12T07:19:57.349757Z","steps":["trace[783993344] 'agreement among raft nodes before linearized reading' (duration: 164.354301ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:19:57.362909Z","caller":"traceutil/trace.go:171","msg":"trace[1605931553] transaction","detail":"{read_only:false; response_revision:21747; number_of_response:1; }","duration":"176.079351ms","start":"2026-09-12T07:19:57.186806Z","end":"2026-09-12T07:19:57.362886Z","steps":["trace[1605931553] 'process raft request' (duration: 175.865036ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:19:57.363381Z","caller":"traceutil/trace.go:171","msg":"trace[318470935] transaction","detail":"{read_only:false; response_revision:21748; number_of_response:1; }","duration":"153.465649ms","start":"2026-09-12T07:19:57.209895Z","end":"2026-09-12T07:19:57.363361Z","steps":["trace[318470935] 'process raft request' (duration: 152.937438ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:22:55.943582Z","caller":"traceutil/trace.go:171","msg":"trace[1100579089] transaction","detail":"{read_only:false; response_revision:23147; number_of_response:1; }","duration":"107.488118ms","start":"2026-09-12T07:22:55.836067Z","end":"2026-09-12T07:22:55.943555Z","steps":["trace[1100579089] 'process raft request' (duration: 101.610884ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:22:55.975328Z","caller":"traceutil/trace.go:171","msg":"trace[65157015] linearizableReadLoop","detail":"{readStateIndex:23945; appliedIndex:23943; }","duration":"129.155531ms","start":"2026-09-12T07:22:55.84615Z","end":"2026-09-12T07:22:55.975305Z","steps":["trace[65157015] 'read index received' (duration: 91.542879ms)","trace[65157015] 'applied index is now lower than readState.Index' (duration: 37.611522ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:22:55.975429Z","caller":"traceutil/trace.go:171","msg":"trace[2132496213] transaction","detail":"{read_only:false; response_revision:23148; number_of_response:1; }","duration":"132.824019ms","start":"2026-09-12T07:22:55.842563Z","end":"2026-09-12T07:22:55.975387Z","steps":["trace[2132496213] 'process raft request' (duration: 132.586654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:22:55.975532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.356655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T07:22:55.97557Z","caller":"traceutil/trace.go:171","msg":"trace[2018804272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23148; }","duration":"129.430947ms","start":"2026-09-12T07:22:55.846132Z","end":"2026-09-12T07:22:55.975563Z","steps":["trace[2018804272] 'agreement among raft nodes before linearized reading' (duration: 129.291154ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:24:09.656737Z","caller":"traceutil/trace.go:171","msg":"trace[2045442577] transaction","detail":"{read_only:false; response_revision:24282; number_of_response:1; }","duration":"114.949689ms","start":"2026-09-12T07:24:09.541724Z","end":"2026-09-12T07:24:09.656673Z","steps":["trace[2045442577] 'process raft request' (duration: 114.757405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:24:10.011885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.991272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:70275"} {"level":"warn","ts":"2026-09-12T07:24:10.011898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.578715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:24:10.011981Z","caller":"traceutil/trace.go:171","msg":"trace[975490323] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:24284; }","duration":"248.153295ms","start":"2026-09-12T07:24:09.763804Z","end":"2026-09-12T07:24:10.011957Z","steps":["trace[975490323] 'range keys from in-memory index tree' (duration: 247.750227ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:24:10.012019Z","caller":"traceutil/trace.go:171","msg":"trace[1017637269] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24284; }","duration":"256.82187ms","start":"2026-09-12T07:24:09.755169Z","end":"2026-09-12T07:24:10.011991Z","steps":["trace[1017637269] 'count revisions from in-memory index tree' (duration: 256.402661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:24:10.012072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.920694ms","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-09-12T07:24:10.012128Z","caller":"traceutil/trace.go:171","msg":"trace[1166644483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24284; }","duration":"174.026656ms","start":"2026-09-12T07:24:09.838086Z","end":"2026-09-12T07:24:10.012113Z","steps":["trace[1166644483] 'range keys from in-memory index tree' (duration: 173.744821ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:24:43.483842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21680} {"level":"info","ts":"2026-09-12T07:24:43.594448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21680,"took":"106.761564ms","hash":2219524721,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":42795008,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-12T07:24:43.594516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2219524721,"revision":21680,"compact-revision":19652} {"level":"info","ts":"2026-09-12T07:26:35.645174Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006807.wal"} {"level":"info","ts":"2026-09-12T07:27:49.108677Z","caller":"traceutil/trace.go:171","msg":"trace[798123188] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"148.659956ms","start":"2026-09-12T07:27:48.959998Z","end":"2026-09-12T07:27:49.108658Z","steps":["trace[798123188] 'process raft request' (duration: 148.547653ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:49.401101Z","caller":"traceutil/trace.go:171","msg":"trace[961471136] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"209.395989ms","start":"2026-09-12T07:27:49.191682Z","end":"2026-09-12T07:27:49.401078Z","steps":["trace[961471136] 'process raft request' (duration: 124.682215ms)","trace[961471136] 'compare' (duration: 84.470859ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:49.486795Z","caller":"traceutil/trace.go:171","msg":"trace[1078543062] linearizableReadLoop","detail":"{readStateIndex:27371; appliedIndex:27369; }","duration":"246.95612ms","start":"2026-09-12T07:27:49.239821Z","end":"2026-09-12T07:27:49.486777Z","steps":["trace[1078543062] 'read index received' (duration: 76.453129ms)","trace[1078543062] 'applied index is now lower than readState.Index' (duration: 170.502321ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:49.486845Z","caller":"traceutil/trace.go:171","msg":"trace[137735385] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"279.538514ms","start":"2026-09-12T07:27:49.207272Z","end":"2026-09-12T07:27:49.48681Z","steps":["trace[137735385] 'process raft request' (duration: 279.3427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:49.486968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.135594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:49.486876Z","caller":"traceutil/trace.go:171","msg":"trace[1604410110] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"217.158645ms","start":"2026-09-12T07:27:49.269712Z","end":"2026-09-12T07:27:49.486871Z","steps":["trace[1604410110] 'process raft request' (duration: 217.027062ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:49.486993Z","caller":"traceutil/trace.go:171","msg":"trace[1846222107] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26491; }","duration":"247.206374ms","start":"2026-09-12T07:27:49.239781Z","end":"2026-09-12T07:27:49.486987Z","steps":["trace[1846222107] 'agreement among raft nodes before linearized reading' (duration: 247.109882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:49.487013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.636802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:49.487108Z","caller":"traceutil/trace.go:171","msg":"trace[1640953625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26491; }","duration":"214.773304ms","start":"2026-09-12T07:27:49.27231Z","end":"2026-09-12T07:27:49.487084Z","steps":["trace[1640953625] 'agreement among raft nodes before linearized reading' (duration: 214.603221ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:49.688362Z","caller":"traceutil/trace.go:171","msg":"trace[697192074] linearizableReadLoop","detail":"{readStateIndex:27373; appliedIndex:27372; }","duration":"117.36185ms","start":"2026-09-12T07:27:49.570956Z","end":"2026-09-12T07:27:49.688318Z","steps":["trace[697192074] 'read index received' (duration: 30.221244ms)","trace[697192074] 'applied index is now lower than readState.Index' (duration: 87.139186ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:49.68851Z","caller":"traceutil/trace.go:171","msg":"trace[1981125603] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"135.505536ms","start":"2026-09-12T07:27:49.552973Z","end":"2026-09-12T07:27:49.688479Z","steps":["trace[1981125603] 'process raft request' (duration: 48.186236ms)","trace[1981125603] 'compare' (duration: 86.916112ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:49.688602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.627385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T07:27:49.688635Z","caller":"traceutil/trace.go:171","msg":"trace[715437006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26492; }","duration":"117.695567ms","start":"2026-09-12T07:27:49.570928Z","end":"2026-09-12T07:27:49.688624Z","steps":["trace[715437006] 'agreement among raft nodes before linearized reading' (duration: 117.551764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:49.688876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.798688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:49.688968Z","caller":"traceutil/trace.go:171","msg":"trace[1927677588] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26492; }","duration":"116.939281ms","start":"2026-09-12T07:27:49.572011Z","end":"2026-09-12T07:27:49.68895Z","steps":["trace[1927677588] 'agreement among raft nodes before linearized reading' (duration: 116.710416ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:53.698642Z","caller":"traceutil/trace.go:171","msg":"trace[909181154] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"198.405985ms","start":"2026-09-12T07:27:53.500203Z","end":"2026-09-12T07:27:53.698609Z","steps":["trace[909181154] 'process raft request' (duration: 143.606979ms)","trace[909181154] 'compare' (duration: 54.643523ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:53.989159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.568947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:53.989278Z","caller":"traceutil/trace.go:171","msg":"trace[2007222201] linearizableReadLoop","detail":"{readStateIndex:27401; appliedIndex:27400; }","duration":"271.674356ms","start":"2026-09-12T07:27:53.717584Z","end":"2026-09-12T07:27:53.989258Z","steps":["trace[2007222201] 'read index received' (duration: 128.781803ms)","trace[2007222201] 'applied index is now lower than readState.Index' (duration: 142.890843ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:53.989409Z","caller":"traceutil/trace.go:171","msg":"trace[1606069939] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"287.550754ms","start":"2026-09-12T07:27:53.701791Z","end":"2026-09-12T07:27:53.989342Z","steps":["trace[1606069939] 'process raft request' (duration: 144.605419ms)","trace[1606069939] 'compare' (duration: 142.409354ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:53.989615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.250019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T07:27:53.989613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.008643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T07:27:53.989692Z","caller":"traceutil/trace.go:171","msg":"trace[1381347109] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:26519; }","duration":"118.363781ms","start":"2026-09-12T07:27:53.871311Z","end":"2026-09-12T07:27:53.989675Z","steps":["trace[1381347109] 'agreement among raft nodes before linearized reading' (duration: 118.064555ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:53.989726Z","caller":"traceutil/trace.go:171","msg":"trace[201764982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26519; }","duration":"272.175987ms","start":"2026-09-12T07:27:53.717529Z","end":"2026-09-12T07:27:53.989705Z","steps":["trace[201764982] 'agreement among raft nodes before linearized reading' (duration: 271.84422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:54.299309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.470362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:54.299439Z","caller":"traceutil/trace.go:171","msg":"trace[1769264214] linearizableReadLoop","detail":"{readStateIndex:27403; appliedIndex:27402; }","duration":"251.6637ms","start":"2026-09-12T07:27:54.047747Z","end":"2026-09-12T07:27:54.299411Z","steps":["trace[1769264214] 'read index received' (duration: 42.011425ms)","trace[1769264214] 'applied index is now lower than readState.Index' (duration: 209.651115ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:54.299518Z","caller":"traceutil/trace.go:171","msg":"trace[661716754] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"291.423507ms","start":"2026-09-12T07:27:54.008081Z","end":"2026-09-12T07:27:54.299504Z","steps":["trace[661716754] 'process raft request' (duration: 81.66311ms)","trace[661716754] 'compare' (duration: 209.354429ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:54.299968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.52248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-12T07:27:54.300069Z","caller":"traceutil/trace.go:171","msg":"trace[700641294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26521; }","duration":"200.714194ms","start":"2026-09-12T07:27:54.099326Z","end":"2026-09-12T07:27:54.300041Z","steps":["trace[700641294] 'agreement among raft nodes before linearized reading' (duration: 200.436639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:54.300153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.178349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T07:27:54.300219Z","caller":"traceutil/trace.go:171","msg":"trace[2070589894] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26521; }","duration":"197.256841ms","start":"2026-09-12T07:27:54.102946Z","end":"2026-09-12T07:27:54.300203Z","steps":["trace[2070589894] 'agreement among raft nodes before linearized reading' (duration: 197.129548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:54.300209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.460047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T07:27:54.300263Z","caller":"traceutil/trace.go:171","msg":"trace[1393333595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26521; }","duration":"252.552159ms","start":"2026-09-12T07:27:54.047695Z","end":"2026-09-12T07:27:54.300248Z","steps":["trace[1393333595] 'agreement among raft nodes before linearized reading' (duration: 252.372835ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:54.578493Z","caller":"traceutil/trace.go:171","msg":"trace[683375751] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"273.383263ms","start":"2026-09-12T07:27:54.305092Z","end":"2026-09-12T07:27:54.578475Z","steps":["trace[683375751] 'process raft request' (duration: 273.265431ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:54.596541Z","caller":"traceutil/trace.go:171","msg":"trace[296025930] linearizableReadLoop","detail":"{readStateIndex:27406; appliedIndex:27404; }","duration":"183.385355ms","start":"2026-09-12T07:27:54.413129Z","end":"2026-09-12T07:27:54.596514Z","steps":["trace[296025930] 'read index received' (duration: 165.29184ms)","trace[296025930] 'applied index is now lower than readState.Index' (duration: 18.092335ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:54.596602Z","caller":"traceutil/trace.go:171","msg":"trace[365895255] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"203.618887ms","start":"2026-09-12T07:27:54.392952Z","end":"2026-09-12T07:27:54.596571Z","steps":["trace[365895255] 'process raft request' (duration: 203.416603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:54.596853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.715332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-12T07:27:54.596906Z","caller":"traceutil/trace.go:171","msg":"trace[135639955] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26524; }","duration":"183.825435ms","start":"2026-09-12T07:27:54.413067Z","end":"2026-09-12T07:27:54.596892Z","steps":["trace[135639955] 'agreement among raft nodes before linearized reading' (duration: 183.645091ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:55.127145Z","caller":"traceutil/trace.go:171","msg":"trace[680128199] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"146.355317ms","start":"2026-09-12T07:27:54.98077Z","end":"2026-09-12T07:27:55.127125Z","steps":["trace[680128199] 'process raft request' (duration: 146.233785ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:55.233533Z","caller":"traceutil/trace.go:171","msg":"trace[512625496] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"101.755697ms","start":"2026-09-12T07:27:55.131756Z","end":"2026-09-12T07:27:55.233512Z","steps":["trace[512625496] 'process raft request' (duration: 29.291495ms)","trace[512625496] 'compare' (duration: 72.269978ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:55.752606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.654075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:55.752784Z","caller":"traceutil/trace.go:171","msg":"trace[721731669] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"600.086191ms","start":"2026-09-12T07:27:55.152671Z","end":"2026-09-12T07:27:55.752757Z","steps":["trace[721731669] 'process raft request' (duration: 198.207741ms)","trace[721731669] 'compare' (duration: 401.557333ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:55.752901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:55.152637Z","time spent":"600.196394ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:27:55.753118Z","caller":"traceutil/trace.go:171","msg":"trace[125231314] linearizableReadLoop","detail":"{readStateIndex:27410; appliedIndex:27409; }","duration":"515.135161ms","start":"2026-09-12T07:27:55.237972Z","end":"2026-09-12T07:27:55.753107Z","steps":["trace[125231314] 'read index received' (duration: 112.852983ms)","trace[125231314] 'applied index is now lower than readState.Index' (duration: 402.280408ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:55.75324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.265535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-12T07:27:55.753267Z","caller":"traceutil/trace.go:171","msg":"trace[970402071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26528; }","duration":"515.320346ms","start":"2026-09-12T07:27:55.237938Z","end":"2026-09-12T07:27:55.753258Z","steps":["trace[970402071] 'agreement among raft nodes before linearized reading' (duration: 515.211563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:55.753284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:55.237922Z","time spent":"515.357496ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-12T07:27:55.753286Z","caller":"traceutil/trace.go:171","msg":"trace[1730325249] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"225.083624ms","start":"2026-09-12T07:27:55.528185Z","end":"2026-09-12T07:27:55.753269Z","steps":["trace[1730325249] 'process raft request' (duration: 224.543292ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:55.871234Z","caller":"traceutil/trace.go:171","msg":"trace[705485891] transaction","detail":"{read_only:false; response_revision:26532; number_of_response:1; }","duration":"114.313495ms","start":"2026-09-12T07:27:55.756898Z","end":"2026-09-12T07:27:55.871212Z","steps":["trace[705485891] 'process raft request' (duration: 114.272284ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:55.871485Z","caller":"traceutil/trace.go:171","msg":"trace[1547020937] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"202.154725ms","start":"2026-09-12T07:27:55.669316Z","end":"2026-09-12T07:27:55.871471Z","steps":["trace[1547020937] 'process raft request' (duration: 183.308484ms)","trace[1547020937] 'compare' (duration: 18.360561ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:55.871632Z","caller":"traceutil/trace.go:171","msg":"trace[1982433393] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"167.846125ms","start":"2026-09-12T07:27:55.703778Z","end":"2026-09-12T07:27:55.871624Z","steps":["trace[1982433393] 'process raft request' (duration: 167.294194ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:55.87172Z","caller":"traceutil/trace.go:171","msg":"trace[2056111829] linearizableReadLoop","detail":"{readStateIndex:27413; appliedIndex:27411; }","duration":"118.556775ms","start":"2026-09-12T07:27:55.753132Z","end":"2026-09-12T07:27:55.871689Z","steps":["trace[2056111829] 'read index received' (duration: 99.57938ms)","trace[2056111829] 'applied index is now lower than readState.Index' (duration: 18.973975ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:55.871905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.318288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T07:27:55.871944Z","caller":"traceutil/trace.go:171","msg":"trace[781495290] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26532; }","duration":"564.409681ms","start":"2026-09-12T07:27:55.307523Z","end":"2026-09-12T07:27:55.871933Z","steps":["trace[781495290] 'agreement among raft nodes before linearized reading' (duration: 564.263978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:55.871984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:55.307509Z","time spent":"564.462572ms","remote":"127.0.0.1:53220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-12T07:27:56.183229Z","caller":"traceutil/trace.go:171","msg":"trace[720095448] linearizableReadLoop","detail":"{readStateIndex:27415; appliedIndex:27414; }","duration":"159.955336ms","start":"2026-09-12T07:27:56.023251Z","end":"2026-09-12T07:27:56.183206Z","steps":["trace[720095448] 'read index received' (duration: 148.880951ms)","trace[720095448] 'applied index is now lower than readState.Index' (duration: 11.073465ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:56.183464Z","caller":"traceutil/trace.go:171","msg":"trace[916932191] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"188.813462ms","start":"2026-09-12T07:27:55.994635Z","end":"2026-09-12T07:27:56.183449Z","steps":["trace[916932191] 'process raft request' (duration: 177.47101ms)","trace[916932191] 'compare' (duration: 10.990064ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:56.183745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.475788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:56.183807Z","caller":"traceutil/trace.go:171","msg":"trace[293040402] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26533; }","duration":"160.589761ms","start":"2026-09-12T07:27:56.023206Z","end":"2026-09-12T07:27:56.183796Z","steps":["trace[293040402] 'agreement among raft nodes before linearized reading' (duration: 160.447838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:56.18393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.836694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T07:27:56.183973Z","caller":"traceutil/trace.go:171","msg":"trace[888142079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26533; }","duration":"136.904106ms","start":"2026-09-12T07:27:56.047055Z","end":"2026-09-12T07:27:56.183959Z","steps":["trace[888142079] 'agreement among raft nodes before linearized reading' (duration: 136.771503ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:56.504802Z","caller":"traceutil/trace.go:171","msg":"trace[137385892] linearizableReadLoop","detail":"{readStateIndex:27416; appliedIndex:27415; }","duration":"313.771563ms","start":"2026-09-12T07:27:56.191001Z","end":"2026-09-12T07:27:56.504773Z","steps":["trace[137385892] 'read index received' (duration: 301.559523ms)","trace[137385892] 'applied index is now lower than readState.Index' (duration: 12.21063ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:56.505067Z","caller":"traceutil/trace.go:171","msg":"trace[1349465190] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"317.052342ms","start":"2026-09-12T07:27:56.187995Z","end":"2026-09-12T07:27:56.505047Z","steps":["trace[1349465190] 'process raft request' (duration: 304.581877ms)","trace[1349465190] 'compare' (duration: 11.985355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:56.50517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.18797Z","time spent":"317.131834ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:27:56.505441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.444947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-12T07:27:56.505473Z","caller":"traceutil/trace.go:171","msg":"trace[1689242341] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26534; }","duration":"314.515218ms","start":"2026-09-12T07:27:56.190948Z","end":"2026-09-12T07:27:56.505463Z","steps":["trace[1689242341] 'agreement among raft nodes before linearized reading' (duration: 314.390405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:56.505499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.19092Z","time spent":"314.5734ms","remote":"127.0.0.1:56790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-12T07:27:56.517036Z","caller":"traceutil/trace.go:171","msg":"trace[1891369063] transaction","detail":"{read_only:false; response_revision:26535; number_of_response:1; }","duration":"211.560437ms","start":"2026-09-12T07:27:56.305452Z","end":"2026-09-12T07:27:56.517012Z","steps":["trace[1891369063] 'process raft request' (duration: 211.321181ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:56.517202Z","caller":"traceutil/trace.go:171","msg":"trace[939666475] transaction","detail":"{read_only:false; response_revision:26536; number_of_response:1; }","duration":"165.568337ms","start":"2026-09-12T07:27:56.351628Z","end":"2026-09-12T07:27:56.517196Z","steps":["trace[939666475] 'process raft request' (duration: 165.291781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:56.517283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.206756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:56.517382Z","caller":"traceutil/trace.go:171","msg":"trace[1719006721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26536; }","duration":"247.347869ms","start":"2026-09-12T07:27:56.27001Z","end":"2026-09-12T07:27:56.517358Z","steps":["trace[1719006721] 'agreement among raft nodes before linearized reading' (duration: 247.187366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:56.517596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.45147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T07:27:56.517653Z","caller":"traceutil/trace.go:171","msg":"trace[1401577732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26536; }","duration":"162.552262ms","start":"2026-09-12T07:27:56.355087Z","end":"2026-09-12T07:27:56.517639Z","steps":["trace[1401577732] 'agreement among raft nodes before linearized reading' (duration: 162.43423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:56.760967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.11194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:56.761076Z","caller":"traceutil/trace.go:171","msg":"trace[1654394512] linearizableReadLoop","detail":"{readStateIndex:27419; appliedIndex:27418; }","duration":"234.992856ms","start":"2026-09-12T07:27:56.526063Z","end":"2026-09-12T07:27:56.761056Z","steps":["trace[1654394512] 'read index received' (duration: 120.556668ms)","trace[1654394512] 'applied index is now lower than readState.Index' (duration: 114.434737ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:56.76117Z","caller":"traceutil/trace.go:171","msg":"trace[2073426990] transaction","detail":"{read_only:false; response_revision:26537; number_of_response:1; }","duration":"240.314919ms","start":"2026-09-12T07:27:56.52084Z","end":"2026-09-12T07:27:56.761155Z","steps":["trace[2073426990] 'process raft request' (duration: 125.766269ms)","trace[2073426990] 'compare' (duration: 113.849294ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:56.761335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.258311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-12T07:27:56.761411Z","caller":"traceutil/trace.go:171","msg":"trace[682022867] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26537; }","duration":"235.344822ms","start":"2026-09-12T07:27:56.526042Z","end":"2026-09-12T07:27:56.761386Z","steps":["trace[682022867] 'agreement among raft nodes before linearized reading' (duration: 235.153188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.015361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.565646ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:57.015485Z","caller":"traceutil/trace.go:171","msg":"trace[1525359739] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26537; }","duration":"126.70564ms","start":"2026-09-12T07:27:56.888761Z","end":"2026-09-12T07:27:57.015467Z","steps":["trace[1525359739] 'range keys from in-memory index tree' (duration: 126.550585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.015566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.792064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:57.01576Z","caller":"traceutil/trace.go:171","msg":"trace[2111608547] linearizableReadLoop","detail":"{readStateIndex:27421; appliedIndex:27419; }","duration":"254.628044ms","start":"2026-09-12T07:27:56.76112Z","end":"2026-09-12T07:27:57.015748Z","steps":["trace[2111608547] 'read index received' (duration: 133.596885ms)","trace[2111608547] 'applied index is now lower than readState.Index' (duration: 121.030398ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:57.015857Z","caller":"traceutil/trace.go:171","msg":"trace[614912130] transaction","detail":"{read_only:false; response_revision:26538; number_of_response:1; }","duration":"412.730922ms","start":"2026-09-12T07:27:56.603114Z","end":"2026-09-12T07:27:57.015845Z","steps":["trace[614912130] 'process raft request' (duration: 291.58854ms)","trace[614912130] 'compare' (duration: 120.690251ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:57.015913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.603088Z","time spent":"412.790263ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:27:57.016115Z","caller":"traceutil/trace.go:171","msg":"trace[28388822] transaction","detail":"{read_only:false; response_revision:26539; number_of_response:1; }","duration":"386.903141ms","start":"2026-09-12T07:27:56.629202Z","end":"2026-09-12T07:27:57.016105Z","steps":["trace[28388822] 'process raft request' (duration: 386.422631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.016187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.629179Z","time spent":"386.974162ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:27:57.016424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.446366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.147\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-12T07:27:57.016459Z","caller":"traceutil/trace.go:171","msg":"trace[1323599727] range","detail":"{range_begin:/registry/masterleases/162.253.55.147; range_end:; response_count:1; response_revision:26540; }","duration":"266.514696ms","start":"2026-09-12T07:27:56.749935Z","end":"2026-09-12T07:27:57.01645Z","steps":["trace[1323599727] 'agreement among raft nodes before linearized reading' (duration: 266.371153ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:57.016801Z","caller":"traceutil/trace.go:171","msg":"trace[245664026] transaction","detail":"{read_only:false; response_revision:26540; number_of_response:1; }","duration":"199.369636ms","start":"2026-09-12T07:27:56.817411Z","end":"2026-09-12T07:27:57.01678Z","steps":["trace[245664026] 'process raft request' (duration: 198.303674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.016963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.594975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:57.016995Z","caller":"traceutil/trace.go:171","msg":"trace[1367442037] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:26540; }","duration":"448.657315ms","start":"2026-09-12T07:27:56.568325Z","end":"2026-09-12T07:27:57.016982Z","steps":["trace[1367442037] 'agreement among raft nodes before linearized reading' (duration: 447.46236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.01696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.526634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-12T07:27:57.017022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.56831Z","time spent":"448.703757ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"info","ts":"2026-09-12T07:27:57.017058Z","caller":"traceutil/trace.go:171","msg":"trace[567330477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26540; }","duration":"433.661296ms","start":"2026-09-12T07:27:56.583369Z","end":"2026-09-12T07:27:57.01703Z","steps":["trace[567330477] 'agreement among raft nodes before linearized reading' (duration: 433.311219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.017077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.288911ms","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-09-12T07:27:57.017117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.583354Z","time spent":"433.749369ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-12T07:27:57.017146Z","caller":"traceutil/trace.go:171","msg":"trace[2043290781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26540; }","duration":"404.376112ms","start":"2026-09-12T07:27:56.612753Z","end":"2026-09-12T07:27:57.017129Z","steps":["trace[2043290781] 'agreement among raft nodes before linearized reading' (duration: 404.134167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.017193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:56.612637Z","time spent":"404.546977ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-12T07:27:57.203052Z","caller":"traceutil/trace.go:171","msg":"trace[2137551196] transaction","detail":"{read_only:false; response_revision:26541; number_of_response:1; }","duration":"176.099909ms","start":"2026-09-12T07:27:57.026928Z","end":"2026-09-12T07:27:57.203028Z","steps":["trace[2137551196] 'process raft request' (duration: 162.577062ms)","trace[2137551196] 'compare' (duration: 13.139519ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:57.203546Z","caller":"traceutil/trace.go:171","msg":"trace[636772107] transaction","detail":"{read_only:false; response_revision:26542; number_of_response:1; }","duration":"170.665065ms","start":"2026-09-12T07:27:57.032846Z","end":"2026-09-12T07:27:57.203511Z","steps":["trace[636772107] 'process raft request' (duration: 170.139483ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:57.203592Z","caller":"traceutil/trace.go:171","msg":"trace[2121471681] transaction","detail":"{read_only:false; response_revision:26543; number_of_response:1; }","duration":"148.35047ms","start":"2026-09-12T07:27:57.05522Z","end":"2026-09-12T07:27:57.203571Z","steps":["trace[2121471681] 'process raft request' (duration: 148.153536ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:57.555793Z","caller":"traceutil/trace.go:171","msg":"trace[213308985] linearizableReadLoop","detail":"{readStateIndex:27428; appliedIndex:27427; }","duration":"305.348933ms","start":"2026-09-12T07:27:57.25042Z","end":"2026-09-12T07:27:57.555769Z","steps":["trace[213308985] 'read index received' (duration: 240.368109ms)","trace[213308985] 'applied index is now lower than readState.Index' (duration: 64.979804ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:57.555944Z","caller":"traceutil/trace.go:171","msg":"trace[289612395] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"310.80583ms","start":"2026-09-12T07:27:57.245103Z","end":"2026-09-12T07:27:57.555909Z","steps":["trace[289612395] 'process raft request' (duration: 245.653323ms)","trace[289612395] 'compare' (duration: 64.8211ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:57.55605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:57.245061Z","time spent":"310.925062ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:27:57.556158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.796876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:57.556196Z","caller":"traceutil/trace.go:171","msg":"trace[1387109303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26545; }","duration":"284.859027ms","start":"2026-09-12T07:27:57.271329Z","end":"2026-09-12T07:27:57.556188Z","steps":["trace[1387109303] 'agreement among raft nodes before linearized reading' (duration: 284.789876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.556251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.800574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:57.556323Z","caller":"traceutil/trace.go:171","msg":"trace[709731377] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26545; }","duration":"305.917196ms","start":"2026-09-12T07:27:57.25038Z","end":"2026-09-12T07:27:57.556298Z","steps":["trace[709731377] 'agreement among raft nodes before linearized reading' (duration: 305.784634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:57.556357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:27:57.250362Z","time spent":"305.987768ms","remote":"127.0.0.1:44314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-09-12T07:27:57.842767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.401916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:57.842917Z","caller":"traceutil/trace.go:171","msg":"trace[101521115] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"177.47637ms","start":"2026-09-12T07:27:57.665413Z","end":"2026-09-12T07:27:57.842889Z","steps":["trace[101521115] 'process raft request' (duration: 39.844729ms)","trace[101521115] 'compare' (duration: 137.223823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:58.150154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.227977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:58.151069Z","caller":"traceutil/trace.go:171","msg":"trace[1394581913] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"273.947045ms","start":"2026-09-12T07:27:57.877099Z","end":"2026-09-12T07:27:58.151046Z","steps":["trace[1394581913] 'process raft request' (duration: 124.693126ms)","trace[1394581913] 'compare' (duration: 147.735746ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:58.162524Z","caller":"traceutil/trace.go:171","msg":"trace[1582762599] linearizableReadLoop","detail":"{readStateIndex:27433; appliedIndex:27431; }","duration":"253.641283ms","start":"2026-09-12T07:27:57.90886Z","end":"2026-09-12T07:27:58.162502Z","steps":["trace[1582762599] 'read index received' (duration: 92.190173ms)","trace[1582762599] 'applied index is now lower than readState.Index' (duration: 161.45015ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:58.162588Z","caller":"traceutil/trace.go:171","msg":"trace[2106464557] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"283.209772ms","start":"2026-09-12T07:27:57.879358Z","end":"2026-09-12T07:27:58.162568Z","steps":["trace[2106464557] 'process raft request' (duration: 283.014168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:58.162645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.758656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T07:27:58.162767Z","caller":"traceutil/trace.go:171","msg":"trace[583915366] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26550; }","duration":"253.927869ms","start":"2026-09-12T07:27:57.908821Z","end":"2026-09-12T07:27:58.162749Z","steps":["trace[583915366] 'agreement among raft nodes before linearized reading' (duration: 253.764465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:58.387007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.290226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:58.3871Z","caller":"traceutil/trace.go:171","msg":"trace[935615063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26551; }","duration":"115.449919ms","start":"2026-09-12T07:27:58.271629Z","end":"2026-09-12T07:27:58.387079Z","steps":["trace[935615063] 'range keys from in-memory index tree' (duration: 115.202324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:58.712512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.701665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:58.71262Z","caller":"traceutil/trace.go:171","msg":"trace[1430702257] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"189.453644ms","start":"2026-09-12T07:27:58.523148Z","end":"2026-09-12T07:27:58.712602Z","steps":["trace[1430702257] 'process raft request' (duration: 72.583746ms)","trace[1430702257] 'compare' (duration: 116.522201ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:59.008809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.833612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:59.008929Z","caller":"traceutil/trace.go:171","msg":"trace[1577751343] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"241.293139ms","start":"2026-09-12T07:27:58.767616Z","end":"2026-09-12T07:27:59.00891Z","steps":["trace[1577751343] 'process raft request' (duration: 136.266282ms)","trace[1577751343] 'compare' (duration: 104.679799ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:27:59.371937Z","caller":"traceutil/trace.go:171","msg":"trace[70910337] linearizableReadLoop","detail":"{readStateIndex:27439; appliedIndex:27438; }","duration":"157.142197ms","start":"2026-09-12T07:27:59.214773Z","end":"2026-09-12T07:27:59.371915Z","steps":["trace[70910337] 'read index received' (duration: 156.978523ms)","trace[70910337] 'applied index is now lower than readState.Index' (duration: 163.134µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:27:59.372107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.081791ms","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-09-12T07:27:59.372105Z","caller":"traceutil/trace.go:171","msg":"trace[1622940761] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"161.877918ms","start":"2026-09-12T07:27:59.210188Z","end":"2026-09-12T07:27:59.372066Z","steps":["trace[1622940761] 'process raft request' (duration: 161.614882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:59.372171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.628643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:27:59.372189Z","caller":"traceutil/trace.go:171","msg":"trace[910057781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26556; }","duration":"100.689254ms","start":"2026-09-12T07:27:59.271495Z","end":"2026-09-12T07:27:59.372185Z","steps":["trace[910057781] 'agreement among raft nodes before linearized reading' (duration: 100.650824ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:59.372134Z","caller":"traceutil/trace.go:171","msg":"trace[1958911060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26556; }","duration":"154.167353ms","start":"2026-09-12T07:27:59.217956Z","end":"2026-09-12T07:27:59.372124Z","steps":["trace[1958911060] 'agreement among raft nodes before linearized reading' (duration: 154.045111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:59.372231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.450644ms","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-09-12T07:27:59.372316Z","caller":"traceutil/trace.go:171","msg":"trace[593244306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26556; }","duration":"157.580116ms","start":"2026-09-12T07:27:59.21472Z","end":"2026-09-12T07:27:59.3723Z","steps":["trace[593244306] 'agreement among raft nodes before linearized reading' (duration: 157.29302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:27:59.562505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.196606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:27:59.562699Z","caller":"traceutil/trace.go:171","msg":"trace[1162853005] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"186.538943ms","start":"2026-09-12T07:27:59.376145Z","end":"2026-09-12T07:27:59.562684Z","steps":["trace[1162853005] 'process raft request' (duration: 186.441641ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:27:59.563042Z","caller":"traceutil/trace.go:171","msg":"trace[1929709723] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"187.076854ms","start":"2026-09-12T07:27:59.375918Z","end":"2026-09-12T07:27:59.562995Z","steps":["trace[1929709723] 'process raft request' (duration: 77.300666ms)","trace[1929709723] 'compare' (duration: 108.939301ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:08.840203Z","caller":"traceutil/trace.go:171","msg":"trace[429804409] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"220.152119ms","start":"2026-09-12T07:28:08.620025Z","end":"2026-09-12T07:28:08.840177Z","steps":["trace[429804409] 'process raft request' (duration: 219.994756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:09.156864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.475862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:09.156985Z","caller":"traceutil/trace.go:171","msg":"trace[1239999641] linearizableReadLoop","detail":"{readStateIndex:27511; appliedIndex:27509; }","duration":"399.397277ms","start":"2026-09-12T07:28:08.757568Z","end":"2026-09-12T07:28:09.156966Z","steps":["trace[1239999641] 'read index received' (duration: 82.426355ms)","trace[1239999641] 'applied index is now lower than readState.Index' (duration: 316.969222ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:09.157101Z","caller":"traceutil/trace.go:171","msg":"trace[1042790640] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"405.995647ms","start":"2026-09-12T07:28:08.751094Z","end":"2026-09-12T07:28:09.157089Z","steps":["trace[1042790640] 'process raft request' (duration: 181.183508ms)","trace[1042790640] 'compare' (duration: 224.058592ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:09.157172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:08.75107Z","time spent":"406.065049ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:09.157403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.801086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T07:28:09.157486Z","caller":"traceutil/trace.go:171","msg":"trace[419865684] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:26626; }","duration":"399.926108ms","start":"2026-09-12T07:28:08.757539Z","end":"2026-09-12T07:28:09.157465Z","steps":["trace[419865684] 'agreement among raft nodes before linearized reading' (duration: 399.772694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:09.157543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:08.757524Z","time spent":"400.00853ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-09-12T07:28:09.704416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.081081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:09.704537Z","caller":"traceutil/trace.go:171","msg":"trace[410426643] linearizableReadLoop","detail":"{readStateIndex:27512; appliedIndex:27511; }","duration":"547.507971ms","start":"2026-09-12T07:28:09.157008Z","end":"2026-09-12T07:28:09.704516Z","steps":["trace[410426643] 'read index received' (duration: 386.160875ms)","trace[410426643] 'applied index is now lower than readState.Index' (duration: 161.345266ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:09.704778Z","caller":"traceutil/trace.go:171","msg":"trace[811617987] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"618.604796ms","start":"2026-09-12T07:28:09.08614Z","end":"2026-09-12T07:28:09.704745Z","steps":["trace[811617987] 'process raft request' (duration: 457.087096ms)","trace[811617987] 'compare' (duration: 160.697063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:09.704928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.086124Z","time spent":"618.719738ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:09.705226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.177434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T07:28:09.705328Z","caller":"traceutil/trace.go:171","msg":"trace[334778384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26627; }","duration":"655.328027ms","start":"2026-09-12T07:28:09.049974Z","end":"2026-09-12T07:28:09.705302Z","steps":["trace[334778384] 'agreement among raft nodes before linearized reading' (duration: 655.021801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:09.705408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.049951Z","time spent":"655.44184ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-12T07:28:09.718188Z","caller":"traceutil/trace.go:171","msg":"trace[756424649] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"313.558479ms","start":"2026-09-12T07:28:09.404615Z","end":"2026-09-12T07:28:09.718173Z","steps":["trace[756424649] 'process raft request' (duration: 313.445857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:09.718279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.40459Z","time spent":"313.64137ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:10.008144Z","caller":"traceutil/trace.go:171","msg":"trace[1414717742] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"411.081465ms","start":"2026-09-12T07:28:09.597034Z","end":"2026-09-12T07:28:10.008116Z","steps":["trace[1414717742] 'process raft request' (duration: 346.05288ms)","trace[1414717742] 'compare' (duration: 64.79755ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:10.008272Z","caller":"traceutil/trace.go:171","msg":"trace[1541907247] linearizableReadLoop","detail":"{readStateIndex:27514; appliedIndex:27512; }","duration":"303.670599ms","start":"2026-09-12T07:28:09.704574Z","end":"2026-09-12T07:28:10.008245Z","steps":["trace[1541907247] 'read index received' (duration: 13.567559ms)","trace[1541907247] 'applied index is now lower than readState.Index' (duration: 290.10136ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:10.008302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.597007Z","time spent":"411.203378ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:10.008411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.013885ms","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-09-12T07:28:10.008492Z","caller":"traceutil/trace.go:171","msg":"trace[484974208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26630; }","duration":"381.143997ms","start":"2026-09-12T07:28:09.627331Z","end":"2026-09-12T07:28:10.008475Z","steps":["trace[484974208] 'agreement among raft nodes before linearized reading' (duration: 380.930253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.0085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.798302ms","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-09-12T07:28:10.00853Z","caller":"traceutil/trace.go:171","msg":"trace[670002463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26630; }","duration":"381.896114ms","start":"2026-09-12T07:28:09.626621Z","end":"2026-09-12T07:28:10.008517Z","steps":["trace[670002463] 'agreement among raft nodes before linearized reading' (duration: 381.730141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.008529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.627309Z","time spent":"381.211549ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-12T07:28:10.008554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.626598Z","time spent":"381.950556ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-12T07:28:10.008654Z","caller":"traceutil/trace.go:171","msg":"trace[721854973] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"297.902866ms","start":"2026-09-12T07:28:09.710743Z","end":"2026-09-12T07:28:10.008646Z","steps":["trace[721854973] 'process raft request' (duration: 297.329343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.008664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.157329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:10.008754Z","caller":"traceutil/trace.go:171","msg":"trace[1555936667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26630; }","duration":"736.282632ms","start":"2026-09-12T07:28:09.272457Z","end":"2026-09-12T07:28:10.008739Z","steps":["trace[1555936667] 'agreement among raft nodes before linearized reading' (duration: 736.16962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.008802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.272436Z","time spent":"736.356553ms","remote":"127.0.0.1:56572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-12T07:28:10.008985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.685224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-12T07:28:10.00912Z","caller":"traceutil/trace.go:171","msg":"trace[952110314] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26630; }","duration":"554.834938ms","start":"2026-09-12T07:28:09.454224Z","end":"2026-09-12T07:28:10.009059Z","steps":["trace[952110314] 'agreement among raft nodes before linearized reading' (duration: 554.541551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.009365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.454201Z","time spent":"555.134813ms","remote":"127.0.0.1:56756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-09-12T07:28:10.508923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16635629157489905629,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-12T07:28:10.604547Z","caller":"traceutil/trace.go:171","msg":"trace[1081726454] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"711.265269ms","start":"2026-09-12T07:28:09.893232Z","end":"2026-09-12T07:28:10.604498Z","steps":["trace[1081726454] 'process raft request' (duration: 676.562151ms)","trace[1081726454] 'compare' (duration: 34.32037ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:10.604633Z","caller":"traceutil/trace.go:171","msg":"trace[88712972] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"678.534671ms","start":"2026-09-12T07:28:09.926065Z","end":"2026-09-12T07:28:10.6046Z","steps":["trace[88712972] 'process raft request' (duration: 678.4687ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:10.604633Z","caller":"traceutil/trace.go:171","msg":"trace[1780172702] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"709.715975ms","start":"2026-09-12T07:28:09.894884Z","end":"2026-09-12T07:28:10.6046Z","steps":["trace[1780172702] 'process raft request' (duration: 709.533282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.604857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.926043Z","time spent":"678.714996ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:10.60487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.894856Z","time spent":"709.963801ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:10.604966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.893203Z","time spent":"711.416872ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:10.616465Z","caller":"traceutil/trace.go:171","msg":"trace[267217073] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"603.131046ms","start":"2026-09-12T07:28:10.013302Z","end":"2026-09-12T07:28:10.616433Z","steps":["trace[267217073] 'process raft request' (duration: 602.898711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.616643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.013276Z","time spent":"603.27163ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:10.616765Z","caller":"traceutil/trace.go:171","msg":"trace[1057750027] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"603.130177ms","start":"2026-09-12T07:28:10.013608Z","end":"2026-09-12T07:28:10.616739Z","steps":["trace[1057750027] 'process raft request' (duration: 602.755899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.616903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.013599Z","time spent":"603.229148ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:10.61701Z","caller":"traceutil/trace.go:171","msg":"trace[1205217159] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"373.088786ms","start":"2026-09-12T07:28:10.243902Z","end":"2026-09-12T07:28:10.616991Z","steps":["trace[1205217159] 'process raft request' (duration: 372.920022ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:10.617029Z","caller":"traceutil/trace.go:171","msg":"trace[1158108491] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"423.736805ms","start":"2026-09-12T07:28:10.193273Z","end":"2026-09-12T07:28:10.61701Z","steps":["trace[1158108491] 'process raft request' (duration: 423.360207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.617111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.243873Z","time spent":"373.179498ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:10.617116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.193245Z","time spent":"423.821456ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:10.617319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.873895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:10.617406Z","caller":"traceutil/trace.go:171","msg":"trace[2000204889] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26637; }","duration":"744.981527ms","start":"2026-09-12T07:28:09.872403Z","end":"2026-09-12T07:28:10.617385Z","steps":["trace[2000204889] 'agreement among raft nodes before linearized reading' (duration: 744.826754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.617463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:09.872332Z","time spent":"745.119561ms","remote":"127.0.0.1:44108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-09-12T07:28:10.616962Z","caller":"traceutil/trace.go:171","msg":"trace[912814290] linearizableReadLoop","detail":"{readStateIndex:27522; appliedIndex:27515; }","duration":"608.625153ms","start":"2026-09-12T07:28:10.00832Z","end":"2026-09-12T07:28:10.616946Z","steps":["trace[912814290] 'read index received' (duration: 561.489879ms)","trace[912814290] 'applied index is now lower than readState.Index' (duration: 47.134504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:10.617652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.56325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:10.617731Z","caller":"traceutil/trace.go:171","msg":"trace[753877012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26637; }","duration":"607.686513ms","start":"2026-09-12T07:28:10.010029Z","end":"2026-09-12T07:28:10.617716Z","steps":["trace[753877012] 'agreement among raft nodes before linearized reading' (duration: 607.575561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.617774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.010007Z","time spent":"607.758365ms","remote":"127.0.0.1:56560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-12T07:28:10.61783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.252863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:10.617866Z","caller":"traceutil/trace.go:171","msg":"trace[1055097695] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26637; }","duration":"468.342085ms","start":"2026-09-12T07:28:10.149513Z","end":"2026-09-12T07:28:10.617855Z","steps":["trace[1055097695] 'agreement among raft nodes before linearized reading' (duration: 468.277884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.617894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.149482Z","time spent":"468.403947ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-09-12T07:28:10.618011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.788504ms","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-09-12T07:28:10.618062Z","caller":"traceutil/trace.go:171","msg":"trace[1099008778] 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:26637; }","duration":"560.842955ms","start":"2026-09-12T07:28:10.057205Z","end":"2026-09-12T07:28:10.618048Z","steps":["trace[1099008778] 'agreement among raft nodes before linearized reading' (duration: 560.755773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.618099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.057184Z","time spent":"560.903937ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-12T07:28:10.618153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.076728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-12T07:28:10.618258Z","caller":"traceutil/trace.go:171","msg":"trace[219385738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26637; }","duration":"390.214471ms","start":"2026-09-12T07:28:10.228031Z","end":"2026-09-12T07:28:10.618245Z","steps":["trace[219385738] 'agreement among raft nodes before linearized reading' (duration: 390.022307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.61829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.228013Z","time spent":"390.269292ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-12T07:28:10.6183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.791465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:10.61834Z","caller":"traceutil/trace.go:171","msg":"trace[1025111285] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26637; }","duration":"600.868607ms","start":"2026-09-12T07:28:10.017458Z","end":"2026-09-12T07:28:10.618327Z","steps":["trace[1025111285] 'agreement among raft nodes before linearized reading' (duration: 600.803986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.618386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.017441Z","time spent":"600.931889ms","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-12T07:28:10.618668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.506407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ad449cf4-ae7f-4853-91e6-80b0a5aa8a06\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-12T07:28:10.618754Z","caller":"traceutil/trace.go:171","msg":"trace[2058007340] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ad449cf4-ae7f-4853-91e6-80b0a5aa8a06; range_end:; response_count:1; response_revision:26637; }","duration":"605.65397ms","start":"2026-09-12T07:28:10.013087Z","end":"2026-09-12T07:28:10.618741Z","steps":["trace[2058007340] 'agreement among raft nodes before linearized reading' (duration: 605.459695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:10.618789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.013065Z","time spent":"605.71398ms","remote":"127.0.0.1:56744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1342,"request content":"key:\"/registry/persistentvolumes/pvc-ad449cf4-ae7f-4853-91e6-80b0a5aa8a06\" "} {"level":"info","ts":"2026-09-12T07:28:11.239073Z","caller":"traceutil/trace.go:171","msg":"trace[706372489] linearizableReadLoop","detail":"{readStateIndex:27523; appliedIndex:27522; }","duration":"394.295778ms","start":"2026-09-12T07:28:10.844749Z","end":"2026-09-12T07:28:11.239045Z","steps":["trace[706372489] 'read index received' (duration: 394.129034ms)","trace[706372489] 'applied index is now lower than readState.Index' (duration: 165.394µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:11.239179Z","caller":"traceutil/trace.go:171","msg":"trace[251205717] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"611.920184ms","start":"2026-09-12T07:28:10.627232Z","end":"2026-09-12T07:28:11.239152Z","steps":["trace[251205717] 'process raft request' (duration: 611.647468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:11.239331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.627206Z","time spent":"612.036266ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:11.239349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.634625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T07:28:11.239419Z","caller":"traceutil/trace.go:171","msg":"trace[372459800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26638; }","duration":"394.761718ms","start":"2026-09-12T07:28:10.84464Z","end":"2026-09-12T07:28:11.239401Z","steps":["trace[372459800] 'agreement among raft nodes before linearized reading' (duration: 394.540133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:11.239458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.844615Z","time spent":"394.834649ms","remote":"127.0.0.1:56864","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-09-12T07:28:11.315308Z","caller":"traceutil/trace.go:171","msg":"trace[176429442] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"427.244489ms","start":"2026-09-12T07:28:10.888035Z","end":"2026-09-12T07:28:11.315279Z","steps":["trace[176429442] 'process raft request' (duration: 418.945602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:11.315456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.888Z","time spent":"427.374012ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:11.315484Z","caller":"traceutil/trace.go:171","msg":"trace[627642640] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"153.582472ms","start":"2026-09-12T07:28:11.161863Z","end":"2026-09-12T07:28:11.315446Z","steps":["trace[627642640] 'process raft request' (duration: 153.296806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:11.315495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.433027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:11.315589Z","caller":"traceutil/trace.go:171","msg":"trace[1179987384] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26641; }","duration":"468.57424ms","start":"2026-09-12T07:28:10.846987Z","end":"2026-09-12T07:28:11.315561Z","steps":["trace[1179987384] 'agreement among raft nodes before linearized reading' (duration: 468.404037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:11.315712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.142715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T07:28:11.31587Z","caller":"traceutil/trace.go:171","msg":"trace[1835695458] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26641; }","duration":"349.36752ms","start":"2026-09-12T07:28:10.966483Z","end":"2026-09-12T07:28:11.31585Z","steps":["trace[1835695458] 'agreement among raft nodes before linearized reading' (duration: 349.035533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:11.315931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.966465Z","time spent":"349.442832ms","remote":"127.0.0.1:56926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":127,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-09-12T07:28:11.315915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:10.846964Z","time spent":"468.663081ms","remote":"127.0.0.1:45058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-09-12T07:28:17.144651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.274711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:17.14483Z","caller":"traceutil/trace.go:171","msg":"trace[1113032357] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"178.517112ms","start":"2026-09-12T07:28:16.966289Z","end":"2026-09-12T07:28:17.144806Z","steps":["trace[1113032357] 'process raft request' (duration: 74.000106ms)","trace[1113032357] 'compare' (duration: 103.682029ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:17.40143Z","caller":"traceutil/trace.go:171","msg":"trace[368248187] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"101.966093ms","start":"2026-09-12T07:28:17.299441Z","end":"2026-09-12T07:28:17.401407Z","steps":["trace[368248187] 'process raft request' (duration: 101.84896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:17.761888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.494812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:17.76201Z","caller":"traceutil/trace.go:171","msg":"trace[718075491] linearizableReadLoop","detail":"{readStateIndex:27631; appliedIndex:27630; }","duration":"417.252667ms","start":"2026-09-12T07:28:17.344737Z","end":"2026-09-12T07:28:17.76199Z","steps":["trace[718075491] 'read index received' (duration: 56.671068ms)","trace[718075491] 'applied index is now lower than readState.Index' (duration: 360.580019ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:17.762093Z","caller":"traceutil/trace.go:171","msg":"trace[729807883] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"427.210629ms","start":"2026-09-12T07:28:17.334868Z","end":"2026-09-12T07:28:17.762079Z","steps":["trace[729807883] 'process raft request' (duration: 171.425041ms)","trace[729807883] 'compare' (duration: 255.162555ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:17.762162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:17.334848Z","time spent":"427.27628ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:17.762289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.525313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T07:28:17.762375Z","caller":"traceutil/trace.go:171","msg":"trace[62546195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26743; }","duration":"417.670456ms","start":"2026-09-12T07:28:17.344682Z","end":"2026-09-12T07:28:17.762352Z","steps":["trace[62546195] 'agreement among raft nodes before linearized reading' (duration: 417.447071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:17.762427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:17.344672Z","time spent":"417.739859ms","remote":"127.0.0.1:56864","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":"warn","ts":"2026-09-12T07:28:17.763878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.950649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:17.763943Z","caller":"traceutil/trace.go:171","msg":"trace[1910902262] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26745; }","duration":"146.063631ms","start":"2026-09-12T07:28:17.617862Z","end":"2026-09-12T07:28:17.763925Z","steps":["trace[1910902262] 'agreement among raft nodes before linearized reading' (duration: 145.914938ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:18.001968Z","caller":"traceutil/trace.go:171","msg":"trace[1618911201] linearizableReadLoop","detail":"{readStateIndex:27635; appliedIndex:27634; }","duration":"179.054324ms","start":"2026-09-12T07:28:17.82288Z","end":"2026-09-12T07:28:18.001935Z","steps":["trace[1618911201] 'read index received' (duration: 178.976462ms)","trace[1618911201] 'applied index is now lower than readState.Index' (duration: 76.492µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:18.002189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.175329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T07:28:18.002192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.016259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f33898b1-3638-4f55-9946-7e3092c2dd1e\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-09-12T07:28:18.00225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.875355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-12T07:28:18.00225Z","caller":"traceutil/trace.go:171","msg":"trace[1989080859] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26747; }","duration":"174.283282ms","start":"2026-09-12T07:28:17.827947Z","end":"2026-09-12T07:28:18.002231Z","steps":["trace[1989080859] 'agreement among raft nodes before linearized reading' (duration: 174.17421ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:18.002301Z","caller":"traceutil/trace.go:171","msg":"trace[210700039] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26747; }","duration":"213.959206ms","start":"2026-09-12T07:28:17.788327Z","end":"2026-09-12T07:28:18.002287Z","steps":["trace[210700039] 'agreement among raft nodes before linearized reading' (duration: 213.779712ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:18.00229Z","caller":"traceutil/trace.go:171","msg":"trace[2104317316] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f33898b1-3638-4f55-9946-7e3092c2dd1e; range_end:; response_count:1; response_revision:26747; }","duration":"200.171233ms","start":"2026-09-12T07:28:17.802093Z","end":"2026-09-12T07:28:18.002265Z","steps":["trace[2104317316] 'agreement among raft nodes before linearized reading' (duration: 199.937038ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:18.002797Z","caller":"traceutil/trace.go:171","msg":"trace[1049424278] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"229.13238ms","start":"2026-09-12T07:28:17.773444Z","end":"2026-09-12T07:28:18.002577Z","steps":["trace[1049424278] 'process raft request' (duration: 228.319623ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:20.148042Z","caller":"traceutil/trace.go:171","msg":"trace[631083128] linearizableReadLoop","detail":"{readStateIndex:27654; appliedIndex:27653; }","duration":"102.657337ms","start":"2026-09-12T07:28:20.045357Z","end":"2026-09-12T07:28:20.148014Z","steps":["trace[631083128] 'read index received' (duration: 102.366201ms)","trace[631083128] 'applied index is now lower than readState.Index' (duration: 289.746µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:20.148172Z","caller":"traceutil/trace.go:171","msg":"trace[603575924] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"107.524591ms","start":"2026-09-12T07:28:20.040616Z","end":"2026-09-12T07:28:20.148141Z","steps":["trace[603575924] 'process raft request' (duration: 107.204134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:20.148283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.910242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T07:28:20.148327Z","caller":"traceutil/trace.go:171","msg":"trace[1412390660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26766; }","duration":"103.001524ms","start":"2026-09-12T07:28:20.045314Z","end":"2026-09-12T07:28:20.148316Z","steps":["trace[1412390660] 'agreement among raft nodes before linearized reading' (duration: 102.842781ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:24.405511Z","caller":"traceutil/trace.go:171","msg":"trace[768939080] linearizableReadLoop","detail":"{readStateIndex:27706; appliedIndex:27705; }","duration":"177.067451ms","start":"2026-09-12T07:28:24.228427Z","end":"2026-09-12T07:28:24.405494Z","steps":["trace[768939080] 'read index received' (duration: 176.893827ms)","trace[768939080] 'applied index is now lower than readState.Index' (duration: 172.984µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:24.405585Z","caller":"traceutil/trace.go:171","msg":"trace[819960242] transaction","detail":"{read_only:false; response_revision:26816; number_of_response:1; }","duration":"243.927275ms","start":"2026-09-12T07:28:24.161625Z","end":"2026-09-12T07:28:24.405552Z","steps":["trace[819960242] 'process raft request' (duration: 243.736991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:24.405625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.33657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:24.405648Z","caller":"traceutil/trace.go:171","msg":"trace[1296367345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26816; }","duration":"133.418652ms","start":"2026-09-12T07:28:24.272222Z","end":"2026-09-12T07:28:24.405641Z","steps":["trace[1296367345] 'agreement among raft nodes before linearized reading' (duration: 133.362221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:24.405805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.360197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T07:28:24.405897Z","caller":"traceutil/trace.go:171","msg":"trace[1732308225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26816; }","duration":"177.48926ms","start":"2026-09-12T07:28:24.228385Z","end":"2026-09-12T07:28:24.405874Z","steps":["trace[1732308225] 'agreement among raft nodes before linearized reading' (duration: 177.203244ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:26.5579Z","caller":"traceutil/trace.go:171","msg":"trace[1754936876] linearizableReadLoop","detail":"{readStateIndex:27725; appliedIndex:27724; }","duration":"122.742994ms","start":"2026-09-12T07:28:26.435136Z","end":"2026-09-12T07:28:26.557879Z","steps":["trace[1754936876] 'read index received' (duration: 118.278699ms)","trace[1754936876] 'applied index is now lower than readState.Index' (duration: 4.463115ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:26.55792Z","caller":"traceutil/trace.go:171","msg":"trace[580310719] transaction","detail":"{read_only:false; response_revision:26835; number_of_response:1; }","duration":"146.878828ms","start":"2026-09-12T07:28:26.411015Z","end":"2026-09-12T07:28:26.557894Z","steps":["trace[580310719] 'process raft request' (duration: 142.512106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:26.558132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.63181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T07:28:26.558171Z","caller":"traceutil/trace.go:171","msg":"trace[185794665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26835; }","duration":"121.700572ms","start":"2026-09-12T07:28:26.43646Z","end":"2026-09-12T07:28:26.558161Z","steps":["trace[185794665] 'agreement among raft nodes before linearized reading' (duration: 121.557999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:26.558183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.035711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-09-12T07:28:26.558279Z","caller":"traceutil/trace.go:171","msg":"trace[140617976] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26835; }","duration":"123.619163ms","start":"2026-09-12T07:28:26.434632Z","end":"2026-09-12T07:28:26.558251Z","steps":["trace[140617976] 'agreement among raft nodes before linearized reading' (duration: 123.361607ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:26.684396Z","caller":"traceutil/trace.go:171","msg":"trace[283096444] linearizableReadLoop","detail":"{readStateIndex:27726; appliedIndex:27725; }","duration":"105.769202ms","start":"2026-09-12T07:28:26.578599Z","end":"2026-09-12T07:28:26.684369Z","steps":["trace[283096444] 'read index received' (duration: 104.890784ms)","trace[283096444] 'applied index is now lower than readState.Index' (duration: 876.918µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:26.684505Z","caller":"traceutil/trace.go:171","msg":"trace[1104622039] transaction","detail":"{read_only:false; response_revision:26836; number_of_response:1; }","duration":"121.982297ms","start":"2026-09-12T07:28:26.562498Z","end":"2026-09-12T07:28:26.68448Z","steps":["trace[1104622039] 'process raft request' (duration: 120.974866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:26.684585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.968137ms","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-09-12T07:28:26.684687Z","caller":"traceutil/trace.go:171","msg":"trace[781110266] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26836; }","duration":"106.101409ms","start":"2026-09-12T07:28:26.578544Z","end":"2026-09-12T07:28:26.684645Z","steps":["trace[781110266] 'agreement among raft nodes before linearized reading' (duration: 105.967796ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:48.785775Z","caller":"traceutil/trace.go:171","msg":"trace[1274157853] transaction","detail":"{read_only:false; response_revision:27013; number_of_response:1; }","duration":"140.3921ms","start":"2026-09-12T07:28:48.645342Z","end":"2026-09-12T07:28:48.785734Z","steps":["trace[1274157853] 'process raft request' (duration: 140.252218ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:48.983546Z","caller":"traceutil/trace.go:171","msg":"trace[1945610882] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"229.679931ms","start":"2026-09-12T07:28:48.753839Z","end":"2026-09-12T07:28:48.983519Z","steps":["trace[1945610882] 'process raft request' (duration: 160.07036ms)","trace[1945610882] 'compare' (duration: 69.212093ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:48.983942Z","caller":"traceutil/trace.go:171","msg":"trace[328042221] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"133.376301ms","start":"2026-09-12T07:28:48.850545Z","end":"2026-09-12T07:28:48.983921Z","steps":["trace[328042221] 'process raft request' (duration: 133.34594ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:48.984006Z","caller":"traceutil/trace.go:171","msg":"trace[398240837] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"225.863011ms","start":"2026-09-12T07:28:48.758119Z","end":"2026-09-12T07:28:48.983982Z","steps":["trace[398240837] 'process raft request' (duration: 225.294738ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:48.984069Z","caller":"traceutil/trace.go:171","msg":"trace[2121652388] linearizableReadLoop","detail":"{readStateIndex:27913; appliedIndex:27908; }","duration":"206.914227ms","start":"2026-09-12T07:28:48.777147Z","end":"2026-09-12T07:28:48.984061Z","steps":["trace[2121652388] 'read index received' (duration: 8.493551ms)","trace[2121652388] 'applied index is now lower than readState.Index' (duration: 198.420016ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:48.984099Z","caller":"traceutil/trace.go:171","msg":"trace[35251795] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"212.67918ms","start":"2026-09-12T07:28:48.771383Z","end":"2026-09-12T07:28:48.984062Z","steps":["trace[35251795] 'process raft request' (duration: 212.474306ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:48.984135Z","caller":"traceutil/trace.go:171","msg":"trace[8074597] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"218.930703ms","start":"2026-09-12T07:28:48.765175Z","end":"2026-09-12T07:28:48.984106Z","steps":["trace[8074597] 'process raft request' (duration: 218.630986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:48.984202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.041599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-12T07:28:48.984223Z","caller":"traceutil/trace.go:171","msg":"trace[111766888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27018; }","duration":"207.111261ms","start":"2026-09-12T07:28:48.777105Z","end":"2026-09-12T07:28:48.984216Z","steps":["trace[111766888] 'agreement among raft nodes before linearized reading' (duration: 207.010319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:48.984365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.386908ms","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-09-12T07:28:48.984419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.445208ms","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-09-12T07:28:48.984469Z","caller":"traceutil/trace.go:171","msg":"trace[1497599306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27018; }","duration":"193.532652ms","start":"2026-09-12T07:28:48.790911Z","end":"2026-09-12T07:28:48.984444Z","steps":["trace[1497599306] 'agreement among raft nodes before linearized reading' (duration: 193.261966ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:48.984509Z","caller":"traceutil/trace.go:171","msg":"trace[240510657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27018; }","duration":"175.56722ms","start":"2026-09-12T07:28:48.808918Z","end":"2026-09-12T07:28:48.984485Z","steps":["trace[240510657] 'agreement among raft nodes before linearized reading' (duration: 175.262783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:49.305318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.987968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:49.305429Z","caller":"traceutil/trace.go:171","msg":"trace[949602013] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"316.692185ms","start":"2026-09-12T07:28:48.988715Z","end":"2026-09-12T07:28:49.305408Z","steps":["trace[949602013] 'process raft request' (duration: 140.515093ms)","trace[949602013] 'compare' (duration: 175.818535ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:49.305489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:48.988674Z","time spent":"316.785407ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:49.335353Z","caller":"traceutil/trace.go:171","msg":"trace[1743465679] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"346.816217ms","start":"2026-09-12T07:28:48.988499Z","end":"2026-09-12T07:28:49.335316Z","steps":["trace[1743465679] 'process raft request' (duration: 346.535241ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:49.33538Z","caller":"traceutil/trace.go:171","msg":"trace[145485450] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"345.643091ms","start":"2026-09-12T07:28:48.989706Z","end":"2026-09-12T07:28:49.335349Z","steps":["trace[145485450] 'process raft request' (duration: 345.512969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:49.335533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:48.988444Z","time spent":"346.99642ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:49.335542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:48.989687Z","time spent":"345.770024ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:49.94058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.494782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-12T07:28:49.940642Z","caller":"traceutil/trace.go:171","msg":"trace[1979615431] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:27022; }","duration":"186.607214ms","start":"2026-09-12T07:28:49.75402Z","end":"2026-09-12T07:28:49.940627Z","steps":["trace[1979615431] 'count revisions from in-memory index tree' (duration: 186.355458ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:50.154735Z","caller":"traceutil/trace.go:171","msg":"trace[279160919] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"264.908812ms","start":"2026-09-12T07:28:49.889807Z","end":"2026-09-12T07:28:50.154716Z","steps":["trace[279160919] 'process raft request' (duration: 264.883132ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:50.154779Z","caller":"traceutil/trace.go:171","msg":"trace[200205271] linearizableReadLoop","detail":"{readStateIndex:27921; appliedIndex:27921; }","duration":"102.610496ms","start":"2026-09-12T07:28:50.052121Z","end":"2026-09-12T07:28:50.154731Z","steps":["trace[200205271] 'read index received' (duration: 102.600865ms)","trace[200205271] 'applied index is now lower than readState.Index' (duration: 7.361µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:50.155026Z","caller":"traceutil/trace.go:171","msg":"trace[236385914] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"271.091464ms","start":"2026-09-12T07:28:49.883918Z","end":"2026-09-12T07:28:50.15501Z","steps":["trace[236385914] 'process raft request' (duration: 270.678675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:50.155197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.041715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:50.155261Z","caller":"traceutil/trace.go:171","msg":"trace[801288359] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27025; }","duration":"103.154487ms","start":"2026-09-12T07:28:50.052089Z","end":"2026-09-12T07:28:50.155243Z","steps":["trace[801288359] 'agreement among raft nodes before linearized reading' (duration: 103.002274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:52.465441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.228224ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-12T07:28:52.466483Z","caller":"traceutil/trace.go:171","msg":"trace[383320571] transaction","detail":"{read_only:false; response_revision:27038; number_of_response:1; }","duration":"306.051238ms","start":"2026-09-12T07:28:52.1604Z","end":"2026-09-12T07:28:52.466451Z","steps":["trace[383320571] 'process raft request' (duration: 305.136048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:52.466636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:52.16038Z","time spent":"306.182632ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:52.593003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.463623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:52.593208Z","caller":"traceutil/trace.go:171","msg":"trace[1259312402] linearizableReadLoop","detail":"{readStateIndex:27937; appliedIndex:27933; }","duration":"357.272499ms","start":"2026-09-12T07:28:52.235911Z","end":"2026-09-12T07:28:52.593183Z","steps":["trace[1259312402] 'read index received' (duration: 41.352481ms)","trace[1259312402] 'applied index is now lower than readState.Index' (duration: 315.918078ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:52.593251Z","caller":"traceutil/trace.go:171","msg":"trace[592425086] transaction","detail":"{read_only:false; response_revision:27040; number_of_response:1; }","duration":"400.664523ms","start":"2026-09-12T07:28:52.192562Z","end":"2026-09-12T07:28:52.593226Z","steps":["trace[592425086] 'process raft request' (duration: 400.545271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:52.593374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:52.192542Z","time spent":"400.769266ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:28:52.593382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.887076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T07:28:52.593518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.585857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-12T07:28:52.593614Z","caller":"traceutil/trace.go:171","msg":"trace[1660193930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27040; }","duration":"357.727579ms","start":"2026-09-12T07:28:52.235864Z","end":"2026-09-12T07:28:52.593591Z","steps":["trace[1660193930] 'agreement among raft nodes before linearized reading' (duration: 357.390772ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:52.593546Z","caller":"traceutil/trace.go:171","msg":"trace[1286627038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27040; }","duration":"322.08248ms","start":"2026-09-12T07:28:52.271447Z","end":"2026-09-12T07:28:52.593529Z","steps":["trace[1286627038] 'agreement among raft nodes before linearized reading' (duration: 321.885806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:52.593677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:52.235845Z","time spent":"357.816641ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-12T07:28:52.593714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:52.271428Z","time spent":"322.259594ms","remote":"127.0.0.1:56572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-12T07:28:52.59337Z","caller":"traceutil/trace.go:171","msg":"trace[477950629] transaction","detail":"{read_only:false; response_revision:27039; number_of_response:1; }","duration":"432.671465ms","start":"2026-09-12T07:28:52.160666Z","end":"2026-09-12T07:28:52.593337Z","steps":["trace[477950629] 'process raft request' (duration: 305.770312ms)","trace[477950629] 'compare' (duration: 126.28863ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:52.5943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:28:52.160641Z","time spent":"433.184846ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:28:52.794389Z","caller":"traceutil/trace.go:171","msg":"trace[1983188921] linearizableReadLoop","detail":"{readStateIndex:27938; appliedIndex:27937; }","duration":"169.414049ms","start":"2026-09-12T07:28:52.624955Z","end":"2026-09-12T07:28:52.794369Z","steps":["trace[1983188921] 'read index received' (duration: 169.224185ms)","trace[1983188921] 'applied index is now lower than readState.Index' (duration: 189.164µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:52.794624Z","caller":"traceutil/trace.go:171","msg":"trace[171684547] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"196.70574ms","start":"2026-09-12T07:28:52.59771Z","end":"2026-09-12T07:28:52.794415Z","steps":["trace[171684547] 'process raft request' (duration: 196.550386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:52.794686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.707886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:52.794728Z","caller":"traceutil/trace.go:171","msg":"trace[2147336206] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:27041; }","duration":"169.794527ms","start":"2026-09-12T07:28:52.62492Z","end":"2026-09-12T07:28:52.794714Z","steps":["trace[2147336206] 'agreement among raft nodes before linearized reading' (duration: 169.704975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:53.001195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.787083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:53.001285Z","caller":"traceutil/trace.go:171","msg":"trace[1882950779] linearizableReadLoop","detail":"{readStateIndex:27939; appliedIndex:27938; }","duration":"124.121885ms","start":"2026-09-12T07:28:52.877149Z","end":"2026-09-12T07:28:53.00127Z","steps":["trace[1882950779] 'read index received' (duration: 42.071µs)","trace[1882950779] 'applied index is now lower than readState.Index' (duration: 124.078544ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:53.001395Z","caller":"traceutil/trace.go:171","msg":"trace[634573051] transaction","detail":"{read_only:false; response_revision:27042; number_of_response:1; }","duration":"202.947324ms","start":"2026-09-12T07:28:52.798417Z","end":"2026-09-12T07:28:53.001364Z","steps":["trace[634573051] 'process raft request' (duration: 65.917885ms)","trace[634573051] 'compare' (duration: 136.551719ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:28:53.001514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.331169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:28:53.001587Z","caller":"traceutil/trace.go:171","msg":"trace[430953984] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:27042; }","duration":"124.456782ms","start":"2026-09-12T07:28:52.87711Z","end":"2026-09-12T07:28:53.001567Z","steps":["trace[430953984] 'agreement among raft nodes before linearized reading' (duration: 124.328409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:53.075379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.575991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-12T07:28:53.07545Z","caller":"traceutil/trace.go:171","msg":"trace[858208707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27046; }","duration":"153.718485ms","start":"2026-09-12T07:28:52.921715Z","end":"2026-09-12T07:28:53.075433Z","steps":["trace[858208707] 'agreement among raft nodes before linearized reading' (duration: 153.51424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:28:53.562808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.77611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:28:53.562985Z","caller":"traceutil/trace.go:171","msg":"trace[85629920] transaction","detail":"{read_only:false; response_revision:27049; number_of_response:1; }","duration":"193.172334ms","start":"2026-09-12T07:28:53.369795Z","end":"2026-09-12T07:28:53.562968Z","steps":["trace[85629920] 'process raft request' (duration: 193.111513ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:53.563008Z","caller":"traceutil/trace.go:171","msg":"trace[1155802790] transaction","detail":"{read_only:false; response_revision:27048; number_of_response:1; }","duration":"193.390209ms","start":"2026-09-12T07:28:53.369582Z","end":"2026-09-12T07:28:53.562973Z","steps":["trace[1155802790] 'process raft request' (duration: 59.336123ms)","trace[1155802790] 'compare' (duration: 133.629907ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:28:55.24865Z","caller":"traceutil/trace.go:171","msg":"trace[953260339] transaction","detail":"{read_only:false; response_revision:27059; number_of_response:1; }","duration":"166.909165ms","start":"2026-09-12T07:28:55.08172Z","end":"2026-09-12T07:28:55.24863Z","steps":["trace[953260339] 'process raft request' (duration: 166.805423ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:55.248723Z","caller":"traceutil/trace.go:171","msg":"trace[1731801774] transaction","detail":"{read_only:false; response_revision:27058; number_of_response:1; }","duration":"168.379776ms","start":"2026-09-12T07:28:55.080302Z","end":"2026-09-12T07:28:55.248682Z","steps":["trace[1731801774] 'process raft request' (duration: 168.046669ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:28:55.24861Z","caller":"traceutil/trace.go:171","msg":"trace[1049579594] transaction","detail":"{read_only:false; response_revision:27057; number_of_response:1; }","duration":"168.217302ms","start":"2026-09-12T07:28:55.080213Z","end":"2026-09-12T07:28:55.24843Z","steps":["trace[1049579594] 'process raft request' (duration: 149.602355ms)","trace[1049579594] 'compare' (duration: 18.327551ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:09.879637Z","caller":"traceutil/trace.go:171","msg":"trace[469636579] linearizableReadLoop","detail":"{readStateIndex:28068; appliedIndex:28067; }","duration":"133.291819ms","start":"2026-09-12T07:29:09.746313Z","end":"2026-09-12T07:29:09.879605Z","steps":["trace[469636579] 'read index received' (duration: 133.000023ms)","trace[469636579] 'applied index is now lower than readState.Index' (duration: 290.116µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:09.879739Z","caller":"traceutil/trace.go:171","msg":"trace[742074339] transaction","detail":"{read_only:false; response_revision:27168; number_of_response:1; }","duration":"133.82151ms","start":"2026-09-12T07:29:09.745877Z","end":"2026-09-12T07:29:09.879698Z","steps":["trace[742074339] 'process raft request' (duration: 133.515054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:09.879839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.478613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:29:09.879891Z","caller":"traceutil/trace.go:171","msg":"trace[1997664747] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:27168; }","duration":"133.579325ms","start":"2026-09-12T07:29:09.746295Z","end":"2026-09-12T07:29:09.879874Z","steps":["trace[1997664747] 'agreement among raft nodes before linearized reading' (duration: 133.443052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:15.256229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.790698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:29:15.256316Z","caller":"traceutil/trace.go:171","msg":"trace[812398643] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:27209; }","duration":"163.920892ms","start":"2026-09-12T07:29:15.092372Z","end":"2026-09-12T07:29:15.256293Z","steps":["trace[812398643] 'count revisions from in-memory index tree' (duration: 163.685445ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:15.426064Z","caller":"traceutil/trace.go:171","msg":"trace[559634979] transaction","detail":"{read_only:false; response_revision:27210; number_of_response:1; }","duration":"296.795841ms","start":"2026-09-12T07:29:15.129233Z","end":"2026-09-12T07:29:15.426029Z","steps":["trace[559634979] 'process raft request' (duration: 296.552846ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:15.453984Z","caller":"traceutil/trace.go:171","msg":"trace[424213718] transaction","detail":"{read_only:false; response_revision:27211; number_of_response:1; }","duration":"290.417005ms","start":"2026-09-12T07:29:15.16354Z","end":"2026-09-12T07:29:15.453957Z","steps":["trace[424213718] 'process raft request' (duration: 290.18992ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:15.45438Z","caller":"traceutil/trace.go:171","msg":"trace[2072456773] transaction","detail":"{read_only:false; response_revision:27212; number_of_response:1; }","duration":"136.476927ms","start":"2026-09-12T07:29:15.317876Z","end":"2026-09-12T07:29:15.454353Z","steps":["trace[2072456773] 'process raft request' (duration: 136.031087ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:15.454522Z","caller":"traceutil/trace.go:171","msg":"trace[2032748925] transaction","detail":"{read_only:false; response_revision:27214; number_of_response:1; }","duration":"126.275199ms","start":"2026-09-12T07:29:15.328234Z","end":"2026-09-12T07:29:15.45451Z","steps":["trace[2032748925] 'process raft request' (duration: 126.207538ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:15.454666Z","caller":"traceutil/trace.go:171","msg":"trace[1464315758] transaction","detail":"{read_only:false; response_revision:27213; number_of_response:1; }","duration":"126.496044ms","start":"2026-09-12T07:29:15.328101Z","end":"2026-09-12T07:29:15.454597Z","steps":["trace[1464315758] 'process raft request' (duration: 126.155767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:15.628181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.90574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-12T07:29:15.628268Z","caller":"traceutil/trace.go:171","msg":"trace[944324787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27214; }","duration":"132.008501ms","start":"2026-09-12T07:29:15.496229Z","end":"2026-09-12T07:29:15.628237Z","steps":["trace[944324787] 'range keys from in-memory index tree' (duration: 131.690445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:15.628024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.561484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:29:15.628641Z","caller":"traceutil/trace.go:171","msg":"trace[416538243] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:27214; }","duration":"141.324441ms","start":"2026-09-12T07:29:15.487308Z","end":"2026-09-12T07:29:15.628632Z","steps":["trace[416538243] 'count revisions from in-memory index tree' (duration: 140.431291ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:15.736234Z","caller":"traceutil/trace.go:171","msg":"trace[922921054] transaction","detail":"{read_only:false; response_revision:27215; number_of_response:1; }","duration":"100.787197ms","start":"2026-09-12T07:29:15.635411Z","end":"2026-09-12T07:29:15.736199Z","steps":["trace[922921054] 'process raft request' (duration: 100.502261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:16.03122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.195195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:29:16.031317Z","caller":"traceutil/trace.go:171","msg":"trace[1150350108] linearizableReadLoop","detail":"{readStateIndex:28117; appliedIndex:28116; }","duration":"290.597499ms","start":"2026-09-12T07:29:15.740704Z","end":"2026-09-12T07:29:16.031301Z","steps":["trace[1150350108] 'read index received' (duration: 190.266752ms)","trace[1150350108] 'applied index is now lower than readState.Index' (duration: 100.329577ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:16.031345Z","caller":"traceutil/trace.go:171","msg":"trace[1970398067] transaction","detail":"{read_only:false; response_revision:27216; number_of_response:1; }","duration":"290.688781ms","start":"2026-09-12T07:29:15.740632Z","end":"2026-09-12T07:29:16.031321Z","steps":["trace[1970398067] 'process raft request' (duration: 190.324374ms)","trace[1970398067] 'compare' (duration: 99.927588ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:29:16.03142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.709522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T07:29:16.031455Z","caller":"traceutil/trace.go:171","msg":"trace[1437775138] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27216; }","duration":"290.797884ms","start":"2026-09-12T07:29:15.740647Z","end":"2026-09-12T07:29:16.031445Z","steps":["trace[1437775138] 'agreement among raft nodes before linearized reading' (duration: 290.717852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:16.045439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.501994ms","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-09-12T07:29:16.045522Z","caller":"traceutil/trace.go:171","msg":"trace[316901685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27217; }","duration":"125.627076ms","start":"2026-09-12T07:29:15.919873Z","end":"2026-09-12T07:29:16.0455Z","steps":["trace[316901685] 'agreement among raft nodes before linearized reading' (duration: 125.223628ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:16.045862Z","caller":"traceutil/trace.go:171","msg":"trace[1775174083] transaction","detail":"{read_only:false; response_revision:27217; number_of_response:1; }","duration":"305.116099ms","start":"2026-09-12T07:29:15.740732Z","end":"2026-09-12T07:29:16.045848Z","steps":["trace[1775174083] 'process raft request' (duration: 304.22189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:16.045956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:29:15.740676Z","time spent":"305.212471ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:29:16.353008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.370141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:29:16.353117Z","caller":"traceutil/trace.go:171","msg":"trace[1224850717] transaction","detail":"{read_only:false; response_revision:27218; number_of_response:1; }","duration":"302.597855ms","start":"2026-09-12T07:29:16.050497Z","end":"2026-09-12T07:29:16.353095Z","steps":["trace[1224850717] 'process raft request' (duration: 160.060169ms)","trace[1224850717] 'compare' (duration: 142.080635ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:29:16.353197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:29:16.05047Z","time spent":"302.698247ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:29:16.621179Z","caller":"traceutil/trace.go:171","msg":"trace[840490402] linearizableReadLoop","detail":"{readStateIndex:28121; appliedIndex:28119; }","duration":"348.523693ms","start":"2026-09-12T07:29:16.272601Z","end":"2026-09-12T07:29:16.621125Z","steps":["trace[840490402] 'read index received' (duration: 271.456162ms)","trace[840490402] 'applied index is now lower than readState.Index' (duration: 77.065951ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:16.621391Z","caller":"traceutil/trace.go:171","msg":"trace[1757936531] transaction","detail":"{read_only:false; response_revision:27220; number_of_response:1; }","duration":"474.833873ms","start":"2026-09-12T07:29:16.146518Z","end":"2026-09-12T07:29:16.621352Z","steps":["trace[1757936531] 'process raft request' (duration: 474.524576ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:16.621484Z","caller":"traceutil/trace.go:171","msg":"trace[1775603692] transaction","detail":"{read_only:false; response_revision:27219; number_of_response:1; }","duration":"565.206508ms","start":"2026-09-12T07:29:16.056243Z","end":"2026-09-12T07:29:16.62145Z","steps":["trace[1775603692] 'process raft request' (duration: 487.866631ms)","trace[1775603692] 'compare' (duration: 76.766065ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:29:16.621588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:29:16.145556Z","time spent":"475.897836ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:29:16.621629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:29:16.05622Z","time spent":"565.33281ms","remote":"127.0.0.1:56790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:29:16.621666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.034473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:29:16.621783Z","caller":"traceutil/trace.go:171","msg":"trace[1988583321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27220; }","duration":"349.206137ms","start":"2026-09-12T07:29:16.272545Z","end":"2026-09-12T07:29:16.621752Z","steps":["trace[1988583321] 'agreement among raft nodes before linearized reading' (duration: 348.981902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:16.621849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:29:16.272521Z","time spent":"349.319309ms","remote":"127.0.0.1:56572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-12T07:29:16.925205Z","caller":"traceutil/trace.go:171","msg":"trace[957701978] linearizableReadLoop","detail":"{readStateIndex:28125; appliedIndex:28122; }","duration":"172.519904ms","start":"2026-09-12T07:29:16.752654Z","end":"2026-09-12T07:29:16.925174Z","steps":["trace[957701978] 'read index received' (duration: 109.110933ms)","trace[957701978] 'applied index is now lower than readState.Index' (duration: 63.407781ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:16.925227Z","caller":"traceutil/trace.go:171","msg":"trace[1739772975] transaction","detail":"{read_only:false; response_revision:27223; number_of_response:1; }","duration":"288.583627ms","start":"2026-09-12T07:29:16.636614Z","end":"2026-09-12T07:29:16.925197Z","steps":["trace[1739772975] 'process raft request' (duration: 288.396233ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:16.925219Z","caller":"traceutil/trace.go:171","msg":"trace[1403822020] transaction","detail":"{read_only:false; response_revision:27222; number_of_response:1; }","duration":"289.034956ms","start":"2026-09-12T07:29:16.636101Z","end":"2026-09-12T07:29:16.925136Z","steps":["trace[1403822020] 'process raft request' (duration: 225.636375ms)","trace[1403822020] 'compare' (duration: 62.938321ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:16.925358Z","caller":"traceutil/trace.go:171","msg":"trace[1633988887] transaction","detail":"{read_only:false; response_revision:27224; number_of_response:1; }","duration":"280.007734ms","start":"2026-09-12T07:29:16.645323Z","end":"2026-09-12T07:29:16.92533Z","steps":["trace[1633988887] 'process raft request' (duration: 279.798229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:16.925397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.73427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.147\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-12T07:29:16.925438Z","caller":"traceutil/trace.go:171","msg":"trace[568967991] range","detail":"{range_begin:/registry/masterleases/162.253.55.147; range_end:; response_count:1; response_revision:27224; }","duration":"172.831362ms","start":"2026-09-12T07:29:16.752595Z","end":"2026-09-12T07:29:16.925426Z","steps":["trace[568967991] 'agreement among raft nodes before linearized reading' (duration: 172.677958ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:16.930031Z","caller":"traceutil/trace.go:171","msg":"trace[1811335438] transaction","detail":"{read_only:false; response_revision:27225; number_of_response:1; }","duration":"127.78372ms","start":"2026-09-12T07:29:16.802229Z","end":"2026-09-12T07:29:16.930013Z","steps":["trace[1811335438] 'process raft request' (duration: 127.431163ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:17.191707Z","caller":"traceutil/trace.go:171","msg":"trace[691780351] linearizableReadLoop","detail":"{readStateIndex:28129; appliedIndex:28128; }","duration":"212.341702ms","start":"2026-09-12T07:29:16.979343Z","end":"2026-09-12T07:29:17.191685Z","steps":["trace[691780351] 'read index received' (duration: 212.20792ms)","trace[691780351] 'applied index is now lower than readState.Index' (duration: 132.602µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:17.191833Z","caller":"traceutil/trace.go:171","msg":"trace[1441244056] transaction","detail":"{read_only:false; response_revision:27227; number_of_response:1; }","duration":"256.177306ms","start":"2026-09-12T07:29:16.935598Z","end":"2026-09-12T07:29:17.191776Z","steps":["trace[1441244056] 'process raft request' (duration: 255.922211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:17.192036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.681006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-12T07:29:17.19203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.662249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-09-12T07:29:17.192114Z","caller":"traceutil/trace.go:171","msg":"trace[103041794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27227; }","duration":"179.793619ms","start":"2026-09-12T07:29:17.012286Z","end":"2026-09-12T07:29:17.19208Z","steps":["trace[103041794] 'agreement among raft nodes before linearized reading' (duration: 179.602735ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:17.192121Z","caller":"traceutil/trace.go:171","msg":"trace[475219566] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27227; }","duration":"212.795942ms","start":"2026-09-12T07:29:16.979305Z","end":"2026-09-12T07:29:17.192101Z","steps":["trace[475219566] 'agreement among raft nodes before linearized reading' (duration: 212.527737ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:17.546472Z","caller":"traceutil/trace.go:171","msg":"trace[1963206013] linearizableReadLoop","detail":"{readStateIndex:28131; appliedIndex:28130; }","duration":"109.680017ms","start":"2026-09-12T07:29:17.436764Z","end":"2026-09-12T07:29:17.546444Z","steps":["trace[1963206013] 'read index received' (duration: 109.559533ms)","trace[1963206013] 'applied index is now lower than readState.Index' (duration: 118.934µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:17.546522Z","caller":"traceutil/trace.go:171","msg":"trace[1388742673] transaction","detail":"{read_only:false; response_revision:27229; number_of_response:1; }","duration":"114.390636ms","start":"2026-09-12T07:29:17.432081Z","end":"2026-09-12T07:29:17.546472Z","steps":["trace[1388742673] 'process raft request' (duration: 114.155011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:17.546784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.011964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-12T07:29:17.546896Z","caller":"traceutil/trace.go:171","msg":"trace[1805486129] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:27229; }","duration":"110.208668ms","start":"2026-09-12T07:29:17.436664Z","end":"2026-09-12T07:29:17.546873Z","steps":["trace[1805486129] 'agreement among raft nodes before linearized reading' (duration: 109.923662ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:17.648581Z","caller":"traceutil/trace.go:171","msg":"trace[1713504437] transaction","detail":"{read_only:false; response_revision:27230; number_of_response:1; }","duration":"189.238681ms","start":"2026-09-12T07:29:17.459304Z","end":"2026-09-12T07:29:17.648542Z","steps":["trace[1713504437] 'process raft request' (duration: 178.340519ms)","trace[1713504437] 'compare' (duration: 10.31685ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:17.648714Z","caller":"traceutil/trace.go:171","msg":"trace[1964421938] transaction","detail":"{read_only:false; response_revision:27232; number_of_response:1; }","duration":"188.408142ms","start":"2026-09-12T07:29:17.46025Z","end":"2026-09-12T07:29:17.648658Z","steps":["trace[1964421938] 'process raft request' (duration: 188.272709ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:17.648738Z","caller":"traceutil/trace.go:171","msg":"trace[1174393947] transaction","detail":"{read_only:false; response_revision:27231; number_of_response:1; }","duration":"189.004835ms","start":"2026-09-12T07:29:17.459658Z","end":"2026-09-12T07:29:17.648663Z","steps":["trace[1174393947] 'process raft request' (duration: 188.74016ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:17.648886Z","caller":"traceutil/trace.go:171","msg":"trace[1306348528] transaction","detail":"{read_only:false; response_revision:27233; number_of_response:1; }","duration":"187.136366ms","start":"2026-09-12T07:29:17.461683Z","end":"2026-09-12T07:29:17.648819Z","steps":["trace[1306348528] 'process raft request' (duration: 186.88365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:35.926615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.985104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:29:35.926775Z","caller":"traceutil/trace.go:171","msg":"trace[956171] transaction","detail":"{read_only:false; response_revision:27507; number_of_response:1; }","duration":"198.181972ms","start":"2026-09-12T07:29:35.728569Z","end":"2026-09-12T07:29:35.926751Z","steps":["trace[956171] 'process raft request' (duration: 48.933972ms)","trace[956171] 'compare' (duration: 148.841971ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:35.971351Z","caller":"traceutil/trace.go:171","msg":"trace[1082711247] linearizableReadLoop","detail":"{readStateIndex:28416; appliedIndex:28413; }","duration":"135.147769ms","start":"2026-09-12T07:29:35.836176Z","end":"2026-09-12T07:29:35.971324Z","steps":["trace[1082711247] 'read index received' (duration: 134.861683ms)","trace[1082711247] 'applied index is now lower than readState.Index' (duration: 284.966µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:35.971398Z","caller":"traceutil/trace.go:171","msg":"trace[1754062508] transaction","detail":"{read_only:false; response_revision:27508; number_of_response:1; }","duration":"240.796989ms","start":"2026-09-12T07:29:35.730563Z","end":"2026-09-12T07:29:35.97136Z","steps":["trace[1754062508] 'process raft request' (duration: 240.503103ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:35.971439Z","caller":"traceutil/trace.go:171","msg":"trace[917716108] transaction","detail":"{read_only:false; response_revision:27509; number_of_response:1; }","duration":"239.41759ms","start":"2026-09-12T07:29:35.732Z","end":"2026-09-12T07:29:35.971417Z","steps":["trace[917716108] 'process raft request' (duration: 239.242696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:29:35.971621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.429386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-12T07:29:35.971671Z","caller":"traceutil/trace.go:171","msg":"trace[458341567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27510; }","duration":"135.493797ms","start":"2026-09-12T07:29:35.836162Z","end":"2026-09-12T07:29:35.971656Z","steps":["trace[458341567] 'agreement among raft nodes before linearized reading' (duration: 135.320133ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:35.971585Z","caller":"traceutil/trace.go:171","msg":"trace[2105938663] transaction","detail":"{read_only:false; response_revision:27510; number_of_response:1; }","duration":"238.024729ms","start":"2026-09-12T07:29:35.733507Z","end":"2026-09-12T07:29:35.971531Z","steps":["trace[2105938663] 'process raft request' (duration: 237.776764ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:29:36.083967Z","caller":"traceutil/trace.go:171","msg":"trace[100078915] transaction","detail":"{read_only:false; response_revision:27511; number_of_response:1; }","duration":"106.980028ms","start":"2026-09-12T07:29:35.976952Z","end":"2026-09-12T07:29:36.083932Z","steps":["trace[100078915] 'process raft request' (duration: 88.925913ms)","trace[100078915] 'compare' (duration: 17.837891ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:29:43.491587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24641} {"level":"info","ts":"2026-09-12T07:29:43.6509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24641,"took":"155.305198ms","hash":1991932231,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":36917248,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-12T07:29:43.650973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1991932231,"revision":24641,"compact-revision":21680} {"level":"info","ts":"2026-09-12T07:31:02.258521Z","caller":"traceutil/trace.go:171","msg":"trace[185995412] transaction","detail":"{read_only:false; response_revision:28306; number_of_response:1; }","duration":"249.920513ms","start":"2026-09-12T07:31:02.008578Z","end":"2026-09-12T07:31:02.258499Z","steps":["trace[185995412] 'process raft request' (duration: 249.766719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:02.692594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.561327ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-12T07:31:02.692844Z","caller":"traceutil/trace.go:171","msg":"trace[2000560449] linearizableReadLoop","detail":"{readStateIndex:29235; appliedIndex:29232; }","duration":"437.059939ms","start":"2026-09-12T07:31:02.255769Z","end":"2026-09-12T07:31:02.692829Z","steps":["trace[2000560449] 'read index received' (duration: 2.535195ms)","trace[2000560449] 'applied index is now lower than readState.Index' (duration: 434.524014ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:31:02.693066Z","caller":"traceutil/trace.go:171","msg":"trace[1588316495] transaction","detail":"{read_only:false; response_revision:28307; number_of_response:1; }","duration":"445.196002ms","start":"2026-09-12T07:31:02.247861Z","end":"2026-09-12T07:31:02.693057Z","steps":["trace[1588316495] 'process raft request' (duration: 444.849234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:02.693149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.24783Z","time spent":"445.263114ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:31:02.693791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.99393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:36 size:307918"} {"level":"info","ts":"2026-09-12T07:31:02.69386Z","caller":"traceutil/trace.go:171","msg":"trace[1226367219] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:36; response_revision:28307; }","duration":"438.144552ms","start":"2026-09-12T07:31:02.255695Z","end":"2026-09-12T07:31:02.693839Z","steps":["trace[1226367219] 'agreement among raft nodes before linearized reading' (duration: 437.55619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:02.693904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.255674Z","time spent":"438.217225ms","remote":"127.0.0.1:57034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":36,"response size":307941,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-09-12T07:31:03.045208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.251707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:31:03.045362Z","caller":"traceutil/trace.go:171","msg":"trace[1890753786] transaction","detail":"{read_only:false; response_revision:28311; number_of_response:1; }","duration":"239.45443ms","start":"2026-09-12T07:31:02.805894Z","end":"2026-09-12T07:31:03.045348Z","steps":["trace[1890753786] 'process raft request' (duration: 239.428929ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:31:03.045709Z","caller":"traceutil/trace.go:171","msg":"trace[814134118] transaction","detail":"{read_only:false; response_revision:28308; number_of_response:1; }","duration":"738.56365ms","start":"2026-09-12T07:31:02.307119Z","end":"2026-09-12T07:31:03.045682Z","steps":["trace[814134118] 'process raft request' (duration: 589.785321ms)","trace[814134118] 'compare' (duration: 147.993312ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:31:03.045759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.307093Z","time spent":"738.640342ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:31:03.046058Z","caller":"traceutil/trace.go:171","msg":"trace[316010460] transaction","detail":"{read_only:false; response_revision:28309; number_of_response:1; }","duration":"722.666722ms","start":"2026-09-12T07:31:02.323383Z","end":"2026-09-12T07:31:03.04605Z","steps":["trace[316010460] 'process raft request' (duration: 721.889215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.046189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.32335Z","time spent":"722.788704ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:31:03.046363Z","caller":"traceutil/trace.go:171","msg":"trace[1252977451] transaction","detail":"{read_only:false; response_revision:28310; number_of_response:1; }","duration":"709.291938ms","start":"2026-09-12T07:31:02.337063Z","end":"2026-09-12T07:31:03.046355Z","steps":["trace[1252977451] 'process raft request' (duration: 708.234545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.046535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.337037Z","time spent":"709.38461ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:31:03.04876Z","caller":"traceutil/trace.go:171","msg":"trace[592254547] linearizableReadLoop","detail":"{readStateIndex:29238; appliedIndex:29235; }","duration":"355.8655ms","start":"2026-09-12T07:31:02.692869Z","end":"2026-09-12T07:31:03.048735Z","steps":["trace[592254547] 'read index received' (duration: 204.035476ms)","trace[592254547] 'applied index is now lower than readState.Index' (duration: 151.828464ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:31:03.049175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.935355ms","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-09-12T07:31:03.049201Z","caller":"traceutil/trace.go:171","msg":"trace[1590343357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28311; }","duration":"428.012656ms","start":"2026-09-12T07:31:02.62118Z","end":"2026-09-12T07:31:03.049193Z","steps":["trace[1590343357] 'agreement among raft nodes before linearized reading' (duration: 427.912074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.049221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.621154Z","time spent":"428.062568ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-12T07:31:03.04934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.308573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-12T07:31:03.049361Z","caller":"traceutil/trace.go:171","msg":"trace[477731487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28311; }","duration":"536.375945ms","start":"2026-09-12T07:31:02.512979Z","end":"2026-09-12T07:31:03.049355Z","steps":["trace[477731487] 'agreement among raft nodes before linearized reading' (duration: 536.321543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.049377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.512931Z","time spent":"536.442196ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-12T07:31:03.0495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.706969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" ","response":"range_response_count:1 size:874"} {"level":"info","ts":"2026-09-12T07:31:03.049523Z","caller":"traceutil/trace.go:171","msg":"trace[267750554] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery; range_end:; response_count:1; response_revision:28311; }","duration":"564.858581ms","start":"2026-09-12T07:31:02.484657Z","end":"2026-09-12T07:31:03.049516Z","steps":["trace[267750554] 'agreement among raft nodes before linearized reading' (duration: 564.79914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.049538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.484634Z","time spent":"564.900612ms","remote":"127.0.0.1:56806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":897,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" "} {"level":"warn","ts":"2026-09-12T07:31:03.049645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.146745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:31:03.049662Z","caller":"traceutil/trace.go:171","msg":"trace[1023843886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28311; }","duration":"779.194246ms","start":"2026-09-12T07:31:02.270463Z","end":"2026-09-12T07:31:03.049657Z","steps":["trace[1023843886] 'agreement among raft nodes before linearized reading' (duration: 779.165085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.049677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.270445Z","time spent":"779.228726ms","remote":"127.0.0.1:56572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-12T07:31:03.049799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.338767ms","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-09-12T07:31:03.049819Z","caller":"traceutil/trace.go:171","msg":"trace[1231597949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28311; }","duration":"338.401228ms","start":"2026-09-12T07:31:02.711413Z","end":"2026-09-12T07:31:03.049814Z","steps":["trace[1231597949] 'agreement among raft nodes before linearized reading' (duration: 338.345027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.049836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:02.711393Z","time spent":"338.438058ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-12T07:31:03.051031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.549716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21419"} {"level":"info","ts":"2026-09-12T07:31:03.05106Z","caller":"traceutil/trace.go:171","msg":"trace[447342118] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:28311; }","duration":"173.603977ms","start":"2026-09-12T07:31:02.877449Z","end":"2026-09-12T07:31:03.051053Z","steps":["trace[447342118] 'agreement among raft nodes before linearized reading' (duration: 173.536226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.051159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.804729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:31:03.051181Z","caller":"traceutil/trace.go:171","msg":"trace[1901623461] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:28311; }","duration":"179.861321ms","start":"2026-09-12T07:31:02.871313Z","end":"2026-09-12T07:31:03.051174Z","steps":["trace[1901623461] 'agreement among raft nodes before linearized reading' (duration: 179.83312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.051326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.473295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:31:03.051351Z","caller":"traceutil/trace.go:171","msg":"trace[1823102927] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:28311; }","duration":"289.502186ms","start":"2026-09-12T07:31:02.761841Z","end":"2026-09-12T07:31:03.051344Z","steps":["trace[1823102927] 'agreement among raft nodes before linearized reading' (duration: 289.445225ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:31:03.381156Z","caller":"traceutil/trace.go:171","msg":"trace[1941627406] linearizableReadLoop","detail":"{readStateIndex:29244; appliedIndex:29240; }","duration":"240.777289ms","start":"2026-09-12T07:31:03.140361Z","end":"2026-09-12T07:31:03.381138Z","steps":["trace[1941627406] 'read index received' (duration: 240.274578ms)","trace[1941627406] 'applied index is now lower than readState.Index' (duration: 501.801µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:31:03.381201Z","caller":"traceutil/trace.go:171","msg":"trace[596934231] transaction","detail":"{read_only:false; response_revision:28313; number_of_response:1; }","duration":"323.356637ms","start":"2026-09-12T07:31:03.057805Z","end":"2026-09-12T07:31:03.381162Z","steps":["trace[596934231] 'process raft request' (duration: 323.108842ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:31:03.381344Z","caller":"traceutil/trace.go:171","msg":"trace[1632626182] transaction","detail":"{read_only:false; response_revision:28314; number_of_response:1; }","duration":"322.897697ms","start":"2026-09-12T07:31:03.058401Z","end":"2026-09-12T07:31:03.381299Z","steps":["trace[1632626182] 'process raft request' (duration: 322.663512ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:31:03.381341Z","caller":"traceutil/trace.go:171","msg":"trace[223091927] transaction","detail":"{read_only:false; response_revision:28316; number_of_response:1; }","duration":"307.447598ms","start":"2026-09-12T07:31:03.073823Z","end":"2026-09-12T07:31:03.381271Z","steps":["trace[223091927] 'process raft request' (duration: 307.291925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.381259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.878161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T07:31:03.381529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:03.058393Z","time spent":"323.04045ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:31:03.381528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:03.05779Z","time spent":"323.4961ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:31:03.381575Z","caller":"traceutil/trace.go:171","msg":"trace[565097576] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:28316; }","duration":"241.222588ms","start":"2026-09-12T07:31:03.140324Z","end":"2026-09-12T07:31:03.381546Z","steps":["trace[565097576] 'agreement among raft nodes before linearized reading' (duration: 240.892211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.38163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:03.073803Z","time spent":"307.687334ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:31:03.382074Z","caller":"traceutil/trace.go:171","msg":"trace[1781695088] transaction","detail":"{read_only:false; response_revision:28315; number_of_response:1; }","duration":"322.193853ms","start":"2026-09-12T07:31:03.059843Z","end":"2026-09-12T07:31:03.382037Z","steps":["trace[1781695088] 'process raft request' (duration: 321.250763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.382258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:03.059836Z","time spent":"322.329306ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T07:31:03.415091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.85695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:31:03.415177Z","caller":"traceutil/trace.go:171","msg":"trace[31480927] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:28318; }","duration":"202.979563ms","start":"2026-09-12T07:31:03.21218Z","end":"2026-09-12T07:31:03.415159Z","steps":["trace[31480927] 'agreement among raft nodes before linearized reading' (duration: 202.84372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:03.415581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.396694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21419"} {"level":"info","ts":"2026-09-12T07:31:03.415615Z","caller":"traceutil/trace.go:171","msg":"trace[1237389777] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:28318; }","duration":"197.453395ms","start":"2026-09-12T07:31:03.218152Z","end":"2026-09-12T07:31:03.415606Z","steps":["trace[1237389777] 'agreement among raft nodes before linearized reading' (duration: 197.337513ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:31:04.173774Z","caller":"traceutil/trace.go:171","msg":"trace[1295510265] transaction","detail":"{read_only:false; response_revision:28320; number_of_response:1; }","duration":"342.316641ms","start":"2026-09-12T07:31:03.831422Z","end":"2026-09-12T07:31:04.173739Z","steps":["trace[1295510265] 'process raft request' (duration: 342.131407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:04.173931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T07:31:03.8314Z","time spent":"342.453074ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T07:31:04.174026Z","caller":"traceutil/trace.go:171","msg":"trace[756609308] linearizableReadLoop","detail":"{readStateIndex:29248; appliedIndex:29248; }","duration":"188.581536ms","start":"2026-09-12T07:31:03.985407Z","end":"2026-09-12T07:31:04.173988Z","steps":["trace[756609308] 'read index received' (duration: 188.529885ms)","trace[756609308] 'applied index is now lower than readState.Index' (duration: 48.631µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:31:04.17421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.800061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:31:04.174282Z","caller":"traceutil/trace.go:171","msg":"trace[877374053] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:28320; }","duration":"188.923103ms","start":"2026-09-12T07:31:03.985344Z","end":"2026-09-12T07:31:04.174267Z","steps":["trace[877374053] 'agreement among raft nodes before linearized reading' (duration: 188.804051ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:31:04.235209Z","caller":"traceutil/trace.go:171","msg":"trace[1575070706] transaction","detail":"{read_only:false; response_revision:28321; number_of_response:1; }","duration":"112.760852ms","start":"2026-09-12T07:31:04.122419Z","end":"2026-09-12T07:31:04.23518Z","steps":["trace[1575070706] 'process raft request' (duration: 112.289672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:04.561018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.242633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T07:31:04.561104Z","caller":"traceutil/trace.go:171","msg":"trace[2056855484] linearizableReadLoop","detail":"{readStateIndex:29250; appliedIndex:29249; }","duration":"290.771672ms","start":"2026-09-12T07:31:04.270315Z","end":"2026-09-12T07:31:04.561087Z","steps":["trace[2056855484] 'read index received' (duration: 122.353865ms)","trace[2056855484] 'applied index is now lower than readState.Index' (duration: 168.416767ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T07:31:04.561199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.891385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T07:31:04.561181Z","caller":"traceutil/trace.go:171","msg":"trace[1277360929] transaction","detail":"{read_only:false; response_revision:28322; number_of_response:1; }","duration":"297.71333ms","start":"2026-09-12T07:31:04.263441Z","end":"2026-09-12T07:31:04.561155Z","steps":["trace[1277360929] 'process raft request' (duration: 129.257123ms)","trace[1277360929] 'compare' (duration: 167.976727ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T07:31:04.561228Z","caller":"traceutil/trace.go:171","msg":"trace[880268070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28322; }","duration":"290.952736ms","start":"2026-09-12T07:31:04.270267Z","end":"2026-09-12T07:31:04.56122Z","steps":["trace[880268070] 'agreement among raft nodes before linearized reading' (duration: 290.899404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T07:31:04.561525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.936628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-12T07:31:04.561558Z","caller":"traceutil/trace.go:171","msg":"trace[1636031715] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:28322; }","duration":"199.010529ms","start":"2026-09-12T07:31:04.362539Z","end":"2026-09-12T07:31:04.561549Z","steps":["trace[1636031715] 'agreement among raft nodes before linearized reading' (duration: 198.876456ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T07:32:19.463714Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":30014,"local-member-snapshot-index":20013,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T07:32:19.478815Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30014} {"level":"info","ts":"2026-09-12T07:32:19.47893Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25014} {"level":"info","ts":"2026-09-12T07:34:43.499955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27593} {"level":"info","ts":"2026-09-12T07:34:43.645482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27593,"took":"141.640115ms","hash":1805855133,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":35381248,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-12T07:34:43.645558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1805855133,"revision":27593,"compact-revision":24641} {"level":"info","ts":"2026-09-12T07:39:43.506901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30292} {"level":"info","ts":"2026-09-12T07:39:43.656798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30292,"took":"145.599752ms","hash":1921357542,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T07:39:43.656864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1921357542,"revision":30292,"compact-revision":27593} {"level":"info","ts":"2026-09-12T07:44:43.516316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32545} {"level":"info","ts":"2026-09-12T07:44:43.668108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32545,"took":"147.516426ms","hash":2004313090,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":31141888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-12T07:44:43.668372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2004313090,"revision":32545,"compact-revision":30292} {"level":"info","ts":"2026-09-12T07:49:43.523927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34769} {"level":"info","ts":"2026-09-12T07:49:43.669759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34769,"took":"141.661831ms","hash":2611212985,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T07:49:43.66984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2611212985,"revision":34769,"compact-revision":32545} {"level":"info","ts":"2026-09-12T07:53:28.448531Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":40015,"local-member-snapshot-index":30014,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T07:53:28.454425Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40015} {"level":"info","ts":"2026-09-12T07:53:28.454517Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35015} {"level":"info","ts":"2026-09-12T07:54:43.530736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37069} {"level":"info","ts":"2026-09-12T07:54:43.664285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37069,"took":"129.474349ms","hash":745266887,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":31825920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T07:54:43.664368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":745266887,"revision":37069,"compact-revision":34769} {"level":"info","ts":"2026-09-12T07:59:43.540963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39342} {"level":"info","ts":"2026-09-12T07:59:43.683424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39342,"took":"138.328489ms","hash":3975984030,"current-db-size-bytes":58216448,"current-db-size":"58 MB","current-db-size-in-use-bytes":30420992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-12T07:59:43.683486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3975984030,"revision":39342,"compact-revision":37069}