{"level":"warn","ts":"2026-05-19T13:19:52.719861Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:19:52.719959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-05-19T13:19:52.720029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:19:52.72004Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-19T13:19:52.720058Z","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-05-19T13:19:52.720736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-19T13:19:52.720807Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-05-19T13:19:52.724403Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.316718ms"} {"level":"info","ts":"2026-05-19T13:19:52.729576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-19T13:19:52.729658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:19:52.729681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-19T13:19:52.729691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:19:52.729703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-19T13:19:52.729737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-19T13:19:52.739169Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:19:52.740837Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:19:52.741899Z","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-05-19T13:19:52.745184Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:19:52.745326Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:19:52.745898Z","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-05-19T13:19:52.746137Z","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-05-19T13:19:52.746168Z","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-05-19T13:19:52.749371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-19T13:19:52.751802Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-19T13:19:52.754836Z","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-05-19T13:19:52.755131Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:19:52.755221Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:19:52.755358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-19T13:19:52.755398Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-19T13:19:53.130763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:19:53.130872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:19:53.130904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-19T13:19:53.130921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:19:53.130929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-19T13:19:53.130941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-19T13:19:53.130953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-19T13:19:53.131904Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:19:53.133217Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:19:53.133216Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:19:53.133492Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:19:53.135442Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:19:53.135565Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:19:53.135606Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:19:53.135699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:19:53.135716Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:19:53.136818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T13:19:53.137297Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-19T13:20:08.501619Z","caller":"traceutil/trace.go:171","msg":"trace[1664049851] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"179.379796ms","start":"2026-05-19T13:20:08.322206Z","end":"2026-05-19T13:20:08.501586Z","steps":["trace[1664049851] 'process raft request' (duration: 179.146552ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:08.504021Z","caller":"traceutil/trace.go:171","msg":"trace[1430266191] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"177.363511ms","start":"2026-05-19T13:20:08.326634Z","end":"2026-05-19T13:20:08.503998Z","steps":["trace[1430266191] 'process raft request' (duration: 177.266779ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:23.944713Z","caller":"traceutil/trace.go:171","msg":"trace[1815505172] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"214.006908ms","start":"2026-05-19T13:20:23.730664Z","end":"2026-05-19T13:20:23.944671Z","steps":["trace[1815505172] 'process raft request' (duration: 209.740103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:31.59029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.742621ms","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-05-19T13:20:31.590437Z","caller":"traceutil/trace.go:171","msg":"trace[2040019736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"112.951036ms","start":"2026-05-19T13:20:31.477465Z","end":"2026-05-19T13:20:31.590416Z","steps":["trace[2040019736] 'range keys from in-memory index tree' (duration: 112.564698ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:31.882262Z","caller":"traceutil/trace.go:171","msg":"trace[1056761010] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"129.398477ms","start":"2026-05-19T13:20:31.752836Z","end":"2026-05-19T13:20:31.882235Z","steps":["trace[1056761010] 'process raft request' (duration: 128.132923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:32.340482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.254534ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T13:20:32.346244Z","caller":"traceutil/trace.go:171","msg":"trace[75923012] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"408.525471ms","start":"2026-05-19T13:20:31.937696Z","end":"2026-05-19T13:20:32.346221Z","steps":["trace[75923012] 'process raft request' (duration: 408.355248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:32.346762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:31.937671Z","time spent":"408.657964ms","remote":"127.0.0.1:41628","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-05-19T13:20:48.589757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.047527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2vx8g\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-19T13:20:48.589845Z","caller":"traceutil/trace.go:171","msg":"trace[1986102946] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2vx8g; range_end:; response_count:1; response_revision:747; }","duration":"119.19143ms","start":"2026-05-19T13:20:48.470638Z","end":"2026-05-19T13:20:48.589829Z","steps":["trace[1986102946] 'range keys from in-memory index tree' (duration: 118.857994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:48.740577Z","caller":"traceutil/trace.go:171","msg":"trace[405031826] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"141.976006ms","start":"2026-05-19T13:20:48.59858Z","end":"2026-05-19T13:20:48.740556Z","steps":["trace[405031826] 'process raft request' (duration: 141.862004ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:18.024981Z","caller":"traceutil/trace.go:171","msg":"trace[2009239258] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"194.889105ms","start":"2026-05-19T13:21:17.830063Z","end":"2026-05-19T13:21:18.024952Z","steps":["trace[2009239258] 'read index received' (duration: 194.720532ms)","trace[2009239258] 'applied index is now lower than readState.Index' (duration: 167.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:18.025104Z","caller":"traceutil/trace.go:171","msg":"trace[1853272092] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"195.556748ms","start":"2026-05-19T13:21:17.829525Z","end":"2026-05-19T13:21:18.025082Z","steps":["trace[1853272092] 'process raft request' (duration: 195.251842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:18.025292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.210761ms","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-05-19T13:21:18.025396Z","caller":"traceutil/trace.go:171","msg":"trace[978406704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1147; }","duration":"195.350373ms","start":"2026-05-19T13:21:17.830024Z","end":"2026-05-19T13:21:18.025374Z","steps":["trace[978406704] 'agreement among raft nodes before linearized reading' (duration: 195.085958ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:39.898764Z","caller":"traceutil/trace.go:171","msg":"trace[971894441] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"118.345082ms","start":"2026-05-19T13:21:39.780246Z","end":"2026-05-19T13:21:39.898591Z","steps":["trace[971894441] 'read index received' (duration: 117.962055ms)","trace[971894441] 'applied index is now lower than readState.Index' (duration: 380.837µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:21:39.898801Z","caller":"traceutil/trace.go:171","msg":"trace[367018954] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"137.143608ms","start":"2026-05-19T13:21:39.761578Z","end":"2026-05-19T13:21:39.898721Z","steps":["trace[367018954] 'process raft request' (duration: 136.783661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:39.89907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.772201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:39.899198Z","caller":"traceutil/trace.go:171","msg":"trace[1524216354] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1404; }","duration":"119.011195ms","start":"2026-05-19T13:21:39.780156Z","end":"2026-05-19T13:21:39.899167Z","steps":["trace[1524216354] 'agreement among raft nodes before linearized reading' (duration: 118.7364ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:22:06.053305Z","caller":"traceutil/trace.go:171","msg":"trace[1062075926] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"178.208383ms","start":"2026-05-19T13:22:05.875064Z","end":"2026-05-19T13:22:06.053272Z","steps":["trace[1062075926] 'process raft request' (duration: 178.007869ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:22:06.428575Z","caller":"traceutil/trace.go:171","msg":"trace[443825684] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"109.736321ms","start":"2026-05-19T13:22:06.31881Z","end":"2026-05-19T13:22:06.428547Z","steps":["trace[443825684] 'read index received' (duration: 54.151202ms)","trace[443825684] 'applied index is now lower than readState.Index' (duration: 55.583679ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:22:06.428665Z","caller":"traceutil/trace.go:171","msg":"trace[1971679995] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"121.603345ms","start":"2026-05-19T13:22:06.307027Z","end":"2026-05-19T13:22:06.42863Z","steps":["trace[1971679995] 'process raft request' (duration: 66.049826ms)","trace[1971679995] 'compare' (duration: 55.325175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:22:06.428864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.044807ms","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-05-19T13:22:06.428913Z","caller":"traceutil/trace.go:171","msg":"trace[856232276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1818; }","duration":"110.159529ms","start":"2026-05-19T13:22:06.31874Z","end":"2026-05-19T13:22:06.428899Z","steps":["trace[856232276] 'agreement among raft nodes before linearized reading' (duration: 109.968455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:23:42.003478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.703829ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T13:23:42.004053Z","caller":"traceutil/trace.go:171","msg":"trace[1075904283] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"287.874399ms","start":"2026-05-19T13:23:41.716133Z","end":"2026-05-19T13:23:42.004008Z","steps":["trace[1075904283] 'process raft request' (duration: 287.489642ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:24:49.273984Z","caller":"traceutil/trace.go:171","msg":"trace[1052651620] linearizableReadLoop","detail":"{readStateIndex:3047; appliedIndex:3046; }","duration":"115.108972ms","start":"2026-05-19T13:24:49.15885Z","end":"2026-05-19T13:24:49.273959Z","steps":["trace[1052651620] 'read index received' (duration: 114.882608ms)","trace[1052651620] 'applied index is now lower than readState.Index' (duration: 225.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:24:49.274059Z","caller":"traceutil/trace.go:171","msg":"trace[215388569] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"152.390967ms","start":"2026-05-19T13:24:49.121624Z","end":"2026-05-19T13:24:49.274015Z","steps":["trace[215388569] 'process raft request' (duration: 152.170973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:24:49.274351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.464189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-19T13:24:49.274427Z","caller":"traceutil/trace.go:171","msg":"trace[282600187] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2935; }","duration":"115.617132ms","start":"2026-05-19T13:24:49.158793Z","end":"2026-05-19T13:24:49.274411Z","steps":["trace[282600187] 'agreement among raft nodes before linearized reading' (duration: 115.333416ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:15.203888Z","caller":"traceutil/trace.go:171","msg":"trace[2048075066] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"236.288596ms","start":"2026-05-19T13:29:14.967535Z","end":"2026-05-19T13:29:15.203824Z","steps":["trace[2048075066] 'process raft request' (duration: 225.110572ms)","trace[2048075066] 'compare' (duration: 10.818708ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:29:23.342025Z","caller":"traceutil/trace.go:171","msg":"trace[947588566] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"108.81409ms","start":"2026-05-19T13:29:23.233156Z","end":"2026-05-19T13:29:23.34197Z","steps":["trace[947588566] 'process raft request' (duration: 108.342111ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:53.256623Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-05-19T13:29:53.33346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"74.828827ms","hash":3788826085,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":32632832,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T13:29:53.333545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3788826085,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-05-19T13:34:53.264518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5909} {"level":"info","ts":"2026-05-19T13:34:53.350897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5909,"took":"84.295492ms","hash":1012153381,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":24809472,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-19T13:34:53.350965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1012153381,"revision":5909,"compact-revision":2958} {"level":"info","ts":"2026-05-19T13:36:40.436529Z","caller":"traceutil/trace.go:171","msg":"trace[1612186522] linearizableReadLoop","detail":"{readStateIndex:9131; appliedIndex:9130; }","duration":"144.389865ms","start":"2026-05-19T13:36:40.292111Z","end":"2026-05-19T13:36:40.436501Z","steps":["trace[1612186522] 'read index received' (duration: 144.175821ms)","trace[1612186522] 'applied index is now lower than readState.Index' (duration: 213.094µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:40.436882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.758232ms","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-05-19T13:36:40.436936Z","caller":"traceutil/trace.go:171","msg":"trace[1448158955] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"147.158867ms","start":"2026-05-19T13:36:40.289724Z","end":"2026-05-19T13:36:40.436883Z","steps":["trace[1448158955] 'process raft request' (duration: 146.634467ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:40.436984Z","caller":"traceutil/trace.go:171","msg":"trace[1487993396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8817; }","duration":"144.931416ms","start":"2026-05-19T13:36:40.292032Z","end":"2026-05-19T13:36:40.436963Z","steps":["trace[1487993396] 'agreement among raft nodes before linearized reading' (duration: 144.674011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:40.437051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.474764ms","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-05-19T13:36:40.437179Z","caller":"traceutil/trace.go:171","msg":"trace[1608176329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8817; }","duration":"137.681748ms","start":"2026-05-19T13:36:40.299471Z","end":"2026-05-19T13:36:40.437153Z","steps":["trace[1608176329] 'agreement among raft nodes before linearized reading' (duration: 137.336022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:53.547297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.899893ms","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-05-19T13:36:53.547399Z","caller":"traceutil/trace.go:171","msg":"trace[2003254163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8885; }","duration":"127.074996ms","start":"2026-05-19T13:36:53.420304Z","end":"2026-05-19T13:36:53.547379Z","steps":["trace[2003254163] 'range keys from in-memory index tree' (duration: 126.608998ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:53.747247Z","caller":"traceutil/trace.go:171","msg":"trace[1681581936] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"102.217297ms","start":"2026-05-19T13:36:53.645Z","end":"2026-05-19T13:36:53.747217Z","steps":["trace[1681581936] 'process raft request' (duration: 101.969182ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:06.409153Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002441.wal"} {"level":"info","ts":"2026-05-19T13:37:22.309463Z","caller":"traceutil/trace.go:171","msg":"trace[613532280] linearizableReadLoop","detail":"{readStateIndex:9420; appliedIndex:9419; }","duration":"424.029333ms","start":"2026-05-19T13:37:21.885386Z","end":"2026-05-19T13:37:22.309416Z","steps":["trace[613532280] 'read index received' (duration: 423.098205ms)","trace[613532280] 'applied index is now lower than readState.Index' (duration: 929.358µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:37:22.309561Z","caller":"traceutil/trace.go:171","msg":"trace[1533639408] transaction","detail":"{read_only:false; response_revision:9095; number_of_response:1; }","duration":"438.549867ms","start":"2026-05-19T13:37:21.870972Z","end":"2026-05-19T13:37:22.309521Z","steps":["trace[1533639408] 'process raft request' (duration: 438.15836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:22.309905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.508961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-19T13:37:22.309972Z","caller":"traceutil/trace.go:171","msg":"trace[640526550] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9095; }","duration":"424.628354ms","start":"2026-05-19T13:37:21.885318Z","end":"2026-05-19T13:37:22.309946Z","steps":["trace[640526550] 'agreement among raft nodes before linearized reading' (duration: 424.372679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:22.310026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:37:21.885299Z","time spent":"424.713845ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-19T13:37:22.310087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:37:21.87093Z","time spent":"438.732151ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:37:22.310302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.561864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:37:22.31036Z","caller":"traceutil/trace.go:171","msg":"trace[521103533] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:9095; }","duration":"309.641686ms","start":"2026-05-19T13:37:22.000699Z","end":"2026-05-19T13:37:22.310341Z","steps":["trace[521103533] 'agreement among raft nodes before linearized reading' (duration: 309.544293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:22.310408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:37:22.00069Z","time spent":"309.704828ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"info","ts":"2026-05-19T13:37:36.176982Z","caller":"traceutil/trace.go:171","msg":"trace[2083535144] transaction","detail":"{read_only:false; response_revision:9211; number_of_response:1; }","duration":"220.972486ms","start":"2026-05-19T13:37:35.955979Z","end":"2026-05-19T13:37:36.176952Z","steps":["trace[2083535144] 'process raft request' (duration: 220.771943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:36.188353Z","caller":"traceutil/trace.go:171","msg":"trace[492288243] transaction","detail":"{read_only:false; response_revision:9212; number_of_response:1; }","duration":"211.616859ms","start":"2026-05-19T13:37:35.976704Z","end":"2026-05-19T13:37:36.188321Z","steps":["trace[492288243] 'process raft request' (duration: 211.404555ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:25.693665Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10006,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T13:38:25.720235Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10006} {"level":"info","ts":"2026-05-19T13:38:25.720361Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5006} {"level":"info","ts":"2026-05-19T13:39:53.273129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7972} {"level":"info","ts":"2026-05-19T13:39:53.377486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7972,"took":"101.357669ms","hash":1368713946,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":27344896,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-19T13:39:53.37809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1368713946,"revision":7972,"compact-revision":5909} {"level":"info","ts":"2026-05-19T13:42:38.807829Z","caller":"traceutil/trace.go:171","msg":"trace[2080355275] transaction","detail":"{read_only:false; response_revision:11829; number_of_response:1; }","duration":"203.628785ms","start":"2026-05-19T13:42:38.604163Z","end":"2026-05-19T13:42:38.807792Z","steps":["trace[2080355275] 'process raft request' (duration: 183.339597ms)","trace[2080355275] 'compare' (duration: 20.115125ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:42:38.836354Z","caller":"traceutil/trace.go:171","msg":"trace[1545692185] linearizableReadLoop","detail":"{readStateIndex:12236; appliedIndex:12233; }","duration":"191.01729ms","start":"2026-05-19T13:42:38.645306Z","end":"2026-05-19T13:42:38.836323Z","steps":["trace[1545692185] 'read index received' (duration: 142.274783ms)","trace[1545692185] 'applied index is now lower than readState.Index' (duration: 48.741167ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:42:38.836411Z","caller":"traceutil/trace.go:171","msg":"trace[713554342] transaction","detail":"{read_only:false; response_revision:11830; number_of_response:1; }","duration":"231.942041ms","start":"2026-05-19T13:42:38.604412Z","end":"2026-05-19T13:42:38.836354Z","steps":["trace[713554342] 'process raft request' (duration: 231.733947ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:42:38.836478Z","caller":"traceutil/trace.go:171","msg":"trace[1033459635] transaction","detail":"{read_only:false; response_revision:11831; number_of_response:1; }","duration":"229.008305ms","start":"2026-05-19T13:42:38.607409Z","end":"2026-05-19T13:42:38.836417Z","steps":["trace[1033459635] 'process raft request' (duration: 228.868803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:42:38.836546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.152034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T13:42:38.836614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.261844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:42:38.836734Z","caller":"traceutil/trace.go:171","msg":"trace[1490049619] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:11831; }","duration":"191.481188ms","start":"2026-05-19T13:42:38.64523Z","end":"2026-05-19T13:42:38.836712Z","steps":["trace[1490049619] 'agreement among raft nodes before linearized reading' (duration: 191.222573ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:42:38.836607Z","caller":"traceutil/trace.go:171","msg":"trace[1873875619] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11831; }","duration":"155.293896ms","start":"2026-05-19T13:42:38.681298Z","end":"2026-05-19T13:42:38.836592Z","steps":["trace[1873875619] 'agreement among raft nodes before linearized reading' (duration: 155.154694ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:42:55.562209Z","caller":"traceutil/trace.go:171","msg":"trace[1875573677] transaction","detail":"{read_only:false; response_revision:11921; number_of_response:1; }","duration":"153.33358ms","start":"2026-05-19T13:42:55.408843Z","end":"2026-05-19T13:42:55.562177Z","steps":["trace[1875573677] 'process raft request' (duration: 152.880332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:42:55.562484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.762623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:42:55.562284Z","caller":"traceutil/trace.go:171","msg":"trace[1610761148] linearizableReadLoop","detail":"{readStateIndex:12330; appliedIndex:12329; }","duration":"107.315135ms","start":"2026-05-19T13:42:55.454675Z","end":"2026-05-19T13:42:55.56199Z","steps":["trace[1610761148] 'read index received' (duration: 106.876276ms)","trace[1610761148] 'applied index is now lower than readState.Index' (duration: 437.439µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:42:55.562627Z","caller":"traceutil/trace.go:171","msg":"trace[920335963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11921; }","duration":"107.981586ms","start":"2026-05-19T13:42:55.454613Z","end":"2026-05-19T13:42:55.562595Z","steps":["trace[920335963] 'agreement among raft nodes before linearized reading' (duration: 107.673571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:44:53.279753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10398} {"level":"info","ts":"2026-05-19T13:44:53.3633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10398,"took":"80.096119ms","hash":445762208,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":27766784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T13:44:53.363374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":445762208,"revision":10398,"compact-revision":7972} {"level":"warn","ts":"2026-05-19T13:49:21.712949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.809277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:49:21.713306Z","caller":"traceutil/trace.go:171","msg":"trace[393779847] transaction","detail":"{read_only:false; response_revision:14512; number_of_response:1; }","duration":"343.286645ms","start":"2026-05-19T13:49:21.369967Z","end":"2026-05-19T13:49:21.713254Z","steps":["trace[393779847] 'process raft request' (duration: 177.04827ms)","trace[393779847] 'compare' (duration: 165.510131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:49:21.713503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:49:21.369948Z","time spent":"343.454248ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:49:21.715424Z","caller":"traceutil/trace.go:171","msg":"trace[1344876727] linearizableReadLoop","detail":"{readStateIndex:15014; appliedIndex:15012; }","duration":"260.668401ms","start":"2026-05-19T13:49:21.454737Z","end":"2026-05-19T13:49:21.715405Z","steps":["trace[1344876727] 'read index received' (duration: 92.242215ms)","trace[1344876727] 'applied index is now lower than readState.Index' (duration: 168.424546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:49:21.715582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.852756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:49:21.715609Z","caller":"traceutil/trace.go:171","msg":"trace[1623325333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14512; }","duration":"260.929368ms","start":"2026-05-19T13:49:21.454671Z","end":"2026-05-19T13:49:21.7156Z","steps":["trace[1623325333] 'agreement among raft nodes before linearized reading' (duration: 260.836766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:49:21.715876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.117748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bpjnk\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-19T13:49:21.71602Z","caller":"traceutil/trace.go:171","msg":"trace[1306384845] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bpjnk; range_end:; response_count:1; response_revision:14512; }","duration":"105.306322ms","start":"2026-05-19T13:49:21.610686Z","end":"2026-05-19T13:49:21.715992Z","steps":["trace[1306384845] 'agreement among raft nodes before linearized reading' (duration: 104.989986ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:49:50.726336Z","caller":"traceutil/trace.go:171","msg":"trace[1781555428] linearizableReadLoop","detail":"{readStateIndex:15371; appliedIndex:15370; }","duration":"205.013139ms","start":"2026-05-19T13:49:50.521293Z","end":"2026-05-19T13:49:50.726306Z","steps":["trace[1781555428] 'read index received' (duration: 204.802894ms)","trace[1781555428] 'applied index is now lower than readState.Index' (duration: 209.075µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:49:50.726409Z","caller":"traceutil/trace.go:171","msg":"trace[590037536] transaction","detail":"{read_only:false; response_revision:14856; number_of_response:1; }","duration":"226.040777ms","start":"2026-05-19T13:49:50.50034Z","end":"2026-05-19T13:49:50.72638Z","steps":["trace[590037536] 'process raft request' (duration: 225.778341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:49:50.726617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.304155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d03a88b-c5be-441e-b9b6-70beeec22aa7\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-05-19T13:49:50.726663Z","caller":"traceutil/trace.go:171","msg":"trace[1737638680] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d03a88b-c5be-441e-b9b6-70beeec22aa7; range_end:; response_count:1; response_revision:14856; }","duration":"205.396046ms","start":"2026-05-19T13:49:50.521254Z","end":"2026-05-19T13:49:50.72665Z","steps":["trace[1737638680] 'agreement among raft nodes before linearized reading' (duration: 205.181122ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:49:50.884052Z","caller":"traceutil/trace.go:171","msg":"trace[571752235] transaction","detail":"{read_only:false; response_revision:14857; number_of_response:1; }","duration":"150.388395ms","start":"2026-05-19T13:49:50.733632Z","end":"2026-05-19T13:49:50.88402Z","steps":["trace[571752235] 'process raft request' (duration: 150.200822ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:49:53.286609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12729} {"level":"info","ts":"2026-05-19T13:49:53.364952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12729,"took":"75.60556ms","hash":1386636264,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":29384704,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T13:49:53.365029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1386636264,"revision":12729,"compact-revision":10398} {"level":"info","ts":"2026-05-19T13:51:43.521105Z","caller":"traceutil/trace.go:171","msg":"trace[1551175014] transaction","detail":"{read_only:false; response_revision:15801; number_of_response:1; }","duration":"111.890747ms","start":"2026-05-19T13:51:43.409156Z","end":"2026-05-19T13:51:43.521047Z","steps":["trace[1551175014] 'process raft request' (duration: 111.419597ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:51:43.703552Z","caller":"traceutil/trace.go:171","msg":"trace[1182926456] transaction","detail":"{read_only:false; response_revision:15802; number_of_response:1; }","duration":"138.805961ms","start":"2026-05-19T13:51:43.564706Z","end":"2026-05-19T13:51:43.703512Z","steps":["trace[1182926456] 'process raft request' (duration: 138.445785ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:54:53.294038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14876} {"level":"info","ts":"2026-05-19T13:54:53.39076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14876,"took":"93.004015ms","hash":641064619,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":29818880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T13:54:53.390847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":641064619,"revision":14876,"compact-revision":12729} {"level":"info","ts":"2026-05-19T13:57:40.649017Z","caller":"traceutil/trace.go:171","msg":"trace[282577645] transaction","detail":"{read_only:false; response_revision:18424; number_of_response:1; }","duration":"143.672207ms","start":"2026-05-19T13:57:40.505316Z","end":"2026-05-19T13:57:40.648988Z","steps":["trace[282577645] 'process raft request' (duration: 143.506664ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:40.670233Z","caller":"traceutil/trace.go:171","msg":"trace[145864364] linearizableReadLoop","detail":"{readStateIndex:19057; appliedIndex:19055; }","duration":"113.987764ms","start":"2026-05-19T13:57:40.556217Z","end":"2026-05-19T13:57:40.670205Z","steps":["trace[145864364] 'read index received' (duration: 92.578978ms)","trace[145864364] 'applied index is now lower than readState.Index' (duration: 21.407516ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:57:40.67035Z","caller":"traceutil/trace.go:171","msg":"trace[1423956580] transaction","detail":"{read_only:false; response_revision:18425; number_of_response:1; }","duration":"164.790818ms","start":"2026-05-19T13:57:40.505545Z","end":"2026-05-19T13:57:40.670335Z","steps":["trace[1423956580] 'process raft request' (duration: 164.561053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:57:40.670495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.251009ms","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-05-19T13:57:40.670904Z","caller":"traceutil/trace.go:171","msg":"trace[212494006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:18425; }","duration":"114.718458ms","start":"2026-05-19T13:57:40.556171Z","end":"2026-05-19T13:57:40.670889Z","steps":["trace[212494006] 'agreement among raft nodes before linearized reading' (duration: 114.266959ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:46.805619Z","caller":"traceutil/trace.go:171","msg":"trace[759321703] linearizableReadLoop","detail":"{readStateIndex:19096; appliedIndex:19095; }","duration":"105.268177ms","start":"2026-05-19T13:57:46.700321Z","end":"2026-05-19T13:57:46.805589Z","steps":["trace[759321703] 'read index received' (duration: 37.084383ms)","trace[759321703] 'applied index is now lower than readState.Index' (duration: 68.182714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:57:46.805881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.557913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T13:57:46.805924Z","caller":"traceutil/trace.go:171","msg":"trace[2047590662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18462; }","duration":"105.671185ms","start":"2026-05-19T13:57:46.700242Z","end":"2026-05-19T13:57:46.805914Z","steps":["trace[2047590662] 'agreement among raft nodes before linearized reading' (duration: 105.459662ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:57:46.806222Z","caller":"traceutil/trace.go:171","msg":"trace[648731523] transaction","detail":"{read_only:false; response_revision:18462; number_of_response:1; }","duration":"124.89319ms","start":"2026-05-19T13:57:46.681315Z","end":"2026-05-19T13:57:46.806208Z","steps":["trace[648731523] 'process raft request' (duration: 56.053623ms)","trace[648731523] 'compare' (duration: 68.015682ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:57:47.491808Z","caller":"traceutil/trace.go:171","msg":"trace[362355185] transaction","detail":"{read_only:false; response_revision:18464; number_of_response:1; }","duration":"409.194995ms","start":"2026-05-19T13:57:47.082576Z","end":"2026-05-19T13:57:47.491771Z","steps":["trace[362355185] 'process raft request' (duration: 408.972001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:57:47.492013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:57:47.082547Z","time spent":"409.361008ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:57:47.505958Z","caller":"traceutil/trace.go:171","msg":"trace[586886720] transaction","detail":"{read_only:false; response_revision:18465; number_of_response:1; }","duration":"421.046921ms","start":"2026-05-19T13:57:47.084862Z","end":"2026-05-19T13:57:47.505909Z","steps":["trace[586886720] 'process raft request' (duration: 420.649373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:57:47.506202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:57:47.084844Z","time spent":"421.225684ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:59:10.060552Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20007,"local-member-snapshot-index":10006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T13:59:10.066756Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20007} {"level":"info","ts":"2026-05-19T13:59:10.06692Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15007} {"level":"info","ts":"2026-05-19T13:59:53.301764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17339} {"level":"info","ts":"2026-05-19T13:59:53.423014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17339,"took":"110.360865ms","hash":439446591,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":34201600,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T13:59:53.423103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":439446591,"revision":17339,"compact-revision":14876} {"level":"warn","ts":"2026-05-19T14:03:22.154626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.571564ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T14:03:22.304642Z","caller":"traceutil/trace.go:171","msg":"trace[1269115058] transaction","detail":"{read_only:false; response_revision:21151; number_of_response:1; }","duration":"117.853856ms","start":"2026-05-19T14:03:22.186757Z","end":"2026-05-19T14:03:22.304611Z","steps":["trace[1269115058] 'process raft request' (duration: 117.633832ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:03:22.872855Z","caller":"traceutil/trace.go:171","msg":"trace[1233605981] linearizableReadLoop","detail":"{readStateIndex:21871; appliedIndex:21870; }","duration":"218.615768ms","start":"2026-05-19T14:03:22.654209Z","end":"2026-05-19T14:03:22.872825Z","steps":["trace[1233605981] 'read index received' (duration: 218.485076ms)","trace[1233605981] 'applied index is now lower than readState.Index' (duration: 126.242µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:03:22.872917Z","caller":"traceutil/trace.go:171","msg":"trace[1072183304] transaction","detail":"{read_only:false; response_revision:21152; number_of_response:1; }","duration":"227.206321ms","start":"2026-05-19T14:03:22.645672Z","end":"2026-05-19T14:03:22.872878Z","steps":["trace[1072183304] 'process raft request' (duration: 226.919856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:03:22.873313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.065346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:03:22.874018Z","caller":"traceutil/trace.go:171","msg":"trace[176495324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21152; }","duration":"219.2501ms","start":"2026-05-19T14:03:22.654149Z","end":"2026-05-19T14:03:22.873399Z","steps":["trace[176495324] 'agreement among raft nodes before linearized reading' (duration: 218.859212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:03:22.883046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.949433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:03:22.883173Z","caller":"traceutil/trace.go:171","msg":"trace[937902866] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:21154; }","duration":"103.161147ms","start":"2026-05-19T14:03:22.779981Z","end":"2026-05-19T14:03:22.883142Z","steps":["trace[937902866] 'agreement among raft nodes before linearized reading' (duration: 102.913932ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:03:23.019638Z","caller":"traceutil/trace.go:171","msg":"trace[328552432] transaction","detail":"{read_only:false; response_revision:21156; number_of_response:1; }","duration":"107.064803ms","start":"2026-05-19T14:03:22.912534Z","end":"2026-05-19T14:03:23.019598Z","steps":["trace[328552432] 'process raft request' (duration: 101.12815ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:03:23.637737Z","caller":"traceutil/trace.go:171","msg":"trace[303230937] linearizableReadLoop","detail":"{readStateIndex:21876; appliedIndex:21875; }","duration":"152.049195ms","start":"2026-05-19T14:03:23.48566Z","end":"2026-05-19T14:03:23.637709Z","steps":["trace[303230937] 'read index received' (duration: 151.77842ms)","trace[303230937] 'applied index is now lower than readState.Index' (duration: 269.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:03:23.637819Z","caller":"traceutil/trace.go:171","msg":"trace[584619350] transaction","detail":"{read_only:false; response_revision:21157; number_of_response:1; }","duration":"215.849736ms","start":"2026-05-19T14:03:23.421922Z","end":"2026-05-19T14:03:23.637772Z","steps":["trace[584619350] 'process raft request' (duration: 215.599031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:03:23.638081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.390272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T14:03:23.638194Z","caller":"traceutil/trace.go:171","msg":"trace[1554589980] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21157; }","duration":"152.574595ms","start":"2026-05-19T14:03:23.48559Z","end":"2026-05-19T14:03:23.638165Z","steps":["trace[1554589980] 'agreement among raft nodes before linearized reading' (duration: 152.256348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:04:53.311283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19646} {"level":"info","ts":"2026-05-19T14:04:53.421084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19646,"took":"106.884006ms","hash":3672344209,"current-db-size-bytes":56799232,"current-db-size":"57 MB","current-db-size-in-use-bytes":29929472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T14:04:53.421168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672344209,"revision":19646,"compact-revision":17339} {"level":"info","ts":"2026-05-19T14:07:22.710904Z","caller":"traceutil/trace.go:171","msg":"trace[1146133232] linearizableReadLoop","detail":"{readStateIndex:24451; appliedIndex:24450; }","duration":"163.154013ms","start":"2026-05-19T14:07:22.547719Z","end":"2026-05-19T14:07:22.710873Z","steps":["trace[1146133232] 'read index received' (duration: 162.915228ms)","trace[1146133232] 'applied index is now lower than readState.Index' (duration: 237.175µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:07:22.71108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.332577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:07:22.711059Z","caller":"traceutil/trace.go:171","msg":"trace[134546840] transaction","detail":"{read_only:false; response_revision:23656; number_of_response:1; }","duration":"219.058363ms","start":"2026-05-19T14:07:22.491953Z","end":"2026-05-19T14:07:22.711011Z","steps":["trace[134546840] 'process raft request' (duration: 218.711056ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:07:22.711123Z","caller":"traceutil/trace.go:171","msg":"trace[571717622] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:23656; }","duration":"163.46813ms","start":"2026-05-19T14:07:22.547642Z","end":"2026-05-19T14:07:22.71111Z","steps":["trace[571717622] 'agreement among raft nodes before linearized reading' (duration: 163.356718ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:07:22.72336Z","caller":"traceutil/trace.go:171","msg":"trace[1179495727] transaction","detail":"{read_only:false; response_revision:23657; number_of_response:1; }","duration":"117.392624ms","start":"2026-05-19T14:07:22.605925Z","end":"2026-05-19T14:07:22.723318Z","steps":["trace[1179495727] 'process raft request' (duration: 116.492088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:07:22.855472Z","caller":"traceutil/trace.go:171","msg":"trace[144710339] linearizableReadLoop","detail":"{readStateIndex:24453; appliedIndex:24452; }","duration":"137.331784ms","start":"2026-05-19T14:07:22.718084Z","end":"2026-05-19T14:07:22.855416Z","steps":["trace[144710339] 'read index received' (duration: 6.906971ms)","trace[144710339] 'applied index is now lower than readState.Index' (duration: 130.423243ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:07:22.855507Z","caller":"traceutil/trace.go:171","msg":"trace[2048270173] transaction","detail":"{read_only:false; response_revision:23658; number_of_response:1; }","duration":"142.839288ms","start":"2026-05-19T14:07:22.712615Z","end":"2026-05-19T14:07:22.855454Z","steps":["trace[2048270173] 'process raft request' (duration: 127.918785ms)","trace[2048270173] 'compare' (duration: 14.699959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:07:22.855763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.655169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5182"} {"level":"info","ts":"2026-05-19T14:07:22.855834Z","caller":"traceutil/trace.go:171","msg":"trace[1639582601] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:23658; }","duration":"137.819273ms","start":"2026-05-19T14:07:22.717992Z","end":"2026-05-19T14:07:22.855812Z","steps":["trace[1639582601] 'agreement among raft nodes before linearized reading' (duration: 137.581298ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:07:22.859054Z","caller":"traceutil/trace.go:171","msg":"trace[1247403] transaction","detail":"{read_only:false; response_revision:23659; number_of_response:1; }","duration":"127.057668ms","start":"2026-05-19T14:07:22.731899Z","end":"2026-05-19T14:07:22.858957Z","steps":["trace[1247403] 'process raft request' (duration: 126.695882ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:09:53.318438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21919} {"level":"info","ts":"2026-05-19T14:09:53.426127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21919,"took":"102.676627ms","hash":300475945,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":43110400,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-19T14:09:53.426194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":300475945,"revision":21919,"compact-revision":19646} {"level":"info","ts":"2026-05-19T14:10:35.642755Z","caller":"traceutil/trace.go:171","msg":"trace[1341643899] linearizableReadLoop","detail":"{readStateIndex:26456; appliedIndex:26454; }","duration":"189.896271ms","start":"2026-05-19T14:10:35.452823Z","end":"2026-05-19T14:10:35.642719Z","steps":["trace[1341643899] 'read index received' (duration: 14.76577ms)","trace[1341643899] 'applied index is now lower than readState.Index' (duration: 175.129221ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:35.642779Z","caller":"traceutil/trace.go:171","msg":"trace[974214374] transaction","detail":"{read_only:false; number_of_response:1; response_revision:25611; }","duration":"230.630132ms","start":"2026-05-19T14:10:35.412113Z","end":"2026-05-19T14:10:35.642743Z","steps":["trace[974214374] 'process raft request' (duration: 230.455599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:35.64286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.019023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:35.642948Z","caller":"traceutil/trace.go:171","msg":"trace[1873881502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25611; }","duration":"190.152165ms","start":"2026-05-19T14:10:35.452773Z","end":"2026-05-19T14:10:35.642925Z","steps":["trace[1873881502] 'agreement among raft nodes before linearized reading' (duration: 190.012153ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:35.644129Z","caller":"traceutil/trace.go:171","msg":"trace[633376695] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"142.090674ms","start":"2026-05-19T14:10:35.502013Z","end":"2026-05-19T14:10:35.644104Z","steps":["trace[633376695] 'process raft request' (duration: 141.853929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:46.536787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121833065363568,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T14:10:46.572539Z","caller":"traceutil/trace.go:171","msg":"trace[315713207] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"566.00351ms","start":"2026-05-19T14:10:46.006498Z","end":"2026-05-19T14:10:46.572502Z","steps":["trace[315713207] 'process raft request' (duration: 565.769145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:46.572772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.006454Z","time spent":"566.195413ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:46.888839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.021502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:46.889143Z","caller":"traceutil/trace.go:171","msg":"trace[84154670] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"290.10008ms","start":"2026-05-19T14:10:46.599022Z","end":"2026-05-19T14:10:46.889122Z","steps":["trace[84154670] 'process raft request' (duration: 289.977348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:46.88933Z","caller":"traceutil/trace.go:171","msg":"trace[1191700298] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26577; }","duration":"852.709446ms","start":"2026-05-19T14:10:46.036471Z","end":"2026-05-19T14:10:46.889181Z","steps":["trace[1191700298] 'read index received' (duration: 535.88987ms)","trace[1191700298] 'applied index is now lower than readState.Index' (duration: 316.812006ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:46.889374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.90576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:46.889532Z","caller":"traceutil/trace.go:171","msg":"trace[2125638868] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25731; }","duration":"853.055163ms","start":"2026-05-19T14:10:46.036407Z","end":"2026-05-19T14:10:46.889462Z","steps":["trace[2125638868] 'agreement among raft nodes before linearized reading' (duration: 852.91821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:46.889671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.036379Z","time spent":"853.252087ms","remote":"127.0.0.1:49796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"info","ts":"2026-05-19T14:10:46.889281Z","caller":"traceutil/trace.go:171","msg":"trace[926167881] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"452.757333ms","start":"2026-05-19T14:10:46.436482Z","end":"2026-05-19T14:10:46.889239Z","steps":["trace[926167881] 'process raft request' (duration: 165.210802ms)","trace[926167881] 'compare' (duration: 286.443711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:46.892284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.436443Z","time spent":"455.774322ms","remote":"127.0.0.1:41374","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-05-19T14:10:47.22059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.992468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:47.220711Z","caller":"traceutil/trace.go:171","msg":"trace[1428015061] linearizableReadLoop","detail":"{readStateIndex:26582; appliedIndex:26580; }","duration":"331.258629ms","start":"2026-05-19T14:10:46.889425Z","end":"2026-05-19T14:10:47.220684Z","steps":["trace[1428015061] 'read index received' (duration: 1.267033ms)","trace[1428015061] 'applied index is now lower than readState.Index' (duration: 329.990236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:47.220811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173400635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:47.220849Z","caller":"traceutil/trace.go:171","msg":"trace[561056957] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25733; }","duration":"1.173494867s","start":"2026-05-19T14:10:46.047343Z","end":"2026-05-19T14:10:47.220838Z","steps":["trace[561056957] 'agreement among raft nodes before linearized reading' (duration: 1.173398595s)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:47.220855Z","caller":"traceutil/trace.go:171","msg":"trace[1011541054] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"380.249938ms","start":"2026-05-19T14:10:46.840559Z","end":"2026-05-19T14:10:47.220809Z","steps":["trace[1011541054] 'process raft request' (duration: 204.928545ms)","trace[1011541054] 'compare' (duration: 174.59335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:47.220887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.047314Z","time spent":"1.173562518s","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:10:47.220924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007696853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-19T14:10:47.220931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066396636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-19T14:10:47.220917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.719604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:47.220972Z","caller":"traceutil/trace.go:171","msg":"trace[1335858875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25733; }","duration":"1.066482118s","start":"2026-05-19T14:10:46.154475Z","end":"2026-05-19T14:10:47.220957Z","steps":["trace[1335858875] 'agreement among raft nodes before linearized reading' (duration: 1.066316205s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.220998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.154449Z","time spent":"1.06654406s","remote":"127.0.0.1:41374","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-05-19T14:10:47.221008Z","caller":"traceutil/trace.go:171","msg":"trace[2023736286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25733; }","duration":"766.860627ms","start":"2026-05-19T14:10:46.454127Z","end":"2026-05-19T14:10:47.220988Z","steps":["trace[2023736286] 'agreement among raft nodes before linearized reading' (duration: 766.601883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.221028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.349024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-19T14:10:47.221024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.840521Z","time spent":"380.410021ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:47.220985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.819597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:47.221057Z","caller":"traceutil/trace.go:171","msg":"trace[1602418065] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:25733; }","duration":"703.426466ms","start":"2026-05-19T14:10:46.517622Z","end":"2026-05-19T14:10:47.221049Z","steps":["trace[1602418065] 'agreement among raft nodes before linearized reading' (duration: 703.359894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.221053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.454111Z","time spent":"766.933959ms","remote":"127.0.0.1:41098","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-05-19T14:10:47.221079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.517591Z","time spent":"703.482926ms","remote":"127.0.0.1:41116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-05-19T14:10:47.221099Z","caller":"traceutil/trace.go:171","msg":"trace[14685746] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25733; }","duration":"440.97529ms","start":"2026-05-19T14:10:46.780095Z","end":"2026-05-19T14:10:47.22107Z","steps":["trace[14685746] 'agreement among raft nodes before linearized reading' (duration: 440.708235ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:47.220961Z","caller":"traceutil/trace.go:171","msg":"trace[1829567001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25733; }","duration":"1.007776675s","start":"2026-05-19T14:10:46.213176Z","end":"2026-05-19T14:10:47.220953Z","steps":["trace[1829567001] 'agreement among raft nodes before linearized reading' (duration: 1.007561841s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.221149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.213154Z","time spent":"1.007988868s","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T14:10:47.221157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.780005Z","time spent":"441.139593ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:10:47.221119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.683661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:47.221228Z","caller":"traceutil/trace.go:171","msg":"trace[979123984] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25733; }","duration":"808.872824ms","start":"2026-05-19T14:10:46.412329Z","end":"2026-05-19T14:10:47.221202Z","steps":["trace[979123984] 'agreement among raft nodes before linearized reading' (duration: 808.682421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.221288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.149063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-19T14:10:47.221285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.412299Z","time spent":"808.973627ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"info","ts":"2026-05-19T14:10:47.221331Z","caller":"traceutil/trace.go:171","msg":"trace[53279962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25733; }","duration":"950.232954ms","start":"2026-05-19T14:10:46.271085Z","end":"2026-05-19T14:10:47.221318Z","steps":["trace[53279962] 'agreement among raft nodes before linearized reading' (duration: 950.149473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.221354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.271062Z","time spent":"950.285976ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-19T14:10:47.221327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.504484ms","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-05-19T14:10:47.221471Z","caller":"traceutil/trace.go:171","msg":"trace[2091024047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25733; }","duration":"956.760149ms","start":"2026-05-19T14:10:46.264693Z","end":"2026-05-19T14:10:47.221453Z","steps":["trace[2091024047] 'agreement among raft nodes before linearized reading' (duration: 956.041375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.221567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:46.264677Z","time spent":"956.882081ms","remote":"127.0.0.1:41374","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-05-19T14:10:47.75289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.862009ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-19T14:10:47.753063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.222144Z","time spent":"530.913125ms","remote":"127.0.0.1:41116","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-19T14:10:47.782478Z","caller":"traceutil/trace.go:171","msg":"trace[707516320] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"556.847206ms","start":"2026-05-19T14:10:47.2256Z","end":"2026-05-19T14:10:47.782447Z","steps":["trace[707516320] 'process raft request' (duration: 556.558161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.782678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.225578Z","time spent":"556.992059ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:47.782927Z","caller":"traceutil/trace.go:171","msg":"trace[789542586] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"555.905199ms","start":"2026-05-19T14:10:47.226998Z","end":"2026-05-19T14:10:47.782903Z","steps":["trace[789542586] 'process raft request' (duration: 555.657944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.783131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.226959Z","time spent":"556.055642ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:47.783119Z","caller":"traceutil/trace.go:171","msg":"trace[1009113748] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26582; }","duration":"436.983514ms","start":"2026-05-19T14:10:47.34608Z","end":"2026-05-19T14:10:47.783063Z","steps":["trace[1009113748] 'read index received' (duration: 14.991423ms)","trace[1009113748] 'applied index is now lower than readState.Index' (duration: 421.990481ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:47.783211Z","caller":"traceutil/trace.go:171","msg":"trace[1568522417] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"556.45987ms","start":"2026-05-19T14:10:47.226711Z","end":"2026-05-19T14:10:47.783171Z","steps":["trace[1568522417] 'process raft request' (duration: 555.680265ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:47.78328Z","caller":"traceutil/trace.go:171","msg":"trace[275498623] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"556.236455ms","start":"2026-05-19T14:10:47.226998Z","end":"2026-05-19T14:10:47.783234Z","steps":["trace[275498623] 'process raft request' (duration: 555.857789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.783342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.226701Z","time spent":"556.564252ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:47.783497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.226974Z","time spent":"556.428158ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:47.783533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.389221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T14:10:47.78371Z","caller":"traceutil/trace.go:171","msg":"trace[1245878533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25737; }","duration":"437.685867ms","start":"2026-05-19T14:10:47.34601Z","end":"2026-05-19T14:10:47.783695Z","steps":["trace[1245878533] 'agreement among raft nodes before linearized reading' (duration: 437.245539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:47.783755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.345981Z","time spent":"437.762759ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T14:10:48.24186Z","caller":"traceutil/trace.go:171","msg":"trace[905666153] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"552.114477ms","start":"2026-05-19T14:10:47.689714Z","end":"2026-05-19T14:10:48.241828Z","steps":["trace[905666153] 'process raft request' (duration: 552.004725ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:48.241876Z","caller":"traceutil/trace.go:171","msg":"trace[863819907] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"583.833869ms","start":"2026-05-19T14:10:47.658002Z","end":"2026-05-19T14:10:48.241836Z","steps":["trace[863819907] 'process raft request' (duration: 583.526124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.242072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.689667Z","time spent":"552.292891ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:48.242087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.657967Z","time spent":"583.999993ms","remote":"127.0.0.1:41374","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-05-19T14:10:48.242241Z","caller":"traceutil/trace.go:171","msg":"trace[2103859120] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"551.564327ms","start":"2026-05-19T14:10:47.690622Z","end":"2026-05-19T14:10:48.242186Z","steps":["trace[2103859120] 'process raft request' (duration: 551.162268ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:48.242344Z","caller":"traceutil/trace.go:171","msg":"trace[1321089743] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"550.927135ms","start":"2026-05-19T14:10:47.691393Z","end":"2026-05-19T14:10:48.24232Z","steps":["trace[1321089743] 'process raft request' (duration: 550.708891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.242444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.691382Z","time spent":"551.010476ms","remote":"127.0.0.1:41374","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-05-19T14:10:48.242544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.690586Z","time spent":"551.817021ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:48.242338Z","caller":"traceutil/trace.go:171","msg":"trace[236957030] linearizableReadLoop","detail":"{readStateIndex:26592; appliedIndex:26587; }","duration":"459.102094ms","start":"2026-05-19T14:10:47.783219Z","end":"2026-05-19T14:10:48.242321Z","steps":["trace[236957030] 'read index received' (duration: 458.40806ms)","trace[236957030] 'applied index is now lower than readState.Index' (duration: 693.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:48.24261Z","caller":"traceutil/trace.go:171","msg":"trace[1789091321] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"488.212136ms","start":"2026-05-19T14:10:47.754382Z","end":"2026-05-19T14:10:48.242594Z","steps":["trace[1789091321] 'process raft request' (duration: 487.87334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.242552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.824647ms","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":"warn","ts":"2026-05-19T14:10:48.242649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.701129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:10:48.242673Z","caller":"traceutil/trace.go:171","msg":"trace[43934824] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25742; }","duration":"882.99734ms","start":"2026-05-19T14:10:47.359661Z","end":"2026-05-19T14:10:48.242658Z","steps":["trace[43934824] 'agreement among raft nodes before linearized reading' (duration: 882.713015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.242632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.131042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:48.242701Z","caller":"traceutil/trace.go:171","msg":"trace[1204164597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25742; }","duration":"801.801381ms","start":"2026-05-19T14:10:47.440887Z","end":"2026-05-19T14:10:48.242689Z","steps":["trace[1204164597] 'agreement among raft nodes before linearized reading' (duration: 801.698729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.242717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.359631Z","time spent":"883.074512ms","remote":"127.0.0.1:41250","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-05-19T14:10:48.242741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.440855Z","time spent":"801.878402ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T14:10:48.242528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.425788ms","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-05-19T14:10:48.242768Z","caller":"traceutil/trace.go:171","msg":"trace[60143538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25742; }","duration":"787.374407ms","start":"2026-05-19T14:10:47.455371Z","end":"2026-05-19T14:10:48.242746Z","steps":["trace[60143538] 'agreement among raft nodes before linearized reading' (duration: 787.084931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.242802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.497523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:10:48.242693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.754353Z","time spent":"488.294798ms","remote":"127.0.0.1:41116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:48.242831Z","caller":"traceutil/trace.go:171","msg":"trace[47697850] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25742; }","duration":"547.777315ms","start":"2026-05-19T14:10:47.695029Z","end":"2026-05-19T14:10:48.242806Z","steps":["trace[47697850] 'agreement among raft nodes before linearized reading' (duration: 547.355547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:48.242843Z","caller":"traceutil/trace.go:171","msg":"trace[1872214047] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25742; }","duration":"486.591854ms","start":"2026-05-19T14:10:47.756238Z","end":"2026-05-19T14:10:48.242829Z","steps":["trace[1872214047] 'agreement among raft nodes before linearized reading' (duration: 486.487062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.24285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.455324Z","time spent":"787.514029ms","remote":"127.0.0.1:41098","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-05-19T14:10:48.242889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.756222Z","time spent":"486.658706ms","remote":"127.0.0.1:44334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:10:48.242901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.695002Z","time spent":"547.876926ms","remote":"127.0.0.1:39614","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-05-19T14:10:48.269635Z","caller":"traceutil/trace.go:171","msg":"trace[1781290129] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"480.870776ms","start":"2026-05-19T14:10:47.788746Z","end":"2026-05-19T14:10:48.269616Z","steps":["trace[1781290129] 'process raft request' (duration: 480.731313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.26973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.788691Z","time spent":"480.993988ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:48.269794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.418022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:48.269861Z","caller":"traceutil/trace.go:171","msg":"trace[616434126] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25743; }","duration":"303.544454ms","start":"2026-05-19T14:10:47.9663Z","end":"2026-05-19T14:10:48.269845Z","steps":["trace[616434126] 'agreement among raft nodes before linearized reading' (duration: 303.410122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:48.269906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:47.966275Z","time spent":"303.617845ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:10:48.487084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.619292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:48.487219Z","caller":"traceutil/trace.go:171","msg":"trace[489601509] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26593; }","duration":"217.613437ms","start":"2026-05-19T14:10:48.269583Z","end":"2026-05-19T14:10:48.487197Z","steps":["trace[489601509] 'read index received' (duration: 53.639188ms)","trace[489601509] 'applied index is now lower than readState.Index' (duration: 163.972749ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:48.487353Z","caller":"traceutil/trace.go:171","msg":"trace[252593563] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"238.692196ms","start":"2026-05-19T14:10:48.248647Z","end":"2026-05-19T14:10:48.487339Z","steps":["trace[252593563] 'process raft request' (duration: 74.705327ms)","trace[252593563] 'compare' (duration: 163.344086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:48.48753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.524257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:10:48.487758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.776664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9afab5f1-27cc-46b6-a01a-f051bb48c4f2\" ","response":"range_response_count:1 size:1319"} {"level":"warn","ts":"2026-05-19T14:10:48.487752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.448996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-19T14:10:48.487836Z","caller":"traceutil/trace.go:171","msg":"trace[1554495627] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9afab5f1-27cc-46b6-a01a-f051bb48c4f2; range_end:; response_count:1; response_revision:25744; }","duration":"241.914847ms","start":"2026-05-19T14:10:48.245901Z","end":"2026-05-19T14:10:48.487816Z","steps":["trace[1554495627] 'agreement among raft nodes before linearized reading' (duration: 241.630922ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:48.487851Z","caller":"traceutil/trace.go:171","msg":"trace[714208797] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25744; }","duration":"242.617729ms","start":"2026-05-19T14:10:48.245211Z","end":"2026-05-19T14:10:48.487829Z","steps":["trace[714208797] 'agreement among raft nodes before linearized reading' (duration: 242.332054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:48.487668Z","caller":"traceutil/trace.go:171","msg":"trace[1450568285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25744; }","duration":"243.724391ms","start":"2026-05-19T14:10:48.243918Z","end":"2026-05-19T14:10:48.487643Z","steps":["trace[1450568285] 'agreement among raft nodes before linearized reading' (duration: 243.479896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:49.019397Z","caller":"traceutil/trace.go:171","msg":"trace[109466512] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"120.635977ms","start":"2026-05-19T14:10:48.89874Z","end":"2026-05-19T14:10:49.019376Z","steps":["trace[109466512] 'process raft request' (duration: 113.124484ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:49.019685Z","caller":"traceutil/trace.go:171","msg":"trace[802722418] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"119.085118ms","start":"2026-05-19T14:10:48.900541Z","end":"2026-05-19T14:10:49.019626Z","steps":["trace[802722418] 'process raft request' (duration: 118.800983ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:51.10258Z","caller":"traceutil/trace.go:171","msg":"trace[474312160] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"112.438572ms","start":"2026-05-19T14:10:50.990103Z","end":"2026-05-19T14:10:51.102542Z","steps":["trace[474312160] 'process raft request' (duration: 112.179936ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:51.133842Z","caller":"traceutil/trace.go:171","msg":"trace[750541424] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"108.775873ms","start":"2026-05-19T14:10:51.025041Z","end":"2026-05-19T14:10:51.133817Z","steps":["trace[750541424] 'process raft request' (duration: 108.546029ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:51.134072Z","caller":"traceutil/trace.go:171","msg":"trace[208211015] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"106.725674ms","start":"2026-05-19T14:10:51.027298Z","end":"2026-05-19T14:10:51.134024Z","steps":["trace[208211015] 'process raft request' (duration: 106.488899ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:51.568737Z","caller":"traceutil/trace.go:171","msg":"trace[1379113280] linearizableReadLoop","detail":"{readStateIndex:26612; appliedIndex:26611; }","duration":"278.9796ms","start":"2026-05-19T14:10:51.289726Z","end":"2026-05-19T14:10:51.568706Z","steps":["trace[1379113280] 'read index received' (duration: 278.623912ms)","trace[1379113280] 'applied index is now lower than readState.Index' (duration: 354.328µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:51.568869Z","caller":"traceutil/trace.go:171","msg":"trace[915170036] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"333.443652ms","start":"2026-05-19T14:10:51.235379Z","end":"2026-05-19T14:10:51.568823Z","steps":["trace[915170036] 'process raft request' (duration: 333.118785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:51.569114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:51.235344Z","time spent":"333.619086ms","remote":"127.0.0.1:41374","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-05-19T14:10:51.569194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.449678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-05-19T14:10:51.569271Z","caller":"traceutil/trace.go:171","msg":"trace[1372599469] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25762; }","duration":"279.594451ms","start":"2026-05-19T14:10:51.289658Z","end":"2026-05-19T14:10:51.569253Z","steps":["trace[1372599469] 'agreement among raft nodes before linearized reading' (duration: 279.179833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:51.975296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.284647ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T14:10:51.975391Z","caller":"traceutil/trace.go:171","msg":"trace[1344735194] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26612; }","duration":"173.033581ms","start":"2026-05-19T14:10:51.802339Z","end":"2026-05-19T14:10:51.975373Z","steps":["trace[1344735194] 'read index received' (duration: 5.788951ms)","trace[1344735194] 'applied index is now lower than readState.Index' (duration: 167.24291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:51.975783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.408578ms","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-05-19T14:10:51.975878Z","caller":"traceutil/trace.go:171","msg":"trace[988496701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25763; }","duration":"173.572481ms","start":"2026-05-19T14:10:51.802281Z","end":"2026-05-19T14:10:51.975853Z","steps":["trace[988496701] 'agreement among raft nodes before linearized reading' (duration: 173.257855ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:52.128799Z","caller":"traceutil/trace.go:171","msg":"trace[1800012580] linearizableReadLoop","detail":"{readStateIndex:26615; appliedIndex:26614; }","duration":"153.356438ms","start":"2026-05-19T14:10:51.975413Z","end":"2026-05-19T14:10:52.128769Z","steps":["trace[1800012580] 'read index received' (duration: 152.927609ms)","trace[1800012580] 'applied index is now lower than readState.Index' (duration: 427.879µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:52.129019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.550072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T14:10:52.128999Z","caller":"traceutil/trace.go:171","msg":"trace[343242329] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"187.955104ms","start":"2026-05-19T14:10:51.940985Z","end":"2026-05-19T14:10:52.12894Z","steps":["trace[343242329] 'process raft request' (duration: 187.452244ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:52.129057Z","caller":"traceutil/trace.go:171","msg":"trace[79471553] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25764; }","duration":"326.664134ms","start":"2026-05-19T14:10:51.802379Z","end":"2026-05-19T14:10:52.129043Z","steps":["trace[79471553] 'agreement among raft nodes before linearized reading' (duration: 326.48968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.129031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.121475ms","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":"warn","ts":"2026-05-19T14:10:52.129096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:51.802346Z","time spent":"326.742075ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-19T14:10:52.129123Z","caller":"traceutil/trace.go:171","msg":"trace[1113565608] 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:25764; }","duration":"199.251448ms","start":"2026-05-19T14:10:51.929844Z","end":"2026-05-19T14:10:52.129096Z","steps":["trace[1113565608] 'agreement among raft nodes before linearized reading' (duration: 198.991983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.129128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.257155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:10:52.129163Z","caller":"traceutil/trace.go:171","msg":"trace[99817786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25764; }","duration":"326.310617ms","start":"2026-05-19T14:10:51.802843Z","end":"2026-05-19T14:10:52.129154Z","steps":["trace[99817786] 'agreement among raft nodes before linearized reading' (duration: 326.151084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.129186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:51.802834Z","time spent":"326.345647ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T14:10:52.129297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.862709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:10:52.129262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.786522ms","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-05-19T14:10:52.129376Z","caller":"traceutil/trace.go:171","msg":"trace[1052553142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25764; }","duration":"311.939154ms","start":"2026-05-19T14:10:51.81741Z","end":"2026-05-19T14:10:52.129349Z","steps":["trace[1052553142] 'agreement among raft nodes before linearized reading' (duration: 311.561687ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:52.129317Z","caller":"traceutil/trace.go:171","msg":"trace[1189488510] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25764; }","duration":"276.92371ms","start":"2026-05-19T14:10:51.852385Z","end":"2026-05-19T14:10:52.129309Z","steps":["trace[1189488510] 'agreement among raft nodes before linearized reading' (duration: 276.88249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.129451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:51.8174Z","time spent":"312.041356ms","remote":"127.0.0.1:41374","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-05-19T14:10:52.324312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.903226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:52.324591Z","caller":"traceutil/trace.go:171","msg":"trace[181484244] linearizableReadLoop","detail":"{readStateIndex:26619; appliedIndex:26615; }","duration":"188.211088ms","start":"2026-05-19T14:10:52.136361Z","end":"2026-05-19T14:10:52.324572Z","steps":["trace[181484244] 'read index received' (duration: 52.166689ms)","trace[181484244] 'applied index is now lower than readState.Index' (duration: 136.043239ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:52.324668Z","caller":"traceutil/trace.go:171","msg":"trace[1459751118] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"190.300837ms","start":"2026-05-19T14:10:52.134325Z","end":"2026-05-19T14:10:52.324626Z","steps":["trace[1459751118] 'process raft request' (duration: 190.109293ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:52.324695Z","caller":"traceutil/trace.go:171","msg":"trace[828030558] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"336.109312ms","start":"2026-05-19T14:10:51.988543Z","end":"2026-05-19T14:10:52.324652Z","steps":["trace[828030558] 'process raft request' (duration: 199.772118ms)","trace[828030558] 'compare' (duration: 135.747364ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:52.324741Z","caller":"traceutil/trace.go:171","msg":"trace[1061410750] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"190.236087ms","start":"2026-05-19T14:10:52.134496Z","end":"2026-05-19T14:10:52.324732Z","steps":["trace[1061410750] 'process raft request' (duration: 190.035032ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:52.324739Z","caller":"traceutil/trace.go:171","msg":"trace[2080368267] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"190.300927ms","start":"2026-05-19T14:10:52.134418Z","end":"2026-05-19T14:10:52.324719Z","steps":["trace[2080368267] 'process raft request' (duration: 190.067232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.324707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.340861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:10:52.324868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:51.988518Z","time spent":"336.254175ms","remote":"127.0.0.1:41374","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-05-19T14:10:52.324925Z","caller":"traceutil/trace.go:171","msg":"trace[100020708] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25768; }","duration":"188.567455ms","start":"2026-05-19T14:10:52.136313Z","end":"2026-05-19T14:10:52.32488Z","steps":["trace[100020708] 'agreement among raft nodes before linearized reading' (duration: 188.32992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.6149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.691015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:52.615016Z","caller":"traceutil/trace.go:171","msg":"trace[77430001] linearizableReadLoop","detail":"{readStateIndex:26623; appliedIndex:26622; }","duration":"261.939206ms","start":"2026-05-19T14:10:52.353056Z","end":"2026-05-19T14:10:52.614995Z","steps":["trace[77430001] 'read index received' (duration: 128.892844ms)","trace[77430001] 'applied index is now lower than readState.Index' (duration: 133.044712ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:52.61516Z","caller":"traceutil/trace.go:171","msg":"trace[1916888945] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"262.981636ms","start":"2026-05-19T14:10:52.352115Z","end":"2026-05-19T14:10:52.615097Z","steps":["trace[1916888945] 'process raft request' (duration: 129.820412ms)","trace[1916888945] 'compare' (duration: 132.140404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:52.615246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.14613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:52.615368Z","caller":"traceutil/trace.go:171","msg":"trace[288443968] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25772; }","duration":"262.317252ms","start":"2026-05-19T14:10:52.353025Z","end":"2026-05-19T14:10:52.615342Z","steps":["trace[288443968] 'agreement among raft nodes before linearized reading' (duration: 262.070998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.615373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.660556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:52.615431Z","caller":"traceutil/trace.go:171","msg":"trace[1407596295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25772; }","duration":"160.749068ms","start":"2026-05-19T14:10:52.454656Z","end":"2026-05-19T14:10:52.615405Z","steps":["trace[1407596295] 'agreement among raft nodes before linearized reading' (duration: 160.459852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:52.615506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.629734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:10:52.615596Z","caller":"traceutil/trace.go:171","msg":"trace[1860749641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25772; }","duration":"113.766337ms","start":"2026-05-19T14:10:52.501807Z","end":"2026-05-19T14:10:52.615573Z","steps":["trace[1860749641] 'agreement among raft nodes before linearized reading' (duration: 113.446721ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:52.840044Z","caller":"traceutil/trace.go:171","msg":"trace[1548493652] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"220.069603ms","start":"2026-05-19T14:10:52.619922Z","end":"2026-05-19T14:10:52.839992Z","steps":["trace[1548493652] 'process raft request' (duration: 161.174226ms)","trace[1548493652] 'compare' (duration: 58.320346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:53.364553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.655759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-05-19T14:10:53.36464Z","caller":"traceutil/trace.go:171","msg":"trace[1782489752] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25776; }","duration":"100.853402ms","start":"2026-05-19T14:10:53.263765Z","end":"2026-05-19T14:10:53.364618Z","steps":["trace[1782489752] 'range keys from in-memory index tree' (duration: 100.424824ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:54.439708Z","caller":"traceutil/trace.go:171","msg":"trace[212417074] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"106.604991ms","start":"2026-05-19T14:10:54.333077Z","end":"2026-05-19T14:10:54.439683Z","steps":["trace[212417074] 'process raft request' (duration: 106.401377ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:54.778081Z","caller":"traceutil/trace.go:171","msg":"trace[302600112] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"444.228132ms","start":"2026-05-19T14:10:54.333822Z","end":"2026-05-19T14:10:54.77805Z","steps":["trace[302600112] 'process raft request' (duration: 398.100168ms)","trace[302600112] 'compare' (duration: 45.019533ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:54.778187Z","caller":"traceutil/trace.go:171","msg":"trace[1916475203] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"444.14404ms","start":"2026-05-19T14:10:54.334009Z","end":"2026-05-19T14:10:54.778153Z","steps":["trace[1916475203] 'process raft request' (duration: 443.546748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.778229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.33381Z","time spent":"444.335934ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:54.778346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.333968Z","time spent":"444.281052ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:54.778327Z","caller":"traceutil/trace.go:171","msg":"trace[507216794] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"443.477157ms","start":"2026-05-19T14:10:54.334793Z","end":"2026-05-19T14:10:54.77827Z","steps":["trace[507216794] 'process raft request' (duration: 442.899216ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:54.778507Z","caller":"traceutil/trace.go:171","msg":"trace[1704153222] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"423.967508ms","start":"2026-05-19T14:10:54.354519Z","end":"2026-05-19T14:10:54.778487Z","steps":["trace[1704153222] 'process raft request' (duration: 423.696563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.778563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.33478Z","time spent":"443.644071ms","remote":"127.0.0.1:41374","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-05-19T14:10:54.778611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.354504Z","time spent":"424.04779ms","remote":"127.0.0.1:41374","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-05-19T14:10:54.778696Z","caller":"traceutil/trace.go:171","msg":"trace[1497827543] linearizableReadLoop","detail":"{readStateIndex:26636; appliedIndex:26630; }","duration":"325.021042ms","start":"2026-05-19T14:10:54.453645Z","end":"2026-05-19T14:10:54.778666Z","steps":["trace[1497827543] 'read index received' (duration: 278.219755ms)","trace[1497827543] 'applied index is now lower than readState.Index' (duration: 46.799757ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:54.778747Z","caller":"traceutil/trace.go:171","msg":"trace[2146101074] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"423.989339ms","start":"2026-05-19T14:10:54.35474Z","end":"2026-05-19T14:10:54.77873Z","steps":["trace[2146101074] 'process raft request' (duration: 423.830166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.778837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.188225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:54.778868Z","caller":"traceutil/trace.go:171","msg":"trace[2015792371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25785; }","duration":"325.336437ms","start":"2026-05-19T14:10:54.453524Z","end":"2026-05-19T14:10:54.778861Z","steps":["trace[2015792371] 'agreement among raft nodes before linearized reading' (duration: 325.253116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.778886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.453469Z","time spent":"325.412249ms","remote":"127.0.0.1:41108","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-05-19T14:10:54.778893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.354701Z","time spent":"424.133561ms","remote":"127.0.0.1:41374","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-05-19T14:10:54.778745Z","caller":"traceutil/trace.go:171","msg":"trace[2123032527] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"424.092891ms","start":"2026-05-19T14:10:54.354636Z","end":"2026-05-19T14:10:54.778729Z","steps":["trace[2123032527] 'process raft request' (duration: 423.803905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.779101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:54.354593Z","time spent":"424.481617ms","remote":"127.0.0.1:41374","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-05-19T14:10:54.779018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.169036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-19T14:10:54.779145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.923293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T14:10:54.779176Z","caller":"traceutil/trace.go:171","msg":"trace[758543746] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25785; }","duration":"198.363811ms","start":"2026-05-19T14:10:54.580799Z","end":"2026-05-19T14:10:54.779163Z","steps":["trace[758543746] 'agreement among raft nodes before linearized reading' (duration: 198.050744ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:54.779189Z","caller":"traceutil/trace.go:171","msg":"trace[442117509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25785; }","duration":"160.021035ms","start":"2026-05-19T14:10:54.619155Z","end":"2026-05-19T14:10:54.779176Z","steps":["trace[442117509] 'agreement among raft nodes before linearized reading' (duration: 159.911812ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:54.916778Z","caller":"traceutil/trace.go:171","msg":"trace[1377407114] linearizableReadLoop","detail":"{readStateIndex:26637; appliedIndex:26636; }","duration":"131.248308ms","start":"2026-05-19T14:10:54.785492Z","end":"2026-05-19T14:10:54.916741Z","steps":["trace[1377407114] 'read index received' (duration: 130.157587ms)","trace[1377407114] 'applied index is now lower than readState.Index' (duration: 1.089381ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:54.916919Z","caller":"traceutil/trace.go:171","msg":"trace[1676550011] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"131.9199ms","start":"2026-05-19T14:10:54.784986Z","end":"2026-05-19T14:10:54.916906Z","steps":["trace[1676550011] 'process raft request' (duration: 130.774739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.917079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.545228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-19T14:10:54.917144Z","caller":"traceutil/trace.go:171","msg":"trace[1445058588] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25786; }","duration":"127.629719ms","start":"2026-05-19T14:10:54.789496Z","end":"2026-05-19T14:10:54.917126Z","steps":["trace[1445058588] 'agreement among raft nodes before linearized reading' (duration: 127.470977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.917376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.87867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-05-19T14:10:54.917403Z","caller":"traceutil/trace.go:171","msg":"trace[652955377] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25786; }","duration":"131.91661ms","start":"2026-05-19T14:10:54.785479Z","end":"2026-05-19T14:10:54.917395Z","steps":["trace[652955377] 'agreement among raft nodes before linearized reading' (duration: 131.821669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:54.917843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.63211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128410"} {"level":"info","ts":"2026-05-19T14:10:54.917873Z","caller":"traceutil/trace.go:171","msg":"trace[1427371728] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25786; }","duration":"127.670151ms","start":"2026-05-19T14:10:54.790195Z","end":"2026-05-19T14:10:54.917865Z","steps":["trace[1427371728] 'agreement among raft nodes before linearized reading' (duration: 127.539197ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:55.079444Z","caller":"traceutil/trace.go:171","msg":"trace[1457108700] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"150.124995ms","start":"2026-05-19T14:10:54.929288Z","end":"2026-05-19T14:10:55.079413Z","steps":["trace[1457108700] 'process raft request' (duration: 108.13967ms)","trace[1457108700] 'compare' (duration: 41.352014ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:55.272541Z","caller":"traceutil/trace.go:171","msg":"trace[1373069977] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"108.036818ms","start":"2026-05-19T14:10:55.164478Z","end":"2026-05-19T14:10:55.272515Z","steps":["trace[1373069977] 'process raft request' (duration: 104.810687ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:55.294923Z","caller":"traceutil/trace.go:171","msg":"trace[1748794539] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"119.18646ms","start":"2026-05-19T14:10:55.175707Z","end":"2026-05-19T14:10:55.294893Z","steps":["trace[1748794539] 'process raft request' (duration: 119.132299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:55.295497Z","caller":"traceutil/trace.go:171","msg":"trace[1150849763] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"120.317672ms","start":"2026-05-19T14:10:55.175168Z","end":"2026-05-19T14:10:55.295485Z","steps":["trace[1150849763] 'process raft request' (duration: 119.487816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:55.533064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.158166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:10:55.533181Z","caller":"traceutil/trace.go:171","msg":"trace[1521057351] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25790; }","duration":"103.336589ms","start":"2026-05-19T14:10:55.429822Z","end":"2026-05-19T14:10:55.533158Z","steps":["trace[1521057351] 'count revisions from in-memory index tree' (duration: 103.079194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:55.533248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.619105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:55.533381Z","caller":"traceutil/trace.go:171","msg":"trace[439816996] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25790; }","duration":"131.848939ms","start":"2026-05-19T14:10:55.401499Z","end":"2026-05-19T14:10:55.533348Z","steps":["trace[439816996] 'count revisions from in-memory index tree' (duration: 131.497143ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:55.659653Z","caller":"traceutil/trace.go:171","msg":"trace[2044951646] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"121.428482ms","start":"2026-05-19T14:10:55.538203Z","end":"2026-05-19T14:10:55.659632Z","steps":["trace[2044951646] 'process raft request' (duration: 121.282099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:55.904213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.803741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:55.904439Z","caller":"traceutil/trace.go:171","msg":"trace[2021268415] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"201.682494ms","start":"2026-05-19T14:10:55.702731Z","end":"2026-05-19T14:10:55.904413Z","steps":["trace[2021268415] 'process raft request' (duration: 201.603223ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:55.904578Z","caller":"traceutil/trace.go:171","msg":"trace[626072627] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"238.015853ms","start":"2026-05-19T14:10:55.66651Z","end":"2026-05-19T14:10:55.904526Z","steps":["trace[626072627] 'process raft request' (duration: 97.768304ms)","trace[626072627] 'compare' (duration: 138.981015ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:56.722828Z","caller":"traceutil/trace.go:171","msg":"trace[1304312968] linearizableReadLoop","detail":"{readStateIndex:26645; appliedIndex:26644; }","duration":"205.048608ms","start":"2026-05-19T14:10:56.517751Z","end":"2026-05-19T14:10:56.722799Z","steps":["trace[1304312968] 'read index received' (duration: 204.732131ms)","trace[1304312968] 'applied index is now lower than readState.Index' (duration: 314.817µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:56.722914Z","caller":"traceutil/trace.go:171","msg":"trace[1936620485] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"273.253961ms","start":"2026-05-19T14:10:56.449607Z","end":"2026-05-19T14:10:56.722861Z","steps":["trace[1936620485] 'process raft request' (duration: 272.966185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:56.723235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.446435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-19T14:10:56.723319Z","caller":"traceutil/trace.go:171","msg":"trace[1404823179] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:25794; }","duration":"205.608908ms","start":"2026-05-19T14:10:56.517692Z","end":"2026-05-19T14:10:56.7233Z","steps":["trace[1404823179] 'agreement among raft nodes before linearized reading' (duration: 205.310522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:56.723211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.319944ms","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-05-19T14:10:56.723489Z","caller":"traceutil/trace.go:171","msg":"trace[485717355] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25794; }","duration":"136.713642ms","start":"2026-05-19T14:10:56.586747Z","end":"2026-05-19T14:10:56.723461Z","steps":["trace[485717355] 'agreement among raft nodes before linearized reading' (duration: 136.310074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:57.016445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.022386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:10:57.016564Z","caller":"traceutil/trace.go:171","msg":"trace[69057703] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"229.554382ms","start":"2026-05-19T14:10:56.786993Z","end":"2026-05-19T14:10:57.016548Z","steps":["trace[69057703] 'process raft request' (duration: 100.340211ms)","trace[69057703] 'compare' (duration: 128.761531ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:57.139017Z","caller":"traceutil/trace.go:171","msg":"trace[1656593176] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"351.902721ms","start":"2026-05-19T14:10:56.787074Z","end":"2026-05-19T14:10:57.138977Z","steps":["trace[1656593176] 'process raft request' (duration: 351.487964ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:57.139105Z","caller":"traceutil/trace.go:171","msg":"trace[763347939] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"351.33161ms","start":"2026-05-19T14:10:56.787747Z","end":"2026-05-19T14:10:57.139079Z","steps":["trace[763347939] 'process raft request' (duration: 350.990524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:57.139261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:56.78772Z","time spent":"351.451633ms","remote":"127.0.0.1:41116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:57.139253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:56.787055Z","time spent":"352.077174ms","remote":"127.0.0.1:41374","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-05-19T14:10:57.139384Z","caller":"traceutil/trace.go:171","msg":"trace[959725344] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"350.779519ms","start":"2026-05-19T14:10:56.788583Z","end":"2026-05-19T14:10:57.139363Z","steps":["trace[959725344] 'process raft request' (duration: 350.211769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:57.139494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:56.788558Z","time spent":"350.872262ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:57.139787Z","caller":"traceutil/trace.go:171","msg":"trace[680311744] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"348.087429ms","start":"2026-05-19T14:10:56.791691Z","end":"2026-05-19T14:10:57.139779Z","steps":["trace[680311744] 'process raft request' (duration: 347.980886ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:57.139798Z","caller":"traceutil/trace.go:171","msg":"trace[338604597] linearizableReadLoop","detail":"{readStateIndex:26653; appliedIndex:26647; }","duration":"249.240465ms","start":"2026-05-19T14:10:56.89053Z","end":"2026-05-19T14:10:57.139771Z","steps":["trace[338604597] 'read index received' (duration: 247.959471ms)","trace[338604597] 'applied index is now lower than readState.Index' (duration: 1.280034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:10:57.139831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:56.791679Z","time spent":"348.127921ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:57.139888Z","caller":"traceutil/trace.go:171","msg":"trace[1163123603] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"348.138491ms","start":"2026-05-19T14:10:56.79174Z","end":"2026-05-19T14:10:57.139878Z","steps":["trace[1163123603] 'process raft request' (duration: 347.990807ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:57.139944Z","caller":"traceutil/trace.go:171","msg":"trace[1439959706] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"348.67768ms","start":"2026-05-19T14:10:56.79126Z","end":"2026-05-19T14:10:57.139938Z","steps":["trace[1439959706] 'process raft request' (duration: 347.982876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:57.139945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:56.791724Z","time spent":"348.185401ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:57.13998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:56.791244Z","time spent":"348.714611ms","remote":"127.0.0.1:41374","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-05-19T14:10:57.140072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.550512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:10:57.14011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.399717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T14:10:57.140107Z","caller":"traceutil/trace.go:171","msg":"trace[249297774] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25801; }","duration":"249.589153ms","start":"2026-05-19T14:10:56.890506Z","end":"2026-05-19T14:10:57.140095Z","steps":["trace[249297774] 'agreement among raft nodes before linearized reading' (duration: 249.510571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:57.140127Z","caller":"traceutil/trace.go:171","msg":"trace[2032744852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25801; }","duration":"212.450658ms","start":"2026-05-19T14:10:56.927671Z","end":"2026-05-19T14:10:57.140122Z","steps":["trace[2032744852] 'agreement among raft nodes before linearized reading' (duration: 212.361137ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:59.518817Z","caller":"traceutil/trace.go:171","msg":"trace[152803969] linearizableReadLoop","detail":"{readStateIndex:26663; appliedIndex:26662; }","duration":"374.628253ms","start":"2026-05-19T14:10:59.144162Z","end":"2026-05-19T14:10:59.51879Z","steps":["trace[152803969] 'read index received' (duration: 374.49744ms)","trace[152803969] 'applied index is now lower than readState.Index' (duration: 129.462µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:59.519012Z","caller":"traceutil/trace.go:171","msg":"trace[1463602097] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"497.217276ms","start":"2026-05-19T14:10:59.021736Z","end":"2026-05-19T14:10:59.518954Z","steps":["trace[1463602097] 'process raft request' (duration: 496.871359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.519061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.876027ms","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-05-19T14:10:59.5191Z","caller":"traceutil/trace.go:171","msg":"trace[1453358098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25811; }","duration":"374.983969ms","start":"2026-05-19T14:10:59.144104Z","end":"2026-05-19T14:10:59.519088Z","steps":["trace[1453358098] 'agreement among raft nodes before linearized reading' (duration: 374.827926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.519143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.144079Z","time spent":"375.055421ms","remote":"127.0.0.1:41374","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-05-19T14:10:59.519216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.021696Z","time spent":"497.399459ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:59.800925Z","caller":"traceutil/trace.go:171","msg":"trace[396886820] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"656.213612ms","start":"2026-05-19T14:10:59.144681Z","end":"2026-05-19T14:10:59.800894Z","steps":["trace[396886820] 'process raft request' (duration: 654.914157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.801103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.144664Z","time spent":"656.344054ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:59.801118Z","caller":"traceutil/trace.go:171","msg":"trace[1132514889] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"656.188929ms","start":"2026-05-19T14:10:59.144911Z","end":"2026-05-19T14:10:59.8011Z","steps":["trace[1132514889] 'process raft request' (duration: 655.918144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.801205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.144898Z","time spent":"656.259481ms","remote":"127.0.0.1:41374","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-05-19T14:10:59.801433Z","caller":"traceutil/trace.go:171","msg":"trace[1171989341] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"516.714077ms","start":"2026-05-19T14:10:59.284709Z","end":"2026-05-19T14:10:59.801423Z","steps":["trace[1171989341] 'process raft request' (duration: 516.326159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.801495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.284652Z","time spent":"516.807109ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:10:59.801995Z","caller":"traceutil/trace.go:171","msg":"trace[687864229] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"278.266926ms","start":"2026-05-19T14:10:59.523693Z","end":"2026-05-19T14:10:59.801959Z","steps":["trace[687864229] 'process raft request' (duration: 278.214585ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:59.801991Z","caller":"traceutil/trace.go:171","msg":"trace[838643633] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"495.077137ms","start":"2026-05-19T14:10:59.30686Z","end":"2026-05-19T14:10:59.801938Z","steps":["trace[838643633] 'process raft request' (duration: 494.516315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.802249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.09805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:10:59.802312Z","caller":"traceutil/trace.go:171","msg":"trace[2111351155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25817; }","duration":"347.213943ms","start":"2026-05-19T14:10:59.455073Z","end":"2026-05-19T14:10:59.802287Z","steps":["trace[2111351155] 'agreement among raft nodes before linearized reading' (duration: 347.140232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.802327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.390188ms","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-05-19T14:10:59.802009Z","caller":"traceutil/trace.go:171","msg":"trace[1899973155] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26663; }","duration":"283.062357ms","start":"2026-05-19T14:10:59.518896Z","end":"2026-05-19T14:10:59.801959Z","steps":["trace[1899973155] 'read index received' (duration: 280.693352ms)","trace[1899973155] 'applied index is now lower than readState.Index' (duration: 2.365985ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:10:59.802409Z","caller":"traceutil/trace.go:171","msg":"trace[798020584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25817; }","duration":"652.48914ms","start":"2026-05-19T14:10:59.1499Z","end":"2026-05-19T14:10:59.802389Z","steps":["trace[798020584] 'agreement among raft nodes before linearized reading' (duration: 652.266486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.802452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.149887Z","time spent":"652.556562ms","remote":"127.0.0.1:41374","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-05-19T14:10:59.802334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.306837Z","time spent":"495.317891ms","remote":"127.0.0.1:41374","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-05-19T14:10:59.802451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.463497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-19T14:10:59.80232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.690839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:10:59.802513Z","caller":"traceutil/trace.go:171","msg":"trace[1168460795] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25817; }","duration":"655.582049ms","start":"2026-05-19T14:10:59.14691Z","end":"2026-05-19T14:10:59.802492Z","steps":["trace[1168460795] 'agreement among raft nodes before linearized reading' (duration: 655.445996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.80257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.146887Z","time spent":"655.67179ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-19T14:10:59.802572Z","caller":"traceutil/trace.go:171","msg":"trace[1132786023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25817; }","duration":"657.981735ms","start":"2026-05-19T14:10:59.144566Z","end":"2026-05-19T14:10:59.802548Z","steps":["trace[1132786023] 'agreement among raft nodes before linearized reading' (duration: 657.550667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.802351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.455035Z","time spent":"347.308984ms","remote":"127.0.0.1:41098","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-05-19T14:10:59.802718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.171135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:10:59.802752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.144556Z","time spent":"658.176278ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T14:10:59.802828Z","caller":"traceutil/trace.go:171","msg":"trace[1827091378] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25817; }","duration":"277.36879ms","start":"2026-05-19T14:10:59.525437Z","end":"2026-05-19T14:10:59.802806Z","steps":["trace[1827091378] 'agreement among raft nodes before linearized reading' (duration: 277.163135ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:10:59.802924Z","caller":"traceutil/trace.go:171","msg":"trace[293914166] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"489.43912ms","start":"2026-05-19T14:10:59.312493Z","end":"2026-05-19T14:10:59.801932Z","steps":["trace[293914166] 'process raft request' (duration: 489.323257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.803013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.312478Z","time spent":"490.486779ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:10:59.803293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.353978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T14:10:59.803547Z","caller":"traceutil/trace.go:171","msg":"trace[48020960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25817; }","duration":"653.660773ms","start":"2026-05-19T14:10:59.149864Z","end":"2026-05-19T14:10:59.803525Z","steps":["trace[48020960] 'agreement among raft nodes before linearized reading' (duration: 652.322477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:10:59.803708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:10:59.149851Z","time spent":"653.841206ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T14:11:09.654743Z","caller":"traceutil/trace.go:171","msg":"trace[534519088] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"102.179517ms","start":"2026-05-19T14:11:09.552534Z","end":"2026-05-19T14:11:09.654713Z","steps":["trace[534519088] 'process raft request' (duration: 101.966443ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:09.940046Z","caller":"traceutil/trace.go:171","msg":"trace[1658843588] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"100.832581ms","start":"2026-05-19T14:11:09.839179Z","end":"2026-05-19T14:11:09.940011Z","steps":["trace[1658843588] 'process raft request' (duration: 100.620337ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:10.209362Z","caller":"traceutil/trace.go:171","msg":"trace[658931555] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"249.328347ms","start":"2026-05-19T14:11:09.960001Z","end":"2026-05-19T14:11:10.209329Z","steps":["trace[658931555] 'process raft request' (duration: 249.276886ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:10.209417Z","caller":"traceutil/trace.go:171","msg":"trace[879222764] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"367.273072ms","start":"2026-05-19T14:11:09.842094Z","end":"2026-05-19T14:11:10.209367Z","steps":["trace[879222764] 'process raft request' (duration: 367.085149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.209603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.842086Z","time spent":"367.409695ms","remote":"127.0.0.1:41374","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-05-19T14:11:10.20967Z","caller":"traceutil/trace.go:171","msg":"trace[773322533] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"367.564198ms","start":"2026-05-19T14:11:09.842088Z","end":"2026-05-19T14:11:10.209652Z","steps":["trace[773322533] 'process raft request' (duration: 367.13427ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:10.209713Z","caller":"traceutil/trace.go:171","msg":"trace[2082673056] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"367.887894ms","start":"2026-05-19T14:11:09.841775Z","end":"2026-05-19T14:11:10.209663Z","steps":["trace[2082673056] 'process raft request' (duration: 292.396033ms)","trace[2082673056] 'compare' (duration: 74.282168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:10.209794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.84206Z","time spent":"367.641579ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:11:10.209787Z","caller":"traceutil/trace.go:171","msg":"trace[230720856] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"367.811162ms","start":"2026-05-19T14:11:09.841941Z","end":"2026-05-19T14:11:10.209752Z","steps":["trace[230720856] 'process raft request' (duration: 367.102369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.20989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.841742Z","time spent":"368.052058ms","remote":"127.0.0.1:41374","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-05-19T14:11:10.209742Z","caller":"traceutil/trace.go:171","msg":"trace[1936075878] linearizableReadLoop","detail":"{readStateIndex:26800; appliedIndex:26795; }","duration":"362.781688ms","start":"2026-05-19T14:11:09.846929Z","end":"2026-05-19T14:11:10.20971Z","steps":["trace[1936075878] 'read index received' (duration: 92.822329ms)","trace[1936075878] 'applied index is now lower than readState.Index' (duration: 269.955239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:10.209918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.988871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-217ee133-3d88-4ef6-a26b-549c1f805cda\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-19T14:11:10.209966Z","caller":"traceutil/trace.go:171","msg":"trace[2022117463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-217ee133-3d88-4ef6-a26b-549c1f805cda; range_end:; response_count:1; response_revision:25946; }","duration":"363.074363ms","start":"2026-05-19T14:11:09.846879Z","end":"2026-05-19T14:11:10.209954Z","steps":["trace[2022117463] 'agreement among raft nodes before linearized reading' (duration: 362.89772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.209971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.841934Z","time spent":"367.933404ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:11:10.210011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.846865Z","time spent":"363.138205ms","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-217ee133-3d88-4ef6-a26b-549c1f805cda\" "} {"level":"warn","ts":"2026-05-19T14:11:10.210063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.629537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:11:10.210094Z","caller":"traceutil/trace.go:171","msg":"trace[1733190852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25946; }","duration":"351.718048ms","start":"2026-05-19T14:11:09.858367Z","end":"2026-05-19T14:11:10.210085Z","steps":["trace[1733190852] 'agreement among raft nodes before linearized reading' (duration: 351.631347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.210119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.858338Z","time spent":"351.77392ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T14:11:10.21021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.761686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T14:11:10.210238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.17606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T14:11:10.210264Z","caller":"traceutil/trace.go:171","msg":"trace[156669914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25946; }","duration":"344.854228ms","start":"2026-05-19T14:11:09.865395Z","end":"2026-05-19T14:11:10.210249Z","steps":["trace[156669914] 'agreement among raft nodes before linearized reading' (duration: 344.673395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.210263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.020359ms","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-05-19T14:11:10.210281Z","caller":"traceutil/trace.go:171","msg":"trace[77336367] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25946; }","duration":"349.230652ms","start":"2026-05-19T14:11:09.861034Z","end":"2026-05-19T14:11:10.210265Z","steps":["trace[77336367] 'agreement among raft nodes before linearized reading' (duration: 349.14556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.210304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.865374Z","time spent":"344.921339ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T14:11:10.210308Z","caller":"traceutil/trace.go:171","msg":"trace[234547946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25946; }","duration":"346.0754ms","start":"2026-05-19T14:11:09.864221Z","end":"2026-05-19T14:11:10.210296Z","steps":["trace[234547946] 'agreement among raft nodes before linearized reading' (duration: 345.894217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.210313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.861025Z","time spent":"349.280212ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-19T14:11:10.210345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.864215Z","time spent":"346.122381ms","remote":"127.0.0.1:41374","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-05-19T14:11:10.210455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.621362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T14:11:10.210563Z","caller":"traceutil/trace.go:171","msg":"trace[23545274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25946; }","duration":"336.774215ms","start":"2026-05-19T14:11:09.873765Z","end":"2026-05-19T14:11:10.210539Z","steps":["trace[23545274] 'agreement among raft nodes before linearized reading' (duration: 336.415168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.210611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:09.873724Z","time spent":"336.877097ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T14:11:10.345118Z","caller":"traceutil/trace.go:171","msg":"trace[1015612085] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"128.407805ms","start":"2026-05-19T14:11:10.216681Z","end":"2026-05-19T14:11:10.345089Z","steps":["trace[1015612085] 'process raft request' (duration: 126.004119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.630738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.266579ms","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-05-19T14:11:10.630913Z","caller":"traceutil/trace.go:171","msg":"trace[1311494844] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25947; }","duration":"160.475403ms","start":"2026-05-19T14:11:10.470408Z","end":"2026-05-19T14:11:10.630883Z","steps":["trace[1311494844] 'range keys from in-memory index tree' (duration: 160.237688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.631162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.965654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:11:10.63155Z","caller":"traceutil/trace.go:171","msg":"trace[573999847] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"414.366696ms","start":"2026-05-19T14:11:10.217139Z","end":"2026-05-19T14:11:10.631506Z","steps":["trace[573999847] 'process raft request' (duration: 234.892613ms)","trace[573999847] 'compare' (duration: 178.79158ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:10.631605Z","caller":"traceutil/trace.go:171","msg":"trace[568087226] linearizableReadLoop","detail":"{readStateIndex:26807; appliedIndex:26801; }","duration":"352.831279ms","start":"2026-05-19T14:11:10.278747Z","end":"2026-05-19T14:11:10.631579Z","steps":["trace[568087226] 'read index received' (duration: 64.030494ms)","trace[568087226] 'applied index is now lower than readState.Index' (duration: 288.799235ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:10.631638Z","caller":"traceutil/trace.go:171","msg":"trace[1332705294] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"414.270724ms","start":"2026-05-19T14:11:10.217331Z","end":"2026-05-19T14:11:10.631602Z","steps":["trace[1332705294] 'process raft request' (duration: 414.106501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.631766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:10.217101Z","time spent":"414.527879ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:11:10.63185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:10.217325Z","time spent":"414.426196ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:11:10.631613Z","caller":"traceutil/trace.go:171","msg":"trace[325840879] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"414.325635ms","start":"2026-05-19T14:11:10.217241Z","end":"2026-05-19T14:11:10.631566Z","steps":["trace[325840879] 'process raft request' (duration: 414.062299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:10.631977Z","caller":"traceutil/trace.go:171","msg":"trace[557025300] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"414.677701ms","start":"2026-05-19T14:11:10.217284Z","end":"2026-05-19T14:11:10.631961Z","steps":["trace[557025300] 'process raft request' (duration: 414.09541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.632076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:10.217278Z","time spent":"414.745482ms","remote":"127.0.0.1:41374","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-05-19T14:11:10.632077Z","caller":"traceutil/trace.go:171","msg":"trace[2030561552] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"413.355436ms","start":"2026-05-19T14:11:10.218688Z","end":"2026-05-19T14:11:10.632043Z","steps":["trace[2030561552] 'process raft request' (duration: 412.811806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.632101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.163474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:11:10.632136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.862615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T14:11:10.632168Z","caller":"traceutil/trace.go:171","msg":"trace[957557767] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25952; }","duration":"279.921487ms","start":"2026-05-19T14:11:10.352235Z","end":"2026-05-19T14:11:10.632157Z","steps":["trace[957557767] 'agreement among raft nodes before linearized reading' (duration: 279.774514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.632163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.379456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-217ee133-3d88-4ef6-a26b-549c1f805cda\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-05-19T14:11:10.632213Z","caller":"traceutil/trace.go:171","msg":"trace[1209035114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25952; }","duration":"171.313018ms","start":"2026-05-19T14:11:10.460843Z","end":"2026-05-19T14:11:10.632156Z","steps":["trace[1209035114] 'agreement among raft nodes before linearized reading' (duration: 171.170195ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:10.632271Z","caller":"traceutil/trace.go:171","msg":"trace[649252200] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-217ee133-3d88-4ef6-a26b-549c1f805cda; range_end:; response_count:1; response_revision:25952; }","duration":"203.532008ms","start":"2026-05-19T14:11:10.428715Z","end":"2026-05-19T14:11:10.632247Z","steps":["trace[649252200] 'agreement among raft nodes before linearized reading' (duration: 203.312804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.632282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:10.218681Z","time spent":"413.464299ms","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:11:10.632258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:10.217235Z","time spent":"414.800854ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:11:10.632377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.634514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-05-19T14:11:10.632425Z","caller":"traceutil/trace.go:171","msg":"trace[365070160] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25952; }","duration":"353.724685ms","start":"2026-05-19T14:11:10.278684Z","end":"2026-05-19T14:11:10.632408Z","steps":["trace[365070160] 'agreement among raft nodes before linearized reading' (duration: 353.539242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.63246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:10.278657Z","time spent":"353.794776ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-05-19T14:11:10.931908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.120406ms","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-05-19T14:11:10.932015Z","caller":"traceutil/trace.go:171","msg":"trace[1104493924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25953; }","duration":"141.38969ms","start":"2026-05-19T14:11:10.790602Z","end":"2026-05-19T14:11:10.931992Z","steps":["trace[1104493924] 'range keys from in-memory index tree' (duration: 140.872781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:10.932054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.147219ms","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-05-19T14:11:10.932222Z","caller":"traceutil/trace.go:171","msg":"trace[542676951] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25953; }","duration":"196.298191ms","start":"2026-05-19T14:11:10.735849Z","end":"2026-05-19T14:11:10.932147Z","steps":["trace[542676951] 'range keys from in-memory index tree' (duration: 195.982386ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:11.231924Z","caller":"traceutil/trace.go:171","msg":"trace[1986143663] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"295.453351ms","start":"2026-05-19T14:11:10.936434Z","end":"2026-05-19T14:11:11.231888Z","steps":["trace[1986143663] 'process raft request' (duration: 252.807073ms)","trace[1986143663] 'compare' (duration: 42.501936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:17.006685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.976984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:11:17.006792Z","caller":"traceutil/trace.go:171","msg":"trace[1909783012] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26017; }","duration":"120.163418ms","start":"2026-05-19T14:11:16.886603Z","end":"2026-05-19T14:11:17.006767Z","steps":["trace[1909783012] 'count revisions from in-memory index tree' (duration: 119.836472ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:17.164503Z","caller":"traceutil/trace.go:171","msg":"trace[2041787889] linearizableReadLoop","detail":"{readStateIndex:26875; appliedIndex:26874; }","duration":"137.892125ms","start":"2026-05-19T14:11:17.026578Z","end":"2026-05-19T14:11:17.16447Z","steps":["trace[2041787889] 'read index received' (duration: 137.508827ms)","trace[2041787889] 'applied index is now lower than readState.Index' (duration: 381.768µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:17.164635Z","caller":"traceutil/trace.go:171","msg":"trace[268424706] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"146.645611ms","start":"2026-05-19T14:11:17.017941Z","end":"2026-05-19T14:11:17.164586Z","steps":["trace[268424706] 'process raft request' (duration: 146.128141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:17.164745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.137089ms","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-05-19T14:11:17.16482Z","caller":"traceutil/trace.go:171","msg":"trace[726378738] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26018; }","duration":"138.287912ms","start":"2026-05-19T14:11:17.026513Z","end":"2026-05-19T14:11:17.164801Z","steps":["trace[726378738] 'agreement among raft nodes before linearized reading' (duration: 138.134889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:17.164838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.481929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-19T14:11:17.164891Z","caller":"traceutil/trace.go:171","msg":"trace[1779758387] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26018; }","duration":"135.57169ms","start":"2026-05-19T14:11:17.029301Z","end":"2026-05-19T14:11:17.164873Z","steps":["trace[1779758387] 'agreement among raft nodes before linearized reading' (duration: 135.365417ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:17.347925Z","caller":"traceutil/trace.go:171","msg":"trace[289765205] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"174.448678ms","start":"2026-05-19T14:11:17.173436Z","end":"2026-05-19T14:11:17.347885Z","steps":["trace[289765205] 'process raft request' (duration: 174.162592ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:17.381811Z","caller":"traceutil/trace.go:171","msg":"trace[754947836] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"207.889682ms","start":"2026-05-19T14:11:17.173881Z","end":"2026-05-19T14:11:17.38177Z","steps":["trace[754947836] 'process raft request' (duration: 207.630547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:17.382225Z","caller":"traceutil/trace.go:171","msg":"trace[402268646] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"208.3302ms","start":"2026-05-19T14:11:17.173853Z","end":"2026-05-19T14:11:17.382184Z","steps":["trace[402268646] 'process raft request' (duration: 207.850191ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:17.38247Z","caller":"traceutil/trace.go:171","msg":"trace[1446817704] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"208.521714ms","start":"2026-05-19T14:11:17.17393Z","end":"2026-05-19T14:11:17.382452Z","steps":["trace[1446817704] 'process raft request' (duration: 208.134097ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:27.90659Z","caller":"traceutil/trace.go:171","msg":"trace[1128045241] linearizableReadLoop","detail":"{readStateIndex:26965; appliedIndex:26964; }","duration":"117.238214ms","start":"2026-05-19T14:11:27.789315Z","end":"2026-05-19T14:11:27.906553Z","steps":["trace[1128045241] 'read index received' (duration: 117.012139ms)","trace[1128045241] 'applied index is now lower than readState.Index' (duration: 224.445µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:27.90677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.408867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:11:27.906821Z","caller":"traceutil/trace.go:171","msg":"trace[1049629945] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26106; }","duration":"117.551599ms","start":"2026-05-19T14:11:27.789254Z","end":"2026-05-19T14:11:27.906806Z","steps":["trace[1049629945] 'agreement among raft nodes before linearized reading' (duration: 117.427127ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:27.907068Z","caller":"traceutil/trace.go:171","msg":"trace[1128969898] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"178.711358ms","start":"2026-05-19T14:11:27.728299Z","end":"2026-05-19T14:11:27.90701Z","steps":["trace[1128969898] 'process raft request' (duration: 178.024545ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:28.144828Z","caller":"traceutil/trace.go:171","msg":"trace[854810047] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"140.019425ms","start":"2026-05-19T14:11:28.004772Z","end":"2026-05-19T14:11:28.144791Z","steps":["trace[854810047] 'process raft request' (duration: 88.661461ms)","trace[854810047] 'compare' (duration: 50.691961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:28.40021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.406539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-19T14:11:28.400304Z","caller":"traceutil/trace.go:171","msg":"trace[237072480] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26107; }","duration":"143.577831ms","start":"2026-05-19T14:11:28.256698Z","end":"2026-05-19T14:11:28.400276Z","steps":["trace[237072480] 'range keys from in-memory index tree' (duration: 143.169414ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:28.40075Z","caller":"traceutil/trace.go:171","msg":"trace[741128541] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"128.049688ms","start":"2026-05-19T14:11:28.272663Z","end":"2026-05-19T14:11:28.400713Z","steps":["trace[741128541] 'process raft request' (duration: 113.89371ms)","trace[741128541] 'compare' (duration: 13.735779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:28.786271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.423648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:11:28.786566Z","caller":"traceutil/trace.go:171","msg":"trace[549106182] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"501.735122ms","start":"2026-05-19T14:11:28.284813Z","end":"2026-05-19T14:11:28.786548Z","steps":["trace[549106182] 'process raft request' (duration: 501.654921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:28.786632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.284788Z","time spent":"501.814003ms","remote":"127.0.0.1:41374","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-05-19T14:11:28.786709Z","caller":"traceutil/trace.go:171","msg":"trace[1332313374] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"506.031554ms","start":"2026-05-19T14:11:28.280642Z","end":"2026-05-19T14:11:28.786674Z","steps":["trace[1332313374] 'process raft request' (duration: 323.103296ms)","trace[1332313374] 'compare' (duration: 182.197094ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:28.786822Z","caller":"traceutil/trace.go:171","msg":"trace[912590866] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"504.356112ms","start":"2026-05-19T14:11:28.282455Z","end":"2026-05-19T14:11:28.786811Z","steps":["trace[912590866] 'process raft request' (duration: 503.909484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:28.786874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.282448Z","time spent":"504.403003ms","remote":"127.0.0.1:41374","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-05-19T14:11:28.786896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.280607Z","time spent":"506.193016ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:11:28.787085Z","caller":"traceutil/trace.go:171","msg":"trace[197159864] linearizableReadLoop","detail":"{readStateIndex:26971; appliedIndex:26967; }","duration":"370.21945ms","start":"2026-05-19T14:11:28.416857Z","end":"2026-05-19T14:11:28.787076Z","steps":["trace[197159864] 'read index received' (duration: 186.908864ms)","trace[197159864] 'applied index is now lower than readState.Index' (duration: 183.309486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:28.787314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.450994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:11:28.787376Z","caller":"traceutil/trace.go:171","msg":"trace[1013991366] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26112; }","duration":"370.542146ms","start":"2026-05-19T14:11:28.416814Z","end":"2026-05-19T14:11:28.787356Z","steps":["trace[1013991366] 'agreement among raft nodes before linearized reading' (duration: 370.449134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:28.787417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.416794Z","time spent":"370.614847ms","remote":"127.0.0.1:39640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-05-19T14:11:28.787601Z","caller":"traceutil/trace.go:171","msg":"trace[1378563219] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"502.636169ms","start":"2026-05-19T14:11:28.284952Z","end":"2026-05-19T14:11:28.787588Z","steps":["trace[1378563219] 'process raft request' (duration: 501.552929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:28.787675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.284908Z","time spent":"502.728041ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:11:28.787421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.211192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T14:11:28.78781Z","caller":"traceutil/trace.go:171","msg":"trace[630867546] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26112; }","duration":"348.628669ms","start":"2026-05-19T14:11:28.439167Z","end":"2026-05-19T14:11:28.787796Z","steps":["trace[630867546] 'agreement among raft nodes before linearized reading' (duration: 348.162371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:28.787853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.439149Z","time spent":"348.693731ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-19T14:11:28.971093Z","caller":"traceutil/trace.go:171","msg":"trace[707184955] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"178.717558ms","start":"2026-05-19T14:11:28.792334Z","end":"2026-05-19T14:11:28.971052Z","steps":["trace[707184955] 'process raft request' (duration: 178.500124ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:29.181002Z","caller":"traceutil/trace.go:171","msg":"trace[476352972] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"388.536176ms","start":"2026-05-19T14:11:28.792443Z","end":"2026-05-19T14:11:29.18098Z","steps":["trace[476352972] 'process raft request' (duration: 367.6305ms)","trace[476352972] 'compare' (duration: 20.743613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:29.181153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.792402Z","time spent":"388.676339ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:11:29.181425Z","caller":"traceutil/trace.go:171","msg":"trace[1764015842] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"384.745605ms","start":"2026-05-19T14:11:28.796641Z","end":"2026-05-19T14:11:29.181386Z","steps":["trace[1764015842] 'process raft request' (duration: 384.285276ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:29.181596Z","caller":"traceutil/trace.go:171","msg":"trace[95129269] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"384.817424ms","start":"2026-05-19T14:11:28.796771Z","end":"2026-05-19T14:11:29.181588Z","steps":["trace[95129269] 'process raft request' (duration: 384.449018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:29.181642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.796741Z","time spent":"384.877417ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:11:29.181628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.79663Z","time spent":"384.890407ms","remote":"127.0.0.1:41374","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-05-19T14:11:29.181799Z","caller":"traceutil/trace.go:171","msg":"trace[1407532441] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26971; }","duration":"338.948186ms","start":"2026-05-19T14:11:28.842844Z","end":"2026-05-19T14:11:29.181792Z","steps":["trace[1407532441] 'read index received' (duration: 128.039598ms)","trace[1407532441] 'applied index is now lower than readState.Index' (duration: 210.907868ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:29.181923Z","caller":"traceutil/trace.go:171","msg":"trace[695523616] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"384.927797ms","start":"2026-05-19T14:11:28.796989Z","end":"2026-05-19T14:11:29.181917Z","steps":["trace[695523616] 'process raft request' (duration: 384.56006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:29.181924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.070149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:11:29.181961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.796973Z","time spent":"384.967909ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:11:29.181968Z","caller":"traceutil/trace.go:171","msg":"trace[1826259000] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26117; }","duration":"339.150631ms","start":"2026-05-19T14:11:28.842803Z","end":"2026-05-19T14:11:29.181953Z","steps":["trace[1826259000] 'agreement among raft nodes before linearized reading' (duration: 339.068129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:29.182003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:11:28.84279Z","time spent":"339.204692ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":31,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:11:29.182034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.01897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:11:29.182131Z","caller":"traceutil/trace.go:171","msg":"trace[1711277136] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26117; }","duration":"182.184014ms","start":"2026-05-19T14:11:28.999922Z","end":"2026-05-19T14:11:29.182106Z","steps":["trace[1711277136] 'agreement among raft nodes before linearized reading' (duration: 181.99377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:29.497469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.981524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:11:29.497592Z","caller":"traceutil/trace.go:171","msg":"trace[1442849331] linearizableReadLoop","detail":"{readStateIndex:26977; appliedIndex:26976; }","duration":"209.985421ms","start":"2026-05-19T14:11:29.287588Z","end":"2026-05-19T14:11:29.497573Z","steps":["trace[1442849331] 'read index received' (duration: 50.64119ms)","trace[1442849331] 'applied index is now lower than readState.Index' (duration: 159.342811ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:29.497695Z","caller":"traceutil/trace.go:171","msg":"trace[1676417519] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"292.76269ms","start":"2026-05-19T14:11:29.204919Z","end":"2026-05-19T14:11:29.497682Z","steps":["trace[1676417519] 'process raft request' (duration: 133.408339ms)","trace[1676417519] 'compare' (duration: 158.864672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:29.498081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.44729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:11:29.49819Z","caller":"traceutil/trace.go:171","msg":"trace[1541622252] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26118; }","duration":"210.575322ms","start":"2026-05-19T14:11:29.287576Z","end":"2026-05-19T14:11:29.498151Z","steps":["trace[1541622252] 'agreement among raft nodes before linearized reading' (duration: 210.379798ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:30.527092Z","caller":"traceutil/trace.go:171","msg":"trace[2100051010] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"121.297229ms","start":"2026-05-19T14:11:30.405758Z","end":"2026-05-19T14:11:30.527055Z","steps":["trace[2100051010] 'process raft request' (duration: 121.099315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:33.095679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.337822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:11:33.095792Z","caller":"traceutil/trace.go:171","msg":"trace[1577180868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26142; }","duration":"100.569877ms","start":"2026-05-19T14:11:32.995198Z","end":"2026-05-19T14:11:33.095768Z","steps":["trace[1577180868] 'range keys from in-memory index tree' (duration: 100.042407ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:35.959579Z","caller":"traceutil/trace.go:171","msg":"trace[2102117530] linearizableReadLoop","detail":"{readStateIndex:27029; appliedIndex:27028; }","duration":"129.24509ms","start":"2026-05-19T14:11:35.830305Z","end":"2026-05-19T14:11:35.95955Z","steps":["trace[2102117530] 'read index received' (duration: 56.734286ms)","trace[2102117530] 'applied index is now lower than readState.Index' (duration: 72.509664ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:35.959659Z","caller":"traceutil/trace.go:171","msg":"trace[1631362507] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"140.290069ms","start":"2026-05-19T14:11:35.819329Z","end":"2026-05-19T14:11:35.959619Z","steps":["trace[1631362507] 'process raft request' (duration: 67.776325ms)","trace[1631362507] 'compare' (duration: 72.28474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:35.959874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.540445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-19T14:11:35.959946Z","caller":"traceutil/trace.go:171","msg":"trace[2102209930] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26169; }","duration":"129.672179ms","start":"2026-05-19T14:11:35.830253Z","end":"2026-05-19T14:11:35.959926Z","steps":["trace[2102209930] 'agreement among raft nodes before linearized reading' (duration: 129.425293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:36.023349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.512016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9648"} {"level":"info","ts":"2026-05-19T14:11:36.023775Z","caller":"traceutil/trace.go:171","msg":"trace[436369026] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26170; }","duration":"176.606318ms","start":"2026-05-19T14:11:35.846804Z","end":"2026-05-19T14:11:36.02341Z","steps":["trace[436369026] 'agreement among raft nodes before linearized reading' (duration: 176.392164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.409268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.869374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T14:11:41.409452Z","caller":"traceutil/trace.go:171","msg":"trace[149931656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26215; }","duration":"167.127419ms","start":"2026-05-19T14:11:41.242302Z","end":"2026-05-19T14:11:41.409429Z","steps":["trace[149931656] 'range keys from in-memory index tree' (duration: 166.64073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.409498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.141593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-19T14:11:41.409589Z","caller":"traceutil/trace.go:171","msg":"trace[1939595631] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26215; }","duration":"153.294536ms","start":"2026-05-19T14:11:41.256271Z","end":"2026-05-19T14:11:41.409565Z","steps":["trace[1939595631] 'range keys from in-memory index tree' (duration: 152.96803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.409841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.131909ms","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-05-19T14:11:41.409937Z","caller":"traceutil/trace.go:171","msg":"trace[506277984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26215; }","duration":"167.281631ms","start":"2026-05-19T14:11:41.242635Z","end":"2026-05-19T14:11:41.409917Z","steps":["trace[506277984] 'range keys from in-memory index tree' (duration: 166.841362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.410582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.919579ms","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-05-19T14:11:41.410713Z","caller":"traceutil/trace.go:171","msg":"trace[1010121242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26215; }","duration":"152.063453ms","start":"2026-05-19T14:11:41.258592Z","end":"2026-05-19T14:11:41.410656Z","steps":["trace[1010121242] 'range keys from in-memory index tree' (duration: 151.732936ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:41.6121Z","caller":"traceutil/trace.go:171","msg":"trace[569621296] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"197.018625ms","start":"2026-05-19T14:11:41.415046Z","end":"2026-05-19T14:11:41.612065Z","steps":["trace[569621296] 'process raft request' (duration: 196.960274ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:41.612221Z","caller":"traceutil/trace.go:171","msg":"trace[1711924207] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"197.117927ms","start":"2026-05-19T14:11:41.415037Z","end":"2026-05-19T14:11:41.612155Z","steps":["trace[1711924207] 'process raft request' (duration: 196.74874ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:41.698378Z","caller":"traceutil/trace.go:171","msg":"trace[639683790] linearizableReadLoop","detail":"{readStateIndex:27080; appliedIndex:27076; }","duration":"247.000842ms","start":"2026-05-19T14:11:41.45134Z","end":"2026-05-19T14:11:41.698341Z","steps":["trace[639683790] 'read index received' (duration: 160.514272ms)","trace[639683790] 'applied index is now lower than readState.Index' (duration: 86.48511ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:41.698408Z","caller":"traceutil/trace.go:171","msg":"trace[102418745] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"283.228379ms","start":"2026-05-19T14:11:41.415136Z","end":"2026-05-19T14:11:41.698364Z","steps":["trace[102418745] 'process raft request' (duration: 282.917364ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:41.698561Z","caller":"traceutil/trace.go:171","msg":"trace[1554042404] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"282.260531ms","start":"2026-05-19T14:11:41.416198Z","end":"2026-05-19T14:11:41.698458Z","steps":["trace[1554042404] 'process raft request' (duration: 282.081628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.698722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.344178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T14:11:41.698781Z","caller":"traceutil/trace.go:171","msg":"trace[166783306] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26219; }","duration":"247.459021ms","start":"2026-05-19T14:11:41.451304Z","end":"2026-05-19T14:11:41.698763Z","steps":["trace[166783306] 'agreement among raft nodes before linearized reading' (duration: 247.219746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.699353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.14462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:11:41.699452Z","caller":"traceutil/trace.go:171","msg":"trace[138035315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26219; }","duration":"243.252782ms","start":"2026-05-19T14:11:41.456172Z","end":"2026-05-19T14:11:41.699425Z","steps":["trace[138035315] 'agreement among raft nodes before linearized reading' (duration: 243.096709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:41.69968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.153537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:11:41.699773Z","caller":"traceutil/trace.go:171","msg":"trace[522582263] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26219; }","duration":"218.32596ms","start":"2026-05-19T14:11:41.481426Z","end":"2026-05-19T14:11:41.699752Z","steps":["trace[522582263] 'agreement among raft nodes before linearized reading' (duration: 218.130766ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:41.854923Z","caller":"traceutil/trace.go:171","msg":"trace[1278388183] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"149.870781ms","start":"2026-05-19T14:11:41.705011Z","end":"2026-05-19T14:11:41.854882Z","steps":["trace[1278388183] 'process raft request' (duration: 115.157202ms)","trace[1278388183] 'compare' (duration: 34.083256ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:41.854514Z","caller":"traceutil/trace.go:171","msg":"trace[1546665266] linearizableReadLoop","detail":"{readStateIndex:27081; appliedIndex:27080; }","duration":"148.000096ms","start":"2026-05-19T14:11:41.706481Z","end":"2026-05-19T14:11:41.854481Z","steps":["trace[1546665266] 'read index received' (duration: 113.587584ms)","trace[1546665266] 'applied index is now lower than readState.Index' (duration: 34.410922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:11:41.970996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.521169ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-19T14:11:41.971347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.845391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-05-19T14:11:41.971567Z","caller":"traceutil/trace.go:171","msg":"trace[1606915844] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26220; }","duration":"265.135196ms","start":"2026-05-19T14:11:41.706408Z","end":"2026-05-19T14:11:41.971543Z","steps":["trace[1606915844] 'agreement among raft nodes before linearized reading' (duration: 148.547006ms)","trace[1606915844] 'range keys from in-memory index tree' (duration: 116.221444ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:42.314757Z","caller":"traceutil/trace.go:171","msg":"trace[43495985] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"101.708818ms","start":"2026-05-19T14:11:42.21301Z","end":"2026-05-19T14:11:42.314719Z","steps":["trace[43495985] 'process raft request' (duration: 21.948066ms)","trace[43495985] 'compare' (duration: 79.522578ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:11:45.300642Z","caller":"traceutil/trace.go:171","msg":"trace[2087738052] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"115.557421ms","start":"2026-05-19T14:11:45.185051Z","end":"2026-05-19T14:11:45.300608Z","steps":["trace[2087738052] 'process raft request' (duration: 115.371547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:11:45.469547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.425131ms","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-05-19T14:11:45.469623Z","caller":"traceutil/trace.go:171","msg":"trace[1778891693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26243; }","duration":"112.524294ms","start":"2026-05-19T14:11:45.357079Z","end":"2026-05-19T14:11:45.469603Z","steps":["trace[1778891693] 'count revisions from in-memory index tree' (duration: 112.313299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:45.746627Z","caller":"traceutil/trace.go:171","msg":"trace[160345766] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"115.528139ms","start":"2026-05-19T14:11:45.631076Z","end":"2026-05-19T14:11:45.746604Z","steps":["trace[160345766] 'process raft request' (duration: 115.389977ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:45.76208Z","caller":"traceutil/trace.go:171","msg":"trace[912590746] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"129.595287ms","start":"2026-05-19T14:11:45.632453Z","end":"2026-05-19T14:11:45.762048Z","steps":["trace[912590746] 'process raft request' (duration: 129.134138ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:11:57.82724Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a4c.wal"} {"level":"info","ts":"2026-05-19T14:12:01.038412Z","caller":"traceutil/trace.go:171","msg":"trace[73970972] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"121.84694ms","start":"2026-05-19T14:12:00.916529Z","end":"2026-05-19T14:12:01.038376Z","steps":["trace[73970972] 'process raft request' (duration: 121.582125ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:01.252405Z","caller":"traceutil/trace.go:171","msg":"trace[702463346] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"335.212506ms","start":"2026-05-19T14:12:00.917153Z","end":"2026-05-19T14:12:01.252366Z","steps":["trace[702463346] 'process raft request' (duration: 293.196559ms)","trace[702463346] 'compare' (duration: 41.663881ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:12:01.252478Z","caller":"traceutil/trace.go:171","msg":"trace[2138802501] linearizableReadLoop","detail":"{readStateIndex:27235; appliedIndex:27232; }","duration":"206.041887ms","start":"2026-05-19T14:12:01.046403Z","end":"2026-05-19T14:12:01.252445Z","steps":["trace[2138802501] 'read index received' (duration: 163.966729ms)","trace[2138802501] 'applied index is now lower than readState.Index' (duration: 42.073988ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:12:01.252624Z","caller":"traceutil/trace.go:171","msg":"trace[1760178039] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"335.407999ms","start":"2026-05-19T14:12:00.917175Z","end":"2026-05-19T14:12:01.252583Z","steps":["trace[1760178039] 'process raft request' (duration: 335.118674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:12:01.252617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:12:00.917129Z","time spent":"335.383489ms","remote":"127.0.0.1:41374","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-05-19T14:12:01.252636Z","caller":"traceutil/trace.go:171","msg":"trace[2047077516] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"302.549176ms","start":"2026-05-19T14:12:00.950042Z","end":"2026-05-19T14:12:01.252591Z","steps":["trace[2047077516] 'process raft request' (duration: 302.354193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:12:01.252805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:12:00.917144Z","time spent":"335.558242ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:12:01.252936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.512995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-05-19T14:12:01.252984Z","caller":"traceutil/trace.go:171","msg":"trace[200303794] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26370; }","duration":"206.621907ms","start":"2026-05-19T14:12:01.046351Z","end":"2026-05-19T14:12:01.252973Z","steps":["trace[200303794] 'agreement among raft nodes before linearized reading' (duration: 206.20051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:12:01.252884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:12:00.950006Z","time spent":"302.832371ms","remote":"127.0.0.1:41374","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-05-19T14:12:01.323189Z","caller":"traceutil/trace.go:171","msg":"trace[445879352] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"250.616172ms","start":"2026-05-19T14:12:01.072537Z","end":"2026-05-19T14:12:01.323153Z","steps":["trace[445879352] 'process raft request' (duration: 250.358887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:12:01.496771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.820543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-19T14:12:01.496859Z","caller":"traceutil/trace.go:171","msg":"trace[426799179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26371; }","duration":"118.980757ms","start":"2026-05-19T14:12:01.377857Z","end":"2026-05-19T14:12:01.496838Z","steps":["trace[426799179] 'range keys from in-memory index tree' (duration: 118.532057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:01.880972Z","caller":"traceutil/trace.go:171","msg":"trace[1413816168] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"144.379007ms","start":"2026-05-19T14:12:01.73656Z","end":"2026-05-19T14:12:01.880939Z","steps":["trace[1413816168] 'process raft request' (duration: 144.100742ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:02.092844Z","caller":"traceutil/trace.go:171","msg":"trace[610489505] linearizableReadLoop","detail":"{readStateIndex:27239; appliedIndex:27238; }","duration":"211.947818ms","start":"2026-05-19T14:12:01.880857Z","end":"2026-05-19T14:12:02.092805Z","steps":["trace[610489505] 'read index received' (duration: 130.789339ms)","trace[610489505] 'applied index is now lower than readState.Index' (duration: 81.154259ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:12:02.093273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.961652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T14:12:02.093322Z","caller":"traceutil/trace.go:171","msg":"trace[2059712182] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26374; }","duration":"246.040303ms","start":"2026-05-19T14:12:01.847269Z","end":"2026-05-19T14:12:02.093309Z","steps":["trace[2059712182] 'agreement among raft nodes before linearized reading' (duration: 245.765188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:12:02.093397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.943625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T14:12:02.093472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.961286ms","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":"warn","ts":"2026-05-19T14:12:02.093524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.942953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-19T14:12:02.093637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.221906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T14:12:02.093671Z","caller":"traceutil/trace.go:171","msg":"trace[72809422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26374; }","duration":"130.276789ms","start":"2026-05-19T14:12:01.963363Z","end":"2026-05-19T14:12:02.09364Z","steps":["trace[72809422] 'agreement among raft nodes before linearized reading' (duration: 129.874391ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:02.093704Z","caller":"traceutil/trace.go:171","msg":"trace[812057366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26374; }","duration":"248.348718ms","start":"2026-05-19T14:12:01.845338Z","end":"2026-05-19T14:12:02.093687Z","steps":["trace[812057366] 'agreement among raft nodes before linearized reading' (duration: 247.919089ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:02.093569Z","caller":"traceutil/trace.go:171","msg":"trace[1092530601] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26374; }","duration":"253.069918ms","start":"2026-05-19T14:12:01.840485Z","end":"2026-05-19T14:12:02.093555Z","steps":["trace[1092530601] 'agreement among raft nodes before linearized reading' (duration: 252.769071ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:02.093584Z","caller":"traceutil/trace.go:171","msg":"trace[746247477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26374; }","duration":"253.222441ms","start":"2026-05-19T14:12:01.840311Z","end":"2026-05-19T14:12:02.093533Z","steps":["trace[746247477] 'agreement among raft nodes before linearized reading' (duration: 252.811743ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:02.092926Z","caller":"traceutil/trace.go:171","msg":"trace[738707628] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"207.287ms","start":"2026-05-19T14:12:01.885604Z","end":"2026-05-19T14:12:02.092891Z","steps":["trace[738707628] 'process raft request' (duration: 207.085546ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:12:06.705796Z","caller":"traceutil/trace.go:171","msg":"trace[358214395] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"140.024214ms","start":"2026-05-19T14:12:06.565729Z","end":"2026-05-19T14:12:06.705753Z","steps":["trace[358214395] 'process raft request' (duration: 67.099642ms)","trace[358214395] 'compare' (duration: 72.31095ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:12:07.19215Z","caller":"traceutil/trace.go:171","msg":"trace[141075213] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"130.154058ms","start":"2026-05-19T14:12:07.061962Z","end":"2026-05-19T14:12:07.192117Z","steps":["trace[141075213] 'process raft request' (duration: 129.939704ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:13:50.892285Z","caller":"traceutil/trace.go:171","msg":"trace[657693919] transaction","detail":"{read_only:false; response_revision:27447; number_of_response:1; }","duration":"105.781726ms","start":"2026-05-19T14:13:50.786455Z","end":"2026-05-19T14:13:50.892236Z","steps":["trace[657693919] 'process raft request' (duration: 105.415108ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:13:50.89252Z","caller":"traceutil/trace.go:171","msg":"trace[33551184] transaction","detail":"{read_only:false; response_revision:27446; number_of_response:1; }","duration":"106.111601ms","start":"2026-05-19T14:13:50.786388Z","end":"2026-05-19T14:13:50.892499Z","steps":["trace[33551184] 'process raft request' (duration: 105.304896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:14:53.324491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25157} {"level":"info","ts":"2026-05-19T14:14:53.468791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25157,"took":"137.637669ms","hash":3983362119,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":37003264,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-19T14:14:53.4689Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3983362119,"revision":25157,"compact-revision":21919} {"level":"info","ts":"2026-05-19T14:16:21.941594Z","caller":"traceutil/trace.go:171","msg":"trace[121222891] transaction","detail":"{read_only:false; response_revision:28936; number_of_response:1; }","duration":"187.875521ms","start":"2026-05-19T14:16:21.753681Z","end":"2026-05-19T14:16:21.941556Z","steps":["trace[121222891] 'process raft request' (duration: 181.051572ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:16:39.77494Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":30008,"local-member-snapshot-index":20007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:16:39.780413Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30008} {"level":"info","ts":"2026-05-19T14:16:39.780549Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25008} {"level":"info","ts":"2026-05-19T14:19:53.333456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28002} {"level":"info","ts":"2026-05-19T14:19:53.467138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28002,"took":"129.24909ms","hash":3855878951,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":32292864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:19:53.467212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3855878951,"revision":28002,"compact-revision":25157} {"level":"info","ts":"2026-05-19T14:24:53.340683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30499} {"level":"info","ts":"2026-05-19T14:24:53.478163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30499,"took":"131.610775ms","hash":1332455479,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":30359552,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T14:24:53.47824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1332455479,"revision":30499,"compact-revision":28002} {"level":"info","ts":"2026-05-19T14:29:53.348806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32754} {"level":"info","ts":"2026-05-19T14:29:53.500099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32754,"took":"144.904947ms","hash":723160349,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":30003200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T14:29:53.500164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":723160349,"revision":32754,"compact-revision":30499} {"level":"info","ts":"2026-05-19T14:34:53.35661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34984} {"level":"info","ts":"2026-05-19T14:34:53.49092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34984,"took":"125.562971ms","hash":2982373326,"current-db-size-bytes":59387904,"current-db-size":"59 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:34:53.490995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2982373326,"revision":34984,"compact-revision":32754} {"level":"info","ts":"2026-05-19T14:38:10.522553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":40009,"local-member-snapshot-index":30008,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:38:10.528421Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40009} {"level":"info","ts":"2026-05-19T14:38:10.528526Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35009} {"level":"info","ts":"2026-05-19T14:39:53.36837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37278} {"level":"info","ts":"2026-05-19T14:39:53.519252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37278,"took":"146.430507ms","hash":3154313734,"current-db-size-bytes":61087744,"current-db-size":"61 MB","current-db-size-in-use-bytes":32022528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:39:53.519323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3154313734,"revision":37278,"compact-revision":34984}