{"level":"warn","ts":"2026-09-07T10:47:27.526168Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:47:27.526339Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.250:2380","--initial-cluster=instance=https://162.253.55.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-07T10:47:27.526456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:47:27.526483Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-09-07T10:47:27.526523Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T10:47:27.527787Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"]} {"level":"info","ts":"2026-09-07T10:47:27.527932Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-07T10:47:27.532874Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.497888ms"} {"level":"info","ts":"2026-09-07T10:47:27.540986Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1e4a0bccff66c64d","cluster-id":"8ba64f6e83da771"} {"level":"info","ts":"2026-09-07T10:47:27.541052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:47:27.541077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 0"} {"level":"info","ts":"2026-09-07T10:47:27.541092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1e4a0bccff66c64d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:47:27.541101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 1"} {"level":"info","ts":"2026-09-07T10:47:27.541148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"warn","ts":"2026-09-07T10:47:27.548557Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:47:27.552356Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:47:27.554547Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-07T10:47:27.556629Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1e4a0bccff66c64d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:47:27.556816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1e4a0bccff66c64d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:47:27.556885Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T10:47:27.557004Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T10:47:27.557022Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T10:47:27.558302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"info","ts":"2026-09-07T10:47:27.558478Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","added-peer-id":"1e4a0bccff66c64d","added-peer-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-09-07T10:47:27.558609Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T10:47:27.558799Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1e4a0bccff66c64d","initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:47:27.558819Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:47:27.558916Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-09-07T10:47:27.558923Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-09-07T10:47:27.842412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:47:27.842536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:47:27.8426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgPreVoteResp from 1e4a0bccff66c64d at term 1"} {"level":"info","ts":"2026-09-07T10:47:27.842664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:47:27.842699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgVoteResp from 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-09-07T10:47:27.842756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became leader at term 2"} {"level":"info","ts":"2026-09-07T10:47:27.842793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1e4a0bccff66c64d elected leader 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-09-07T10:47:27.8438Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1e4a0bccff66c64d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.250:2379]}","request-path":"/0/members/1e4a0bccff66c64d/attributes","cluster-id":"8ba64f6e83da771","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:47:27.843901Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:47:27.84408Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:27.843946Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:47:27.844331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:47:27.844404Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:47:27.845389Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:27.845577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:27.845663Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:47:27.848142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.250:2379"} {"level":"info","ts":"2026-09-07T10:47:27.848196Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-07T10:47:42.261885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.251074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-07T10:47:42.262035Z","caller":"traceutil/trace.go:171","msg":"trace[358435443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"141.48146ms","start":"2026-09-07T10:47:42.12053Z","end":"2026-09-07T10:47:42.262011Z","steps":["trace[358435443] 'range keys from in-memory index tree' (duration: 141.147362ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:42.419718Z","caller":"traceutil/trace.go:171","msg":"trace[1785490728] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"152.738729ms","start":"2026-09-07T10:47:42.266943Z","end":"2026-09-07T10:47:42.419682Z","steps":["trace[1785490728] 'process raft request' (duration: 152.523034ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:48:06.229489Z","caller":"traceutil/trace.go:171","msg":"trace[1810988151] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"191.970986ms","start":"2026-09-07T10:48:06.037477Z","end":"2026-09-07T10:48:06.229447Z","steps":["trace[1810988151] 'process raft request' (duration: 191.674238ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:48:08.419294Z","caller":"traceutil/trace.go:171","msg":"trace[725813806] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"284.026164ms","start":"2026-09-07T10:48:08.135237Z","end":"2026-09-07T10:48:08.419263Z","steps":["trace[725813806] 'read index received' (duration: 283.653594ms)","trace[725813806] 'applied index is now lower than readState.Index' (duration: 371.06µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:48:08.419373Z","caller":"traceutil/trace.go:171","msg":"trace[1370242730] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"286.455866ms","start":"2026-09-07T10:48:08.132876Z","end":"2026-09-07T10:48:08.419331Z","steps":["trace[1370242730] 'process raft request' (duration: 286.074266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:08.419558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.002014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T10:48:08.419611Z","caller":"traceutil/trace.go:171","msg":"trace[2071692869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"182.144267ms","start":"2026-09-07T10:48:08.237455Z","end":"2026-09-07T10:48:08.419599Z","steps":["trace[2071692869] 'agreement among raft nodes before linearized reading' (duration: 182.009394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:08.419666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.401954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-09-07T10:48:08.419763Z","caller":"traceutil/trace.go:171","msg":"trace[1466521839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"284.524317ms","start":"2026-09-07T10:48:08.135217Z","end":"2026-09-07T10:48:08.419742Z","steps":["trace[1466521839] 'agreement among raft nodes before linearized reading' (duration: 284.199029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:08.420003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.763677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:48:08.420061Z","caller":"traceutil/trace.go:171","msg":"trace[436806952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"175.851029ms","start":"2026-09-07T10:48:08.244196Z","end":"2026-09-07T10:48:08.420047Z","steps":["trace[436806952] 'agreement among raft nodes before linearized reading' (duration: 175.742516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:08.420087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.683723ms","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:5"} {"level":"info","ts":"2026-09-07T10:48:08.420205Z","caller":"traceutil/trace.go:171","msg":"trace[1090019052] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"175.9044ms","start":"2026-09-07T10:48:08.244274Z","end":"2026-09-07T10:48:08.420178Z","steps":["trace[1090019052] 'agreement among raft nodes before linearized reading' (duration: 175.638684ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:48:13.832065Z","caller":"traceutil/trace.go:171","msg":"trace[704362727] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"463.839033ms","start":"2026-09-07T10:48:13.368198Z","end":"2026-09-07T10:48:13.832037Z","steps":["trace[704362727] 'read index received' (duration: 463.605616ms)","trace[704362727] 'applied index is now lower than readState.Index' (duration: 232.287µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:48:13.83214Z","caller":"traceutil/trace.go:171","msg":"trace[352021126] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"522.336302ms","start":"2026-09-07T10:48:13.30977Z","end":"2026-09-07T10:48:13.832106Z","steps":["trace[352021126] 'process raft request' (duration: 522.103167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:13.832307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.50601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f2q52\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-07T10:48:13.83235Z","caller":"traceutil/trace.go:171","msg":"trace[717869416] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f2q52; range_end:; response_count:1; response_revision:637; }","duration":"345.618472ms","start":"2026-09-07T10:48:13.48672Z","end":"2026-09-07T10:48:13.832338Z","steps":["trace[717869416] 'agreement among raft nodes before linearized reading' (duration: 345.469768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:13.832359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.16431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-07T10:48:13.83238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:48:13.486692Z","time spent":"345.680354ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-f2q52\" "} {"level":"info","ts":"2026-09-07T10:48:13.832428Z","caller":"traceutil/trace.go:171","msg":"trace[1853991494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"464.290314ms","start":"2026-09-07T10:48:13.368122Z","end":"2026-09-07T10:48:13.832412Z","steps":["trace[1853991494] 'agreement among raft nodes before linearized reading' (duration: 464.053298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:13.832465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:48:13.368094Z","time spent":"464.363195ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T10:48:13.832794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:48:13.309731Z","time spent":"522.461695ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:48:31.211303Z","caller":"traceutil/trace.go:171","msg":"trace[1711645126] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"127.741792ms","start":"2026-09-07T10:48:31.083529Z","end":"2026-09-07T10:48:31.211271Z","steps":["trace[1711645126] 'read index received' (duration: 127.433254ms)","trace[1711645126] 'applied index is now lower than readState.Index' (duration: 307.258µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:48:31.211532Z","caller":"traceutil/trace.go:171","msg":"trace[238410846] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"156.078627ms","start":"2026-09-07T10:48:31.055426Z","end":"2026-09-07T10:48:31.211505Z","steps":["trace[238410846] 'process raft request' (duration: 155.571355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:31.211677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.109771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T10:48:31.211773Z","caller":"traceutil/trace.go:171","msg":"trace[595158634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"128.326626ms","start":"2026-09-07T10:48:31.083425Z","end":"2026-09-07T10:48:31.211752Z","steps":["trace[595158634] 'agreement among raft nodes before linearized reading' (duration: 128.029698ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:46.466407Z","caller":"traceutil/trace.go:171","msg":"trace[1920441502] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"399.33751ms","start":"2026-09-07T10:49:46.067028Z","end":"2026-09-07T10:49:46.466365Z","steps":["trace[1920441502] 'process raft request' (duration: 399.068993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:46.466898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:46.066987Z","time spent":"399.549246ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10932,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:49:46.495378Z","caller":"traceutil/trace.go:171","msg":"trace[2672761] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1936; }","duration":"251.876063ms","start":"2026-09-07T10:49:46.24348Z","end":"2026-09-07T10:49:46.495356Z","steps":["trace[2672761] 'read index received' (duration: 222.614804ms)","trace[2672761] 'applied index is now lower than readState.Index' (duration: 29.260309ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:46.495513Z","caller":"traceutil/trace.go:171","msg":"trace[954178721] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"306.765561ms","start":"2026-09-07T10:49:46.188711Z","end":"2026-09-07T10:49:46.495477Z","steps":["trace[954178721] 'process raft request' (duration: 306.513085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:46.495584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.077928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:49:46.495624Z","caller":"traceutil/trace.go:171","msg":"trace[177547814] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1868; }","duration":"252.181941ms","start":"2026-09-07T10:49:46.24343Z","end":"2026-09-07T10:49:46.495612Z","steps":["trace[177547814] 'agreement among raft nodes before linearized reading' (duration: 252.038467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:46.495677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:46.188689Z","time spent":"306.882654ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:49:46.629206Z","caller":"traceutil/trace.go:171","msg":"trace[1267387295] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"156.325052ms","start":"2026-09-07T10:49:46.472836Z","end":"2026-09-07T10:49:46.629161Z","steps":["trace[1267387295] 'process raft request' (duration: 110.33229ms)","trace[1267387295] 'compare' (duration: 45.674815ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:46.629602Z","caller":"traceutil/trace.go:171","msg":"trace[1522261074] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"156.720241ms","start":"2026-09-07T10:49:46.472859Z","end":"2026-09-07T10:49:46.629579Z","steps":["trace[1522261074] 'process raft request' (duration: 156.23951ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:46.62969Z","caller":"traceutil/trace.go:171","msg":"trace[2066449764] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1938; }","duration":"134.207596ms","start":"2026-09-07T10:49:46.495451Z","end":"2026-09-07T10:49:46.629659Z","steps":["trace[2066449764] 'read index received' (duration: 87.709762ms)","trace[2066449764] 'applied index is now lower than readState.Index' (duration: 46.496563ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:46.629731Z","caller":"traceutil/trace.go:171","msg":"trace[122009097] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"155.997513ms","start":"2026-09-07T10:49:46.473695Z","end":"2026-09-07T10:49:46.629692Z","steps":["trace[122009097] 'process raft request' (duration: 155.903321ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:46.629847Z","caller":"traceutil/trace.go:171","msg":"trace[732067293] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"156.837054ms","start":"2026-09-07T10:49:46.472976Z","end":"2026-09-07T10:49:46.629813Z","steps":["trace[732067293] 'process raft request' (duration: 156.446884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:46.629914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.960519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-07T10:49:46.629926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.49266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa4bc9ef-919c-4c2b-b097-d3661ffa04b0\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-07T10:49:46.629922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.518457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-07T10:49:46.629972Z","caller":"traceutil/trace.go:171","msg":"trace[545708460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1872; }","duration":"279.06235ms","start":"2026-09-07T10:49:46.350893Z","end":"2026-09-07T10:49:46.629956Z","steps":["trace[545708460] 'agreement among raft nodes before linearized reading' (duration: 278.806653ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:46.629996Z","caller":"traceutil/trace.go:171","msg":"trace[1645051791] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1872; }","duration":"297.65476ms","start":"2026-09-07T10:49:46.332323Z","end":"2026-09-07T10:49:46.629978Z","steps":["trace[1645051791] 'agreement among raft nodes before linearized reading' (duration: 297.464255ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:46.629996Z","caller":"traceutil/trace.go:171","msg":"trace[195511706] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa4bc9ef-919c-4c2b-b097-d3661ffa04b0; range_end:; response_count:1; response_revision:1872; }","duration":"157.581502ms","start":"2026-09-07T10:49:46.472393Z","end":"2026-09-07T10:49:46.629974Z","steps":["trace[195511706] 'agreement among raft nodes before linearized reading' (duration: 157.299484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:47.163228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.215843ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-07T10:49:47.163702Z","caller":"traceutil/trace.go:171","msg":"trace[531766588] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"302.397315ms","start":"2026-09-07T10:49:46.861245Z","end":"2026-09-07T10:49:47.163642Z","steps":["trace[531766588] 'read index received' (duration: 87.003µs)","trace[531766588] 'applied index is now lower than readState.Index' (duration: 302.306622ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:47.163983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.704744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:49:47.164088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.276613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-07T10:49:47.164088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.227587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa4bc9ef-919c-4c2b-b097-d3661ffa04b0\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-07T10:49:47.16412Z","caller":"traceutil/trace.go:171","msg":"trace[474607794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"302.922058ms","start":"2026-09-07T10:49:46.861169Z","end":"2026-09-07T10:49:47.164091Z","steps":["trace[474607794] 'agreement among raft nodes before linearized reading' (duration: 302.641052ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:47.164145Z","caller":"traceutil/trace.go:171","msg":"trace[415741049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1875; }","duration":"197.386145ms","start":"2026-09-07T10:49:46.96674Z","end":"2026-09-07T10:49:47.164126Z","steps":["trace[415741049] 'agreement among raft nodes before linearized reading' (duration: 197.15069ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:47.164148Z","caller":"traceutil/trace.go:171","msg":"trace[1985351136] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa4bc9ef-919c-4c2b-b097-d3661ffa04b0; range_end:; response_count:1; response_revision:1875; }","duration":"246.37116ms","start":"2026-09-07T10:49:46.917763Z","end":"2026-09-07T10:49:47.164134Z","steps":["trace[1985351136] 'agreement among raft nodes before linearized reading' (duration: 246.078964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:47.164188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:46.861138Z","time spent":"303.033831ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T10:50:02.35874Z","caller":"traceutil/trace.go:171","msg":"trace[367900568] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"181.812107ms","start":"2026-09-07T10:50:02.176901Z","end":"2026-09-07T10:50:02.358713Z","steps":["trace[367900568] 'process raft request' (duration: 181.536401ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:35.837246Z","caller":"traceutil/trace.go:171","msg":"trace[1395115371] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"214.475006ms","start":"2026-09-07T10:50:35.62274Z","end":"2026-09-07T10:50:35.837215Z","steps":["trace[1395115371] 'process raft request' (duration: 214.22694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:36.114521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.290434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:50:36.114767Z","caller":"traceutil/trace.go:171","msg":"trace[1576866015] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2266; }","duration":"253.749868ms","start":"2026-09-07T10:50:35.860995Z","end":"2026-09-07T10:50:36.114745Z","steps":["trace[1576866015] 'read index received' (duration: 101.073634ms)","trace[1576866015] 'applied index is now lower than readState.Index' (duration: 152.674924ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:36.114776Z","caller":"traceutil/trace.go:171","msg":"trace[1485038422] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"381.643964ms","start":"2026-09-07T10:50:35.733079Z","end":"2026-09-07T10:50:36.114723Z","steps":["trace[1485038422] 'process raft request' (duration: 229.015181ms)","trace[1485038422] 'compare' (duration: 151.926054ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:36.114873Z","caller":"traceutil/trace.go:171","msg":"trace[1412492543] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"368.414443ms","start":"2026-09-07T10:50:35.746415Z","end":"2026-09-07T10:50:36.114829Z","steps":["trace[1412492543] 'process raft request' (duration: 368.233898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:36.114951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.938684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:50:36.114976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:50:35.733047Z","time spent":"381.819738ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:50:36.115042Z","caller":"traceutil/trace.go:171","msg":"trace[1010028733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"254.110717ms","start":"2026-09-07T10:50:35.860907Z","end":"2026-09-07T10:50:36.115017Z","steps":["trace[1010028733] 'agreement among raft nodes before linearized reading' (duration: 253.946123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:36.115067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:50:35.746372Z","time spent":"368.588908ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:50:36.115207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.716099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-07T10:50:36.115266Z","caller":"traceutil/trace.go:171","msg":"trace[287702244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2188; }","duration":"243.860483ms","start":"2026-09-07T10:50:35.871388Z","end":"2026-09-07T10:50:36.115248Z","steps":["trace[287702244] 'agreement among raft nodes before linearized reading' (duration: 243.557475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:36.115237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.133413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-07T10:50:36.115322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.442857ms","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-09-07T10:50:36.115387Z","caller":"traceutil/trace.go:171","msg":"trace[2114426599] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2188; }","duration":"242.508149ms","start":"2026-09-07T10:50:35.872858Z","end":"2026-09-07T10:50:36.115366Z","steps":["trace[2114426599] 'agreement among raft nodes before linearized reading' (duration: 242.17193ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:36.115339Z","caller":"traceutil/trace.go:171","msg":"trace[1539012978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2188; }","duration":"229.256257ms","start":"2026-09-07T10:50:35.886063Z","end":"2026-09-07T10:50:36.115319Z","steps":["trace[1539012978] 'agreement among raft nodes before linearized reading' (duration: 228.938569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:36.116411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.500248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-07T10:50:36.116471Z","caller":"traceutil/trace.go:171","msg":"trace[130706587] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"131.628042ms","start":"2026-09-07T10:50:35.984828Z","end":"2026-09-07T10:50:36.116456Z","steps":["trace[130706587] 'agreement among raft nodes before linearized reading' (duration: 130.439671ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:36.351471Z","caller":"traceutil/trace.go:171","msg":"trace[337925909] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"229.73907ms","start":"2026-09-07T10:50:36.121702Z","end":"2026-09-07T10:50:36.351441Z","steps":["trace[337925909] 'process raft request' (duration: 229.650427ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:36.351486Z","caller":"traceutil/trace.go:171","msg":"trace[462987595] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"229.759419ms","start":"2026-09-07T10:50:36.121672Z","end":"2026-09-07T10:50:36.351432Z","steps":["trace[462987595] 'process raft request' (duration: 204.369145ms)","trace[462987595] 'compare' (duration: 25.121238ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:36.351664Z","caller":"traceutil/trace.go:171","msg":"trace[1777382573] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2268; }","duration":"132.577916ms","start":"2026-09-07T10:50:36.219051Z","end":"2026-09-07T10:50:36.351629Z","steps":["trace[1777382573] 'read index received' (duration: 107.11043ms)","trace[1777382573] 'applied index is now lower than readState.Index' (duration: 25.465286ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:36.351748Z","caller":"traceutil/trace.go:171","msg":"trace[1983034967] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"229.719829ms","start":"2026-09-07T10:50:36.122006Z","end":"2026-09-07T10:50:36.351725Z","steps":["trace[1983034967] 'process raft request' (duration: 229.396631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:36.352047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.972327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T10:50:36.352139Z","caller":"traceutil/trace.go:171","msg":"trace[1360567475] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2191; }","duration":"133.11001ms","start":"2026-09-07T10:50:36.218997Z","end":"2026-09-07T10:50:36.352107Z","steps":["trace[1360567475] 'agreement among raft nodes before linearized reading' (duration: 132.792631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:02.697604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.944672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:51:02.697778Z","caller":"traceutil/trace.go:171","msg":"trace[1263570762] linearizableReadLoop","detail":"{readStateIndex:2442; appliedIndex:2441; }","duration":"145.363754ms","start":"2026-09-07T10:51:02.552344Z","end":"2026-09-07T10:51:02.697707Z","steps":["trace[1263570762] 'read index received' (duration: 9.138845ms)","trace[1263570762] 'applied index is now lower than readState.Index' (duration: 136.223389ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:02.698001Z","caller":"traceutil/trace.go:171","msg":"trace[13637966] transaction","detail":"{read_only:false; response_revision:2355; number_of_response:1; }","duration":"193.509137ms","start":"2026-09-07T10:51:02.504433Z","end":"2026-09-07T10:51:02.697942Z","steps":["trace[13637966] 'process raft request' (duration: 57.141783ms)","trace[13637966] 'compare' (duration: 135.694636ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:02.69815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.800187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-07T10:51:02.698222Z","caller":"traceutil/trace.go:171","msg":"trace[1441073753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2355; }","duration":"145.905469ms","start":"2026-09-07T10:51:02.5523Z","end":"2026-09-07T10:51:02.698206Z","steps":["trace[1441073753] 'agreement among raft nodes before linearized reading' (duration: 145.715125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:02.69829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.035815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T10:51:02.698332Z","caller":"traceutil/trace.go:171","msg":"trace[1572202509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2355; }","duration":"129.124407ms","start":"2026-09-07T10:51:02.569193Z","end":"2026-09-07T10:51:02.698318Z","steps":["trace[1572202509] 'agreement among raft nodes before linearized reading' (duration: 128.920322ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:03.321798Z","caller":"traceutil/trace.go:171","msg":"trace[858542998] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"119.042917ms","start":"2026-09-07T10:51:03.202722Z","end":"2026-09-07T10:51:03.321765Z","steps":["trace[858542998] 'process raft request' (duration: 67.252642ms)","trace[858542998] 'compare' (duration: 51.524749ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:03.502887Z","caller":"traceutil/trace.go:171","msg":"trace[53515129] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2391; }","duration":"111.789341ms","start":"2026-09-07T10:51:03.391067Z","end":"2026-09-07T10:51:03.502857Z","steps":["trace[53515129] 'process raft request' (duration: 111.709989ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:03.502957Z","caller":"traceutil/trace.go:171","msg":"trace[1569294404] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"114.059909ms","start":"2026-09-07T10:51:03.38886Z","end":"2026-09-07T10:51:03.50292Z","steps":["trace[1569294404] 'process raft request' (duration: 68.250179ms)","trace[1569294404] 'compare' (duration: 45.507453ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:03.647921Z","caller":"traceutil/trace.go:171","msg":"trace[940644163] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2401; }","duration":"116.148872ms","start":"2026-09-07T10:51:03.531747Z","end":"2026-09-07T10:51:03.647896Z","steps":["trace[940644163] 'process raft request' (duration: 111.463332ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:03.648068Z","caller":"traceutil/trace.go:171","msg":"trace[1503662625] linearizableReadLoop","detail":"{readStateIndex:2492; appliedIndex:2483; }","duration":"109.754898ms","start":"2026-09-07T10:51:03.538285Z","end":"2026-09-07T10:51:03.64804Z","steps":["trace[1503662625] 'read index received' (duration: 41.377056ms)","trace[1503662625] 'applied index is now lower than readState.Index' (duration: 68.376272ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:03.648162Z","caller":"traceutil/trace.go:171","msg":"trace[846834162] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"116.318556ms","start":"2026-09-07T10:51:03.531808Z","end":"2026-09-07T10:51:03.648126Z","steps":["trace[846834162] 'process raft request' (duration: 116.015359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:03.6483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.979764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/valkey-ca\" ","response":"range_response_count:1 size:2410"} {"level":"info","ts":"2026-09-07T10:51:03.648361Z","caller":"traceutil/trace.go:171","msg":"trace[556895376] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/valkey-ca; range_end:; response_count:1; response_revision:2402; }","duration":"110.105968ms","start":"2026-09-07T10:51:03.538242Z","end":"2026-09-07T10:51:03.648347Z","steps":["trace[556895376] 'agreement among raft nodes before linearized reading' (duration: 109.882721ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:15.958038Z","caller":"traceutil/trace.go:171","msg":"trace[1300528931] linearizableReadLoop","detail":"{readStateIndex:2676; appliedIndex:2675; }","duration":"203.11707ms","start":"2026-09-07T10:51:15.7549Z","end":"2026-09-07T10:51:15.958017Z","steps":["trace[1300528931] 'read index received' (duration: 202.936456ms)","trace[1300528931] 'applied index is now lower than readState.Index' (duration: 179.744µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:15.95813Z","caller":"traceutil/trace.go:171","msg":"trace[139669855] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"205.511687ms","start":"2026-09-07T10:51:15.752575Z","end":"2026-09-07T10:51:15.958087Z","steps":["trace[139669855] 'process raft request' (duration: 205.328093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:15.958258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.85802ms","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-09-07T10:51:15.958172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.255704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:51:15.95829Z","caller":"traceutil/trace.go:171","msg":"trace[352577588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2580; }","duration":"130.926401ms","start":"2026-09-07T10:51:15.827357Z","end":"2026-09-07T10:51:15.958284Z","steps":["trace[352577588] 'agreement among raft nodes before linearized reading' (duration: 130.864969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:15.958265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.954179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:51:15.958336Z","caller":"traceutil/trace.go:171","msg":"trace[1719354827] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2580; }","duration":"128.073871ms","start":"2026-09-07T10:51:15.830245Z","end":"2026-09-07T10:51:15.958319Z","steps":["trace[1719354827] 'agreement among raft nodes before linearized reading' (duration: 127.941018ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:15.958328Z","caller":"traceutil/trace.go:171","msg":"trace[160470102] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2580; }","duration":"203.450718ms","start":"2026-09-07T10:51:15.754858Z","end":"2026-09-07T10:51:15.958309Z","steps":["trace[160470102] 'agreement among raft nodes before linearized reading' (duration: 203.271594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:15.958424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.222491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T10:51:15.958512Z","caller":"traceutil/trace.go:171","msg":"trace[558895649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2580; }","duration":"153.354016ms","start":"2026-09-07T10:51:15.80515Z","end":"2026-09-07T10:51:15.958504Z","steps":["trace[558895649] 'agreement among raft nodes before linearized reading' (duration: 153.010327ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:44.303457Z","caller":"traceutil/trace.go:171","msg":"trace[1933649612] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"108.269522ms","start":"2026-09-07T10:51:44.195162Z","end":"2026-09-07T10:51:44.303431Z","steps":["trace[1933649612] 'read index received' (duration: 108.138449ms)","trace[1933649612] 'applied index is now lower than readState.Index' (duration: 130.243µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:44.303641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.467288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T10:51:44.303677Z","caller":"traceutil/trace.go:171","msg":"trace[28489275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2755; }","duration":"108.55463ms","start":"2026-09-07T10:51:44.195113Z","end":"2026-09-07T10:51:44.303667Z","steps":["trace[28489275] 'agreement among raft nodes before linearized reading' (duration: 108.414586ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:44.303571Z","caller":"traceutil/trace.go:171","msg":"trace[2019485978] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"126.020642ms","start":"2026-09-07T10:51:44.177499Z","end":"2026-09-07T10:51:44.303519Z","steps":["trace[2019485978] 'process raft request' (duration: 125.797027ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:44.520942Z","caller":"traceutil/trace.go:171","msg":"trace[97721419] linearizableReadLoop","detail":"{readStateIndex:2859; appliedIndex:2858; }","duration":"200.66136ms","start":"2026-09-07T10:51:44.320251Z","end":"2026-09-07T10:51:44.520913Z","steps":["trace[97721419] 'read index received' (duration: 200.503306ms)","trace[97721419] 'applied index is now lower than readState.Index' (duration: 156.874µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:44.521143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.864035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T10:51:44.521295Z","caller":"traceutil/trace.go:171","msg":"trace[518745733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2756; }","duration":"201.055799ms","start":"2026-09-07T10:51:44.320224Z","end":"2026-09-07T10:51:44.52128Z","steps":["trace[518745733] 'agreement among raft nodes before linearized reading' (duration: 200.792893ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:44.52116Z","caller":"traceutil/trace.go:171","msg":"trace[1980765024] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"212.337313ms","start":"2026-09-07T10:51:44.308636Z","end":"2026-09-07T10:51:44.520974Z","steps":["trace[1980765024] 'process raft request' (duration: 212.106678ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:50.370589Z","caller":"traceutil/trace.go:171","msg":"trace[928360233] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"110.772412ms","start":"2026-09-07T10:51:50.259775Z","end":"2026-09-07T10:51:50.370548Z","steps":["trace[928360233] 'process raft request' (duration: 110.562688ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:50.490637Z","caller":"traceutil/trace.go:171","msg":"trace[1702497770] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"167.655371ms","start":"2026-09-07T10:51:50.322943Z","end":"2026-09-07T10:51:50.490598Z","steps":["trace[1702497770] 'process raft request' (duration: 109.39642ms)","trace[1702497770] 'compare' (duration: 58.098367ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:51.069934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14289253644919917647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-07T10:51:51.348113Z","caller":"traceutil/trace.go:171","msg":"trace[1701421116] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"797.827315ms","start":"2026-09-07T10:51:50.550255Z","end":"2026-09-07T10:51:51.348083Z","steps":["trace[1701421116] 'process raft request' (duration: 738.960129ms)","trace[1701421116] 'compare' (duration: 58.660401ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:51.348304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.550229Z","time spent":"797.965499ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:51:51.518987Z","caller":"traceutil/trace.go:171","msg":"trace[2059694574] linearizableReadLoop","detail":"{readStateIndex:2894; appliedIndex:2891; }","duration":"950.163005ms","start":"2026-09-07T10:51:50.568806Z","end":"2026-09-07T10:51:51.518969Z","steps":["trace[2059694574] 'read index received' (duration: 720.523303ms)","trace[2059694574] 'applied index is now lower than readState.Index' (duration: 229.638542ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:51.519089Z","caller":"traceutil/trace.go:171","msg":"trace[459719524] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"957.442792ms","start":"2026-09-07T10:51:50.561635Z","end":"2026-09-07T10:51:51.519078Z","steps":["trace[459719524] 'process raft request' (duration: 914.919811ms)","trace[459719524] 'compare' (duration: 42.252724ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:51.519179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.561618Z","time spent":"957.488473ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:51:51.519419Z","caller":"traceutil/trace.go:171","msg":"trace[1163995326] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"632.612394ms","start":"2026-09-07T10:51:50.886762Z","end":"2026-09-07T10:51:51.519374Z","steps":["trace[1163995326] 'process raft request' (duration: 632.153462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.519497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.071365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-07T10:51:51.51958Z","caller":"traceutil/trace.go:171","msg":"trace[1836577773] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2790; }","duration":"105.224709ms","start":"2026-09-07T10:51:51.414331Z","end":"2026-09-07T10:51:51.519555Z","steps":["trace[1836577773] 'agreement among raft nodes before linearized reading' (duration: 105.019624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.519617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.739696ms","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":"warn","ts":"2026-09-07T10:51:51.519657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.88672Z","time spent":"632.809268ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:51:51.519684Z","caller":"traceutil/trace.go:171","msg":"trace[2109621387] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2790; }","duration":"407.820308ms","start":"2026-09-07T10:51:51.11184Z","end":"2026-09-07T10:51:51.51966Z","steps":["trace[2109621387] 'agreement among raft nodes before linearized reading' (duration: 407.704546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.519735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:51.111827Z","time spent":"407.89412ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-07T10:51:51.519762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.572442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-07T10:51:51.519791Z","caller":"traceutil/trace.go:171","msg":"trace[1584699988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2790; }","duration":"440.662623ms","start":"2026-09-07T10:51:51.07912Z","end":"2026-09-07T10:51:51.519783Z","steps":["trace[1584699988] 'agreement among raft nodes before linearized reading' (duration: 440.585541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.519816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:51.079087Z","time spent":"440.720165ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T10:51:51.519867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.915066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:51:51.5199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.449901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-09-07T10:51:51.519498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.040813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T10:51:51.519913Z","caller":"traceutil/trace.go:171","msg":"trace[495407575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2790; }","duration":"658.004408ms","start":"2026-09-07T10:51:50.861896Z","end":"2026-09-07T10:51:51.5199Z","steps":["trace[495407575] 'agreement among raft nodes before linearized reading' (duration: 657.940265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.519958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.861871Z","time spent":"658.07724ms","remote":"127.0.0.1:57894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T10:51:51.519932Z","caller":"traceutil/trace.go:171","msg":"trace[226080161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2790; }","duration":"936.524475ms","start":"2026-09-07T10:51:50.583398Z","end":"2026-09-07T10:51:51.519922Z","steps":["trace[226080161] 'agreement among raft nodes before linearized reading' (duration: 935.945091ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:51.519922Z","caller":"traceutil/trace.go:171","msg":"trace[1782123179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2790; }","duration":"412.510462ms","start":"2026-09-07T10:51:51.107404Z","end":"2026-09-07T10:51:51.519914Z","steps":["trace[1782123179] 'agreement among raft nodes before linearized reading' (duration: 412.453621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.520173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:51.107371Z","time spent":"412.787389ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T10:51:51.520168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.583361Z","time spent":"936.762791ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T10:51:51.519674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.871832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d3046318d92f4f\" ","response":"range_response_count:1 size:768"} {"level":"info","ts":"2026-09-07T10:51:51.520405Z","caller":"traceutil/trace.go:171","msg":"trace[1533821598] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d3046318d92f4f; range_end:; response_count:1; response_revision:2790; }","duration":"951.601969ms","start":"2026-09-07T10:51:50.568794Z","end":"2026-09-07T10:51:51.520396Z","steps":["trace[1533821598] 'agreement among raft nodes before linearized reading' (duration: 950.82677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:51.520436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:50.568753Z","time spent":"951.675261ms","remote":"127.0.0.1:57978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":790,"request content":"key:\"/registry/events/openstack/valkey-node-0.18d3046318d92f4f\" "} {"level":"info","ts":"2026-09-07T10:54:30.698779Z","caller":"traceutil/trace.go:171","msg":"trace[1167031293] linearizableReadLoop","detail":"{readStateIndex:3914; appliedIndex:3913; }","duration":"111.388342ms","start":"2026-09-07T10:54:30.587346Z","end":"2026-09-07T10:54:30.698734Z","steps":["trace[1167031293] 'read index received' (duration: 91.483866ms)","trace[1167031293] 'applied index is now lower than readState.Index' (duration: 19.902916ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:54:30.698884Z","caller":"traceutil/trace.go:171","msg":"trace[884040603] transaction","detail":"{read_only:false; response_revision:3770; number_of_response:1; }","duration":"117.228873ms","start":"2026-09-07T10:54:30.581617Z","end":"2026-09-07T10:54:30.698846Z","steps":["trace[884040603] 'process raft request' (duration: 97.209945ms)","trace[884040603] 'compare' (duration: 19.69581ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:54:30.698939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.580016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/sshkeys.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:54:30.699103Z","caller":"traceutil/trace.go:171","msg":"trace[818647419] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/sshkeys.secretgen.k14s.io; range_end:; response_count:0; response_revision:3770; }","duration":"111.693548ms","start":"2026-09-07T10:54:30.587308Z","end":"2026-09-07T10:54:30.699002Z","steps":["trace[818647419] 'agreement among raft nodes before linearized reading' (duration: 111.574895ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:30.700169Z","caller":"traceutil/trace.go:171","msg":"trace[1909628133] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"112.483256ms","start":"2026-09-07T10:54:30.587667Z","end":"2026-09-07T10:54:30.70015Z","steps":["trace[1909628133] 'process raft request' (duration: 112.313253ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:55:04.582851Z","caller":"traceutil/trace.go:171","msg":"trace[728070108] transaction","detail":"{read_only:false; response_revision:4007; number_of_response:1; }","duration":"298.257288ms","start":"2026-09-07T10:55:04.284568Z","end":"2026-09-07T10:55:04.582825Z","steps":["trace[728070108] 'process raft request' (duration: 298.093524ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:55:04.584201Z","caller":"traceutil/trace.go:171","msg":"trace[2126150928] linearizableReadLoop","detail":"{readStateIndex:4159; appliedIndex:4157; }","duration":"157.018851ms","start":"2026-09-07T10:55:04.427162Z","end":"2026-09-07T10:55:04.584181Z","steps":["trace[2126150928] 'read index received' (duration: 155.509095ms)","trace[2126150928] 'applied index is now lower than readState.Index' (duration: 1.508396ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:55:04.584323Z","caller":"traceutil/trace.go:171","msg":"trace[1572592088] transaction","detail":"{read_only:false; response_revision:4008; number_of_response:1; }","duration":"296.28122ms","start":"2026-09-07T10:55:04.288014Z","end":"2026-09-07T10:55:04.584295Z","steps":["trace[1572592088] 'process raft request' (duration: 296.074684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:55:04.584416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.229686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:55:04.584451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.638138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T10:55:04.584478Z","caller":"traceutil/trace.go:171","msg":"trace[788538643] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4008; }","duration":"157.340369ms","start":"2026-09-07T10:55:04.42712Z","end":"2026-09-07T10:55:04.584461Z","steps":["trace[788538643] 'agreement among raft nodes before linearized reading' (duration: 157.199576ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:55:04.58449Z","caller":"traceutil/trace.go:171","msg":"trace[1931642006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4008; }","duration":"148.70569ms","start":"2026-09-07T10:55:04.435774Z","end":"2026-09-07T10:55:04.58448Z","steps":["trace[1931642006] 'agreement among raft nodes before linearized reading' (duration: 148.561096ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:33.064018Z","caller":"traceutil/trace.go:171","msg":"trace[771731003] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"148.648562ms","start":"2026-09-07T10:56:32.915346Z","end":"2026-09-07T10:56:33.063995Z","steps":["trace[771731003] 'process raft request' (duration: 148.503588ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:33.086444Z","caller":"traceutil/trace.go:171","msg":"trace[266764415] linearizableReadLoop","detail":"{readStateIndex:5250; appliedIndex:5248; }","duration":"153.530442ms","start":"2026-09-07T10:56:32.932894Z","end":"2026-09-07T10:56:33.086424Z","steps":["trace[266764415] 'read index received' (duration: 130.957579ms)","trace[266764415] 'applied index is now lower than readState.Index' (duration: 22.571922ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:33.086513Z","caller":"traceutil/trace.go:171","msg":"trace[424335046] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"170.19888ms","start":"2026-09-07T10:56:32.916291Z","end":"2026-09-07T10:56:33.08649Z","steps":["trace[424335046] 'process raft request' (duration: 169.884303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:33.087247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.339941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-07T10:56:33.087323Z","caller":"traceutil/trace.go:171","msg":"trace[387971521] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5059; }","duration":"154.455584ms","start":"2026-09-07T10:56:32.932855Z","end":"2026-09-07T10:56:33.08731Z","steps":["trace[387971521] 'agreement among raft nodes before linearized reading' (duration: 153.678225ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:35.033164Z","caller":"traceutil/trace.go:171","msg":"trace[1652917205] linearizableReadLoop","detail":"{readStateIndex:5380; appliedIndex:5379; }","duration":"122.626425ms","start":"2026-09-07T10:56:34.910512Z","end":"2026-09-07T10:56:35.033138Z","steps":["trace[1652917205] 'read index received' (duration: 122.335698ms)","trace[1652917205] 'applied index is now lower than readState.Index' (duration: 289.546µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:35.033228Z","caller":"traceutil/trace.go:171","msg":"trace[1811229538] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"132.051145ms","start":"2026-09-07T10:56:34.901149Z","end":"2026-09-07T10:56:35.033201Z","steps":["trace[1811229538] 'process raft request' (duration: 131.83148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:35.033559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.032555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e881ca27-41a0-4650-aa8b-2aaf5f81cf15\" ","response":"range_response_count:1 size:4065"} {"level":"info","ts":"2026-09-07T10:56:35.033651Z","caller":"traceutil/trace.go:171","msg":"trace[1417778372] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e881ca27-41a0-4650-aa8b-2aaf5f81cf15; range_end:; response_count:1; response_revision:5186; }","duration":"123.188969ms","start":"2026-09-07T10:56:34.910443Z","end":"2026-09-07T10:56:35.033632Z","steps":["trace[1417778372] 'agreement among raft nodes before linearized reading' (duration: 122.905502ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:35.170097Z","caller":"traceutil/trace.go:171","msg":"trace[1175303780] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"130.158309ms","start":"2026-09-07T10:56:35.039912Z","end":"2026-09-07T10:56:35.17007Z","steps":["trace[1175303780] 'process raft request' (duration: 126.408307ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:35.171517Z","caller":"traceutil/trace.go:171","msg":"trace[186461151] linearizableReadLoop","detail":"{readStateIndex:5382; appliedIndex:5380; }","duration":"113.196335ms","start":"2026-09-07T10:56:35.058306Z","end":"2026-09-07T10:56:35.171502Z","steps":["trace[186461151] 'read index received' (duration: 108.024508ms)","trace[186461151] 'applied index is now lower than readState.Index' (duration: 5.170767ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:35.1716Z","caller":"traceutil/trace.go:171","msg":"trace[226359571] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"125.320112ms","start":"2026-09-07T10:56:35.046271Z","end":"2026-09-07T10:56:35.171591Z","steps":["trace[226359571] 'process raft request' (duration: 125.049484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:35.171868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.551213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:56:35.171914Z","caller":"traceutil/trace.go:171","msg":"trace[893370680] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5190; }","duration":"113.615554ms","start":"2026-09-07T10:56:35.058285Z","end":"2026-09-07T10:56:35.171901Z","steps":["trace[893370680] 'agreement among raft nodes before linearized reading' (duration: 113.477291ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:35.171962Z","caller":"traceutil/trace.go:171","msg":"trace[455766553] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"102.707237ms","start":"2026-09-07T10:56:35.069231Z","end":"2026-09-07T10:56:35.171938Z","steps":["trace[455766553] 'process raft request' (duration: 102.183765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.383867Z","caller":"traceutil/trace.go:171","msg":"trace[1422676570] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"132.134918ms","start":"2026-09-07T10:56:45.251716Z","end":"2026-09-07T10:56:45.383851Z","steps":["trace[1422676570] 'process raft request' (duration: 131.79065ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.384033Z","caller":"traceutil/trace.go:171","msg":"trace[447547180] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"132.298122ms","start":"2026-09-07T10:56:45.251728Z","end":"2026-09-07T10:56:45.384026Z","steps":["trace[447547180] 'process raft request' (duration: 131.860771ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.384152Z","caller":"traceutil/trace.go:171","msg":"trace[1208678167] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"132.399835ms","start":"2026-09-07T10:56:45.251745Z","end":"2026-09-07T10:56:45.384145Z","steps":["trace[1208678167] 'process raft request' (duration: 131.863561ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.384234Z","caller":"traceutil/trace.go:171","msg":"trace[353712380] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"132.258362ms","start":"2026-09-07T10:56:45.251931Z","end":"2026-09-07T10:56:45.38419Z","steps":["trace[353712380] 'process raft request' (duration: 131.717198ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.384227Z","caller":"traceutil/trace.go:171","msg":"trace[1204222714] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"132.432445ms","start":"2026-09-07T10:56:45.251746Z","end":"2026-09-07T10:56:45.384178Z","steps":["trace[1204222714] 'process raft request' (duration: 131.882811ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.597304Z","caller":"traceutil/trace.go:171","msg":"trace[2012498734] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"172.922578ms","start":"2026-09-07T10:56:45.424306Z","end":"2026-09-07T10:56:45.597228Z","steps":["trace[2012498734] 'process raft request' (duration: 172.760124ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.597322Z","caller":"traceutil/trace.go:171","msg":"trace[1048057026] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"154.29219ms","start":"2026-09-07T10:56:45.443007Z","end":"2026-09-07T10:56:45.597299Z","steps":["trace[1048057026] 'process raft request' (duration: 154.205678ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:45.597325Z","caller":"traceutil/trace.go:171","msg":"trace[518362837] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"173.018869ms","start":"2026-09-07T10:56:45.424199Z","end":"2026-09-07T10:56:45.597218Z","steps":["trace[518362837] 'process raft request' (duration: 101.044756ms)","trace[518362837] 'compare' (duration: 71.511881ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:45.597392Z","caller":"traceutil/trace.go:171","msg":"trace[1756794593] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"171.996785ms","start":"2026-09-07T10:56:45.425364Z","end":"2026-09-07T10:56:45.597361Z","steps":["trace[1756794593] 'process raft request' (duration: 171.79417ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:49.863426Z","caller":"traceutil/trace.go:171","msg":"trace[844582127] linearizableReadLoop","detail":"{readStateIndex:5668; appliedIndex:5667; }","duration":"192.500788ms","start":"2026-09-07T10:56:49.670909Z","end":"2026-09-07T10:56:49.86341Z","steps":["trace[844582127] 'read index received' (duration: 192.379004ms)","trace[844582127] 'applied index is now lower than readState.Index' (duration: 121.214µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:49.863507Z","caller":"traceutil/trace.go:171","msg":"trace[1033692113] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"242.41629ms","start":"2026-09-07T10:56:49.621051Z","end":"2026-09-07T10:56:49.863467Z","steps":["trace[1033692113] 'process raft request' (duration: 242.235915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:49.863666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.750153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2xqxj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T10:56:49.86375Z","caller":"traceutil/trace.go:171","msg":"trace[1563531166] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2xqxj; range_end:; response_count:1; response_revision:5465; }","duration":"192.850676ms","start":"2026-09-07T10:56:49.670883Z","end":"2026-09-07T10:56:49.863733Z","steps":["trace[1563531166] 'agreement among raft nodes before linearized reading' (duration: 192.5984ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:49.890006Z","caller":"traceutil/trace.go:171","msg":"trace[1095899202] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"167.37409ms","start":"2026-09-07T10:56:49.722598Z","end":"2026-09-07T10:56:49.889972Z","steps":["trace[1095899202] 'process raft request' (duration: 167.203506ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:50.002213Z","caller":"traceutil/trace.go:171","msg":"trace[1403628754] linearizableReadLoop","detail":"{readStateIndex:5670; appliedIndex:5668; }","duration":"135.139151ms","start":"2026-09-07T10:56:49.867042Z","end":"2026-09-07T10:56:50.002181Z","steps":["trace[1403628754] 'read index received' (duration: 22.735467ms)","trace[1403628754] 'applied index is now lower than readState.Index' (duration: 112.402234ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:50.002248Z","caller":"traceutil/trace.go:171","msg":"trace[535030247] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"137.322145ms","start":"2026-09-07T10:56:49.864904Z","end":"2026-09-07T10:56:50.002226Z","steps":["trace[535030247] 'process raft request' (duration: 131.128994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:50.002547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.48712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70677"} {"level":"info","ts":"2026-09-07T10:56:50.002616Z","caller":"traceutil/trace.go:171","msg":"trace[281768495] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5467; }","duration":"135.588843ms","start":"2026-09-07T10:56:49.867011Z","end":"2026-09-07T10:56:50.0026Z","steps":["trace[281768495] 'agreement among raft nodes before linearized reading' (duration: 135.290945ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:58.850542Z","caller":"traceutil/trace.go:171","msg":"trace[768628601] linearizableReadLoop","detail":"{readStateIndex:5845; appliedIndex:5844; }","duration":"234.951888ms","start":"2026-09-07T10:56:58.615569Z","end":"2026-09-07T10:56:58.85052Z","steps":["trace[768628601] 'read index received' (duration: 234.737403ms)","trace[768628601] 'applied index is now lower than readState.Index' (duration: 213.785µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:56:58.850856Z","caller":"traceutil/trace.go:171","msg":"trace[900960039] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"262.149902ms","start":"2026-09-07T10:56:58.588694Z","end":"2026-09-07T10:56:58.850844Z","steps":["trace[900960039] 'process raft request' (duration: 261.685162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:58.851111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.524882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:56:58.85115Z","caller":"traceutil/trace.go:171","msg":"trace[89184190] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5639; }","duration":"235.607753ms","start":"2026-09-07T10:56:58.615533Z","end":"2026-09-07T10:56:58.851141Z","steps":["trace[89184190] 'agreement among raft nodes before linearized reading' (duration: 235.500071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:58.851425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.386629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-07T10:56:58.85151Z","caller":"traceutil/trace.go:171","msg":"trace[664752552] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5639; }","duration":"162.507632ms","start":"2026-09-07T10:56:58.688984Z","end":"2026-09-07T10:56:58.851491Z","steps":["trace[664752552] 'agreement among raft nodes before linearized reading' (duration: 162.257146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:56:59.052794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.011657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:56:59.052921Z","caller":"traceutil/trace.go:171","msg":"trace[1974337058] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5639; }","duration":"130.190421ms","start":"2026-09-07T10:56:58.922706Z","end":"2026-09-07T10:56:59.052896Z","steps":["trace[1974337058] 'range keys from in-memory index tree' (duration: 129.921654ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:57:27.971682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-09-07T10:57:28.064009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"90.887258ms","hash":2205247552,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":32022528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T10:57:28.064083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2205247552,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-09-07T11:02:27.979494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5923} {"level":"info","ts":"2026-09-07T11:02:28.074381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5923,"took":"93.163401ms","hash":1392184266,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":26132480,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T11:02:28.074451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1392184266,"revision":5923,"compact-revision":2995} {"level":"warn","ts":"2026-09-07T11:03:05.990791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.280446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:03:05.99089Z","caller":"traceutil/trace.go:171","msg":"trace[840897453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8479; }","duration":"279.416399ms","start":"2026-09-07T11:03:05.711451Z","end":"2026-09-07T11:03:05.990868Z","steps":["trace[840897453] 'range keys from in-memory index tree' (duration: 279.197223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:05.990808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.712535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:03:05.99112Z","caller":"traceutil/trace.go:171","msg":"trace[570816076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8479; }","duration":"131.067543ms","start":"2026-09-07T11:03:05.860035Z","end":"2026-09-07T11:03:05.991102Z","steps":["trace[570816076] 'range keys from in-memory index tree' (duration: 130.629383ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:17.048669Z","caller":"traceutil/trace.go:171","msg":"trace[1439100911] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"179.198947ms","start":"2026-09-07T11:03:16.869446Z","end":"2026-09-07T11:03:17.048645Z","steps":["trace[1439100911] 'process raft request' (duration: 179.018573ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:17.165852Z","caller":"traceutil/trace.go:171","msg":"trace[429584549] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8832; }","duration":"166.652381ms","start":"2026-09-07T11:03:16.999176Z","end":"2026-09-07T11:03:17.165829Z","steps":["trace[429584549] 'read index received' (duration: 49.297911ms)","trace[429584549] 'applied index is now lower than readState.Index' (duration: 117.35316ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:17.165896Z","caller":"traceutil/trace.go:171","msg":"trace[231913142] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"277.966374ms","start":"2026-09-07T11:03:16.887893Z","end":"2026-09-07T11:03:17.165859Z","steps":["trace[231913142] 'process raft request' (duration: 243.585306ms)","trace[231913142] 'compare' (duration: 34.241504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:03:17.166062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.876587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-07T11:03:17.166162Z","caller":"traceutil/trace.go:171","msg":"trace[16897817] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8534; }","duration":"167.02148ms","start":"2026-09-07T11:03:16.999125Z","end":"2026-09-07T11:03:17.166146Z","steps":["trace[16897817] 'agreement among raft nodes before linearized reading' (duration: 166.815216ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:03:17.652553Z","caller":"traceutil/trace.go:171","msg":"trace[1614145413] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8836; }","duration":"131.939924ms","start":"2026-09-07T11:03:17.520588Z","end":"2026-09-07T11:03:17.652528Z","steps":["trace[1614145413] 'read index received' (duration: 131.610566ms)","trace[1614145413] 'applied index is now lower than readState.Index' (duration: 327.838µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:17.652624Z","caller":"traceutil/trace.go:171","msg":"trace[1565414781] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"132.593111ms","start":"2026-09-07T11:03:17.52Z","end":"2026-09-07T11:03:17.652593Z","steps":["trace[1565414781] 'process raft request' (duration: 132.293894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:17.652786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.184351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T11:03:17.652862Z","caller":"traceutil/trace.go:171","msg":"trace[1911326325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8537; }","duration":"132.289802ms","start":"2026-09-07T11:03:17.520552Z","end":"2026-09-07T11:03:17.652841Z","steps":["trace[1911326325] 'agreement among raft nodes before linearized reading' (duration: 132.062367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:17.94117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.150987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:03:17.941438Z","caller":"traceutil/trace.go:171","msg":"trace[528949222] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"284.292517ms","start":"2026-09-07T11:03:17.657119Z","end":"2026-09-07T11:03:17.941412Z","steps":["trace[528949222] 'process raft request' (duration: 83.797182ms)","trace[528949222] 'compare' (duration: 200.021144ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:17.941585Z","caller":"traceutil/trace.go:171","msg":"trace[1639554193] linearizableReadLoop","detail":"{readStateIndex:8840; appliedIndex:8837; }","duration":"280.174567ms","start":"2026-09-07T11:03:17.661386Z","end":"2026-09-07T11:03:17.94156Z","steps":["trace[1639554193] 'read index received' (duration: 9.950252ms)","trace[1639554193] 'applied index is now lower than readState.Index' (duration: 270.222255ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:03:17.941607Z","caller":"traceutil/trace.go:171","msg":"trace[1653585581] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"283.583141ms","start":"2026-09-07T11:03:17.657996Z","end":"2026-09-07T11:03:17.94158Z","steps":["trace[1653585581] 'process raft request' (duration: 283.322944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:17.941783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.387363ms","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-09-07T11:03:17.941821Z","caller":"traceutil/trace.go:171","msg":"trace[1600997333] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8540; }","duration":"280.487295ms","start":"2026-09-07T11:03:17.661323Z","end":"2026-09-07T11:03:17.941811Z","steps":["trace[1600997333] 'agreement among raft nodes before linearized reading' (duration: 280.331031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:03:18.491628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.957741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2xqxj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-07T11:03:18.491718Z","caller":"traceutil/trace.go:171","msg":"trace[1830830216] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2xqxj; range_end:; response_count:1; response_revision:8542; }","duration":"124.109845ms","start":"2026-09-07T11:03:18.36759Z","end":"2026-09-07T11:03:18.4917Z","steps":["trace[1830830216] 'range keys from in-memory index tree' (duration: 123.761476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:00.308742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.289641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:04:00.308961Z","caller":"traceutil/trace.go:171","msg":"trace[278108363] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"158.913502ms","start":"2026-09-07T11:04:00.15003Z","end":"2026-09-07T11:04:00.308943Z","steps":["trace[278108363] 'process raft request' (duration: 158.827ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:00.309468Z","caller":"traceutil/trace.go:171","msg":"trace[973760973] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"160.788308ms","start":"2026-09-07T11:04:00.148649Z","end":"2026-09-07T11:04:00.309437Z","steps":["trace[973760973] 'process raft request' (duration: 16.716557ms)","trace[973760973] 'compare' (duration: 143.129398ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:04:00.309521Z","caller":"traceutil/trace.go:171","msg":"trace[704689541] linearizableReadLoop","detail":"{readStateIndex:9173; appliedIndex:9172; }","duration":"159.62631ms","start":"2026-09-07T11:04:00.149861Z","end":"2026-09-07T11:04:00.309487Z","steps":["trace[704689541] 'read index received' (duration: 15.474858ms)","trace[704689541] 'applied index is now lower than readState.Index' (duration: 144.148172ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:04:00.30976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.870286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-44jlp\" ","response":"range_response_count:1 size:2803"} {"level":"warn","ts":"2026-09-07T11:04:00.30982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.393738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T11:04:00.30985Z","caller":"traceutil/trace.go:171","msg":"trace[752289887] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-44jlp; range_end:; response_count:1; response_revision:8861; }","duration":"160.00265ms","start":"2026-09-07T11:04:00.149829Z","end":"2026-09-07T11:04:00.309832Z","steps":["trace[752289887] 'agreement among raft nodes before linearized reading' (duration: 159.758473ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:00.309864Z","caller":"traceutil/trace.go:171","msg":"trace[687859491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8861; }","duration":"123.51055ms","start":"2026-09-07T11:04:00.18634Z","end":"2026-09-07T11:04:00.309851Z","steps":["trace[687859491] 'agreement among raft nodes before linearized reading' (duration: 123.326165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:04:00.30997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.6088ms","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-09-07T11:04:00.310042Z","caller":"traceutil/trace.go:171","msg":"trace[1475035850] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8861; }","duration":"118.713252ms","start":"2026-09-07T11:04:00.191305Z","end":"2026-09-07T11:04:00.310019Z","steps":["trace[1475035850] 'agreement among raft nodes before linearized reading' (duration: 118.533558ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:01.325388Z","caller":"traceutil/trace.go:171","msg":"trace[68823738] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"217.546091ms","start":"2026-09-07T11:04:01.10782Z","end":"2026-09-07T11:04:01.325366Z","steps":["trace[68823738] 'process raft request' (duration: 217.367586ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:04:16.089632Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246b.wal"} {"level":"info","ts":"2026-09-07T11:05:20.996579Z","caller":"traceutil/trace.go:171","msg":"trace[1541723604] linearizableReadLoop","detail":"{readStateIndex:9911; appliedIndex:9910; }","duration":"168.487091ms","start":"2026-09-07T11:05:20.828067Z","end":"2026-09-07T11:05:20.996554Z","steps":["trace[1541723604] 'read index received' (duration: 168.331098ms)","trace[1541723604] 'applied index is now lower than readState.Index' (duration: 154.743µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:05:20.996703Z","caller":"traceutil/trace.go:171","msg":"trace[1290607548] transaction","detail":"{read_only:false; response_revision:9575; number_of_response:1; }","duration":"170.337605ms","start":"2026-09-07T11:05:20.826292Z","end":"2026-09-07T11:05:20.996629Z","steps":["trace[1290607548] 'process raft request' (duration: 170.036608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:05:20.9968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.712016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T11:05:20.996857Z","caller":"traceutil/trace.go:171","msg":"trace[18427486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9575; }","duration":"168.822419ms","start":"2026-09-07T11:05:20.828022Z","end":"2026-09-07T11:05:20.996845Z","steps":["trace[18427486] 'agreement among raft nodes before linearized reading' (duration: 168.643375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:05:21.013157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.176564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:05:21.013225Z","caller":"traceutil/trace.go:171","msg":"trace[656447115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9576; }","duration":"153.291577ms","start":"2026-09-07T11:05:20.859917Z","end":"2026-09-07T11:05:21.013209Z","steps":["trace[656447115] 'agreement among raft nodes before linearized reading' (duration: 153.166604ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:21.01328Z","caller":"traceutil/trace.go:171","msg":"trace[1947229629] transaction","detail":"{read_only:false; response_revision:9576; number_of_response:1; }","duration":"182.509389ms","start":"2026-09-07T11:05:20.830736Z","end":"2026-09-07T11:05:21.013246Z","steps":["trace[1947229629] 'process raft request' (duration: 182.196912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:05:21.013388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.505403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:05:21.013503Z","caller":"traceutil/trace.go:171","msg":"trace[1955566443] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:9576; }","duration":"103.692087ms","start":"2026-09-07T11:05:20.909787Z","end":"2026-09-07T11:05:21.013479Z","steps":["trace[1955566443] 'agreement among raft nodes before linearized reading' (duration: 103.430181ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:24.067122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1e4a0bccff66c64d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:05:24.076108Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T11:05:24.076206Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T11:07:27.986963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8100} {"level":"info","ts":"2026-09-07T11:07:28.061893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8100,"took":"71.303132ms","hash":207772178,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":28962816,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-07T11:07:28.061972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":207772178,"revision":8100,"compact-revision":5923} {"level":"info","ts":"2026-09-07T11:08:46.647912Z","caller":"traceutil/trace.go:171","msg":"trace[733364146] transaction","detail":"{read_only:false; response_revision:11422; number_of_response:1; }","duration":"125.044147ms","start":"2026-09-07T11:08:46.522839Z","end":"2026-09-07T11:08:46.647883Z","steps":["trace[733364146] 'process raft request' (duration: 124.932975ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:09:00.83206Z","caller":"traceutil/trace.go:171","msg":"trace[1853269211] transaction","detail":"{read_only:false; response_revision:11502; number_of_response:1; }","duration":"135.038755ms","start":"2026-09-07T11:09:00.696994Z","end":"2026-09-07T11:09:00.832033Z","steps":["trace[1853269211] 'process raft request' (duration: 134.874241ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:09:00.863627Z","caller":"traceutil/trace.go:171","msg":"trace[1124704229] transaction","detail":"{read_only:false; response_revision:11503; number_of_response:1; }","duration":"164.3086ms","start":"2026-09-07T11:09:00.699293Z","end":"2026-09-07T11:09:00.863602Z","steps":["trace[1124704229] 'process raft request' (duration: 164.089075ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:12:27.994406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10580} {"level":"info","ts":"2026-09-07T11:12:28.086277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10580,"took":"89.199029ms","hash":1852230403,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":27463680,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T11:12:28.086357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1852230403,"revision":10580,"compact-revision":8100} {"level":"warn","ts":"2026-09-07T11:15:26.958165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.02107ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-07T11:15:26.958613Z","caller":"traceutil/trace.go:171","msg":"trace[1819597494] transaction","detail":"{read_only:false; response_revision:14098; number_of_response:1; }","duration":"282.883875ms","start":"2026-09-07T11:15:26.675711Z","end":"2026-09-07T11:15:26.958595Z","steps":["trace[1819597494] 'process raft request' (duration: 282.549648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:15:27.150855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.799422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:15:27.154004Z","caller":"traceutil/trace.go:171","msg":"trace[1277503985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14098; }","duration":"294.002318ms","start":"2026-09-07T11:15:26.85996Z","end":"2026-09-07T11:15:27.153963Z","steps":["trace[1277503985] 'agreement among raft nodes before linearized reading' (duration: 98.617868ms)","trace[1277503985] 'range keys from in-memory index tree' (duration: 192.202035ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:15:27.159605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.365041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:15:27.159706Z","caller":"traceutil/trace.go:171","msg":"trace[767942043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14098; }","duration":"142.515009ms","start":"2026-09-07T11:15:27.017164Z","end":"2026-09-07T11:15:27.159679Z","steps":["trace[767942043] 'count revisions from in-memory index tree' (duration: 133.29655ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:15:36.808267Z","caller":"traceutil/trace.go:171","msg":"trace[1277809712] transaction","detail":"{read_only:false; response_revision:14260; number_of_response:1; }","duration":"105.516201ms","start":"2026-09-07T11:15:36.702724Z","end":"2026-09-07T11:15:36.80824Z","steps":["trace[1277809712] 'process raft request' (duration: 105.390608ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:15:37.192437Z","caller":"traceutil/trace.go:171","msg":"trace[1942803182] linearizableReadLoop","detail":"{readStateIndex:14747; appliedIndex:14746; }","duration":"100.189564ms","start":"2026-09-07T11:15:37.092201Z","end":"2026-09-07T11:15:37.19239Z","steps":["trace[1942803182] 'read index received' (duration: 96.392154ms)","trace[1942803182] 'applied index is now lower than readState.Index' (duration: 3.79574ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:15:37.192479Z","caller":"traceutil/trace.go:171","msg":"trace[988955707] transaction","detail":"{read_only:false; response_revision:14261; number_of_response:1; }","duration":"179.303969ms","start":"2026-09-07T11:15:37.013143Z","end":"2026-09-07T11:15:37.192447Z","steps":["trace[988955707] 'process raft request' (duration: 175.55451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:15:37.192674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.461721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-nb-0.18d305b1302332b7\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-09-07T11:15:37.192759Z","caller":"traceutil/trace.go:171","msg":"trace[368036030] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-nb-0.18d305b1302332b7; range_end:; response_count:1; response_revision:14261; }","duration":"100.587563ms","start":"2026-09-07T11:15:37.092151Z","end":"2026-09-07T11:15:37.192739Z","steps":["trace[368036030] 'agreement among raft nodes before linearized reading' (duration: 100.383258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:15:37.529643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.022938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:15:37.529774Z","caller":"traceutil/trace.go:171","msg":"trace[428991606] linearizableReadLoop","detail":"{readStateIndex:14748; appliedIndex:14747; }","duration":"267.636683ms","start":"2026-09-07T11:15:37.262116Z","end":"2026-09-07T11:15:37.529753Z","steps":["trace[428991606] 'read index received' (duration: 76.41689ms)","trace[428991606] 'applied index is now lower than readState.Index' (duration: 191.218243ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:15:37.529832Z","caller":"traceutil/trace.go:171","msg":"trace[177288790] transaction","detail":"{read_only:false; response_revision:14262; number_of_response:1; }","duration":"334.955619ms","start":"2026-09-07T11:15:37.194831Z","end":"2026-09-07T11:15:37.529786Z","steps":["trace[177288790] 'process raft request' (duration: 143.687255ms)","trace[177288790] 'compare' (duration: 190.800753ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:15:37.529988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.87525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-gr96p.18d305b139e2c828\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-09-07T11:15:37.529985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:15:37.194807Z","time spent":"335.098752ms","remote":"127.0.0.1:57978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:15:37.530041Z","caller":"traceutil/trace.go:171","msg":"trace[554997756] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-gr96p.18d305b139e2c828; range_end:; response_count:1; response_revision:14262; }","duration":"267.963821ms","start":"2026-09-07T11:15:37.262062Z","end":"2026-09-07T11:15:37.530026Z","steps":["trace[554997756] 'agreement among raft nodes before linearized reading' (duration: 267.795086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:15:37.530229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.167796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:15:37.530265Z","caller":"traceutil/trace.go:171","msg":"trace[765290669] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:14262; }","duration":"225.245008ms","start":"2026-09-07T11:15:37.305009Z","end":"2026-09-07T11:15:37.530254Z","steps":["trace[765290669] 'agreement among raft nodes before linearized reading' (duration: 225.175566ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:18.709945Z","caller":"traceutil/trace.go:171","msg":"trace[348261731] linearizableReadLoop","detail":"{readStateIndex:15874; appliedIndex:15873; }","duration":"103.854721ms","start":"2026-09-07T11:17:18.606068Z","end":"2026-09-07T11:17:18.709923Z","steps":["trace[348261731] 'read index received' (duration: 103.651907ms)","trace[348261731] 'applied index is now lower than readState.Index' (duration: 201.954µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:17:18.710045Z","caller":"traceutil/trace.go:171","msg":"trace[1250779311] transaction","detail":"{read_only:false; response_revision:15353; number_of_response:1; }","duration":"146.463701ms","start":"2026-09-07T11:17:18.563547Z","end":"2026-09-07T11:17:18.710011Z","steps":["trace[1250779311] 'process raft request' (duration: 146.231466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:17:18.710126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.039545ms","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-09-07T11:17:18.710182Z","caller":"traceutil/trace.go:171","msg":"trace[507078775] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:15353; }","duration":"104.155328ms","start":"2026-09-07T11:17:18.606015Z","end":"2026-09-07T11:17:18.710171Z","steps":["trace[507078775] 'agreement among raft nodes before linearized reading' (duration: 104.046136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:17:18.945713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.458955ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:17:18.945821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.914312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T11:17:18.945884Z","caller":"traceutil/trace.go:171","msg":"trace[686263784] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15353; }","duration":"101.64913ms","start":"2026-09-07T11:17:18.844208Z","end":"2026-09-07T11:17:18.945857Z","steps":["trace[686263784] 'range keys from in-memory index tree' (duration: 101.424173ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:18.945899Z","caller":"traceutil/trace.go:171","msg":"trace[455409865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15353; }","duration":"209.036404ms","start":"2026-09-07T11:17:18.736842Z","end":"2026-09-07T11:17:18.945879Z","steps":["trace[455409865] 'range keys from in-memory index tree' (duration: 208.739697ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:18.946091Z","caller":"traceutil/trace.go:171","msg":"trace[115436362] transaction","detail":"{read_only:false; response_revision:15354; number_of_response:1; }","duration":"207.401785ms","start":"2026-09-07T11:17:18.738654Z","end":"2026-09-07T11:17:18.946056Z","steps":["trace[115436362] 'process raft request' (duration: 184.442471ms)","trace[115436362] 'compare' (duration: 22.515973ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:17:19.118272Z","caller":"traceutil/trace.go:171","msg":"trace[913399489] transaction","detail":"{read_only:false; response_revision:15355; number_of_response:1; }","duration":"306.277119ms","start":"2026-09-07T11:17:18.811966Z","end":"2026-09-07T11:17:19.118243Z","steps":["trace[913399489] 'process raft request' (duration: 306.060334ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:19.118441Z","caller":"traceutil/trace.go:171","msg":"trace[1252100648] linearizableReadLoop","detail":"{readStateIndex:15877; appliedIndex:15874; }","duration":"284.503883ms","start":"2026-09-07T11:17:18.83391Z","end":"2026-09-07T11:17:19.118414Z","steps":["trace[1252100648] 'read index received' (duration: 89.116582ms)","trace[1252100648] 'applied index is now lower than readState.Index' (duration: 195.386251ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:17:19.118622Z","caller":"traceutil/trace.go:171","msg":"trace[2019899776] transaction","detail":"{read_only:false; response_revision:15356; number_of_response:1; }","duration":"288.938659ms","start":"2026-09-07T11:17:18.829672Z","end":"2026-09-07T11:17:19.118611Z","steps":["trace[2019899776] 'process raft request' (duration: 288.520159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:17:19.11885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:17:18.811942Z","time spent":"306.547006ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:17:19.118905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.985394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m2h2v\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-09-07T11:17:19.119149Z","caller":"traceutil/trace.go:171","msg":"trace[2055068932] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m2h2v; range_end:; response_count:1; response_revision:15356; }","duration":"285.269221ms","start":"2026-09-07T11:17:18.833855Z","end":"2026-09-07T11:17:19.119124Z","steps":["trace[2055068932] 'agreement among raft nodes before linearized reading' (duration: 284.918283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:17:19.119411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.139396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:17:19.1195Z","caller":"traceutil/trace.go:171","msg":"trace[543066275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15357; }","duration":"260.276379ms","start":"2026-09-07T11:17:18.859206Z","end":"2026-09-07T11:17:19.119482Z","steps":["trace[543066275] 'agreement among raft nodes before linearized reading' (duration: 260.128215ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:19.119545Z","caller":"traceutil/trace.go:171","msg":"trace[501986055] transaction","detail":"{read_only:false; response_revision:15357; number_of_response:1; }","duration":"167.782257ms","start":"2026-09-07T11:17:18.951739Z","end":"2026-09-07T11:17:19.119521Z","steps":["trace[501986055] 'process raft request' (duration: 167.429058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:17:19.119576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.374027ms","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-09-07T11:17:19.119647Z","caller":"traceutil/trace.go:171","msg":"trace[1459237934] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15357; }","duration":"129.50304ms","start":"2026-09-07T11:17:18.990123Z","end":"2026-09-07T11:17:19.119626Z","steps":["trace[1459237934] 'agreement among raft nodes before linearized reading' (duration: 129.281685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:17:19.119789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.133924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T11:17:19.119855Z","caller":"traceutil/trace.go:171","msg":"trace[1069371540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15357; }","duration":"146.236306ms","start":"2026-09-07T11:17:18.9736Z","end":"2026-09-07T11:17:19.119836Z","steps":["trace[1069371540] 'agreement among raft nodes before linearized reading' (duration: 146.056602ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:17:28.001691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12838} {"level":"info","ts":"2026-09-07T11:17:28.092588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12838,"took":"88.342684ms","hash":3600684624,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T11:17:28.092655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3600684624,"revision":12838,"compact-revision":10580} {"level":"info","ts":"2026-09-07T11:22:28.009426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15403} {"level":"info","ts":"2026-09-07T11:22:28.112826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15403,"took":"100.191066ms","hash":2843183740,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T11:22:28.112901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2843183740,"revision":15403,"compact-revision":12838} {"level":"info","ts":"2026-09-07T11:22:54.514283Z","caller":"traceutil/trace.go:171","msg":"trace[1802679839] transaction","detail":"{read_only:false; response_revision:17862; number_of_response:1; }","duration":"108.271777ms","start":"2026-09-07T11:22:54.405987Z","end":"2026-09-07T11:22:54.514258Z","steps":["trace[1802679839] 'process raft request' (duration: 107.212322ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:23:00.234744Z","caller":"traceutil/trace.go:171","msg":"trace[1442311550] linearizableReadLoop","detail":"{readStateIndex:18518; appliedIndex:18517; }","duration":"169.930107ms","start":"2026-09-07T11:23:00.064791Z","end":"2026-09-07T11:23:00.234721Z","steps":["trace[1442311550] 'read index received' (duration: 169.756593ms)","trace[1442311550] 'applied index is now lower than readState.Index' (duration: 172.674µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:23:00.235083Z","caller":"traceutil/trace.go:171","msg":"trace[1797714166] transaction","detail":"{read_only:false; response_revision:17912; number_of_response:1; }","duration":"193.101542ms","start":"2026-09-07T11:23:00.04197Z","end":"2026-09-07T11:23:00.235071Z","steps":["trace[1797714166] 'process raft request' (duration: 192.6361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.235356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.560142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T11:23:00.235394Z","caller":"traceutil/trace.go:171","msg":"trace[793905532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17912; }","duration":"170.649594ms","start":"2026-09-07T11:23:00.064736Z","end":"2026-09-07T11:23:00.235385Z","steps":["trace[793905532] 'agreement among raft nodes before linearized reading' (duration: 170.528021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.235576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.508382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-07T11:23:00.235609Z","caller":"traceutil/trace.go:171","msg":"trace[1025407116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17912; }","duration":"162.572684ms","start":"2026-09-07T11:23:00.073029Z","end":"2026-09-07T11:23:00.235602Z","steps":["trace[1025407116] 'agreement among raft nodes before linearized reading' (duration: 162.484132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.235867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.276309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:23:00.235901Z","caller":"traceutil/trace.go:171","msg":"trace[1602025364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17912; }","duration":"157.33268ms","start":"2026-09-07T11:23:00.07856Z","end":"2026-09-07T11:23:00.235893Z","steps":["trace[1602025364] 'agreement among raft nodes before linearized reading' (duration: 157.247879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.699015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.4117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:23:00.699121Z","caller":"traceutil/trace.go:171","msg":"trace[1114280385] transaction","detail":"{read_only:false; response_revision:17913; number_of_response:1; }","duration":"455.836733ms","start":"2026-09-07T11:23:00.243265Z","end":"2026-09-07T11:23:00.699102Z","steps":["trace[1114280385] 'process raft request' (duration: 276.258168ms)","trace[1114280385] 'compare' (duration: 179.314368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:23:00.699176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:23:00.243243Z","time spent":"455.905185ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:23:00.748392Z","caller":"traceutil/trace.go:171","msg":"trace[1417631500] transaction","detail":"{read_only:false; response_revision:17914; number_of_response:1; }","duration":"504.749622ms","start":"2026-09-07T11:23:00.243619Z","end":"2026-09-07T11:23:00.748369Z","steps":["trace[1417631500] 'process raft request' (duration: 504.572778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.748539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:23:00.243608Z","time spent":"504.854215ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:23:00.748781Z","caller":"traceutil/trace.go:171","msg":"trace[176394355] linearizableReadLoop","detail":"{readStateIndex:18522; appliedIndex:18518; }","duration":"345.741883ms","start":"2026-09-07T11:23:00.403015Z","end":"2026-09-07T11:23:00.748757Z","steps":["trace[176394355] 'read index received' (duration: 116.520261ms)","trace[176394355] 'applied index is now lower than readState.Index' (duration: 229.220612ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:23:00.748855Z","caller":"traceutil/trace.go:171","msg":"trace[2007305867] transaction","detail":"{read_only:false; response_revision:17915; number_of_response:1; }","duration":"505.170842ms","start":"2026-09-07T11:23:00.24367Z","end":"2026-09-07T11:23:00.748841Z","steps":["trace[2007305867] 'process raft request' (duration: 504.65926ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:23:00.748889Z","caller":"traceutil/trace.go:171","msg":"trace[2018930918] transaction","detail":"{read_only:false; response_revision:17916; number_of_response:1; }","duration":"505.0726ms","start":"2026-09-07T11:23:00.243805Z","end":"2026-09-07T11:23:00.748878Z","steps":["trace[2018930918] 'process raft request' (duration: 504.831195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.748914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:23:00.243664Z","time spent":"505.217183ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:23:00.749002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:23:00.243798Z","time spent":"505.123451ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:23:00.749108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.065931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-09-07T11:23:00.749233Z","caller":"traceutil/trace.go:171","msg":"trace[1168129812] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:17916; }","duration":"346.219543ms","start":"2026-09-07T11:23:00.402987Z","end":"2026-09-07T11:23:00.749177Z","steps":["trace[1168129812] 'agreement among raft nodes before linearized reading' (duration: 345.966548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.749316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:23:00.402974Z","time spent":"346.329017ms","remote":"127.0.0.1:58040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-09-07T11:23:00.769865Z","caller":"traceutil/trace.go:171","msg":"trace[872672697] transaction","detail":"{read_only:false; response_revision:17917; number_of_response:1; }","duration":"238.761286ms","start":"2026-09-07T11:23:00.531066Z","end":"2026-09-07T11:23:00.769828Z","steps":["trace[872672697] 'process raft request' (duration: 238.473019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:23:00.770671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.842868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-tbr6n\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-09-07T11:23:00.770891Z","caller":"traceutil/trace.go:171","msg":"trace[2059145013] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-tbr6n; range_end:; response_count:1; response_revision:17917; }","duration":"276.146636ms","start":"2026-09-07T11:23:00.494722Z","end":"2026-09-07T11:23:00.770868Z","steps":["trace[2059145013] 'agreement among raft nodes before linearized reading' (duration: 275.055839ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:23:21.204438Z","caller":"traceutil/trace.go:171","msg":"trace[1952393386] transaction","detail":"{read_only:false; response_revision:18116; number_of_response:1; }","duration":"116.240155ms","start":"2026-09-07T11:23:21.088161Z","end":"2026-09-07T11:23:21.204402Z","steps":["trace[1952393386] 'process raft request' (duration: 83.100715ms)","trace[1952393386] 'compare' (duration: 32.640568ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:25:53.402287Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1e4a0bccff66c64d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:25:53.410321Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T11:25:53.41055Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T11:27:28.017517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17639} {"level":"info","ts":"2026-09-07T11:27:28.138811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17639,"took":"117.55804ms","hash":1173027220,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T11:27:28.138886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1173027220,"revision":17639,"compact-revision":15403} {"level":"info","ts":"2026-09-07T11:27:54.056571Z","caller":"traceutil/trace.go:171","msg":"trace[995195960] transaction","detail":"{read_only:false; response_revision:20237; number_of_response:1; }","duration":"142.136401ms","start":"2026-09-07T11:27:53.91439Z","end":"2026-09-07T11:27:54.056527Z","steps":["trace[995195960] 'process raft request' (duration: 141.897215ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:27:54.057499Z","caller":"traceutil/trace.go:171","msg":"trace[1530897982] transaction","detail":"{read_only:false; response_revision:20238; number_of_response:1; }","duration":"103.193304ms","start":"2026-09-07T11:27:53.954289Z","end":"2026-09-07T11:27:54.057483Z","steps":["trace[1530897982] 'process raft request' (duration: 102.798965ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:09.570376Z","caller":"traceutil/trace.go:171","msg":"trace[1358351100] transaction","detail":"{read_only:false; response_revision:20315; number_of_response:1; }","duration":"115.276624ms","start":"2026-09-07T11:28:09.455071Z","end":"2026-09-07T11:28:09.570348Z","steps":["trace[1358351100] 'process raft request' (duration: 115.038849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:09.977068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.709778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:28:09.977124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.667966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:28:09.977124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.730724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:28:09.977165Z","caller":"traceutil/trace.go:171","msg":"trace[1230109189] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:20315; }","duration":"334.851341ms","start":"2026-09-07T11:28:09.642293Z","end":"2026-09-07T11:28:09.977144Z","steps":["trace[1230109189] 'count revisions from in-memory index tree' (duration: 334.626685ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:09.977208Z","caller":"traceutil/trace.go:171","msg":"trace[1021591660] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:20315; }","duration":"118.784509ms","start":"2026-09-07T11:28:09.858388Z","end":"2026-09-07T11:28:09.977172Z","steps":["trace[1021591660] 'count revisions from in-memory index tree' (duration: 118.521463ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:09.977225Z","caller":"traceutil/trace.go:171","msg":"trace[450249428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20315; }","duration":"117.849327ms","start":"2026-09-07T11:28:09.859328Z","end":"2026-09-07T11:28:09.977178Z","steps":["trace[450249428] 'range keys from in-memory index tree' (duration: 117.654532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:09.97724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:09.642238Z","time spent":"334.986984ms","remote":"127.0.0.1:58344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-09-07T11:28:10.359778Z","caller":"traceutil/trace.go:171","msg":"trace[910894880] transaction","detail":"{read_only:false; response_revision:20317; number_of_response:1; }","duration":"148.026144ms","start":"2026-09-07T11:28:10.211717Z","end":"2026-09-07T11:28:10.359743Z","steps":["trace[910894880] 'process raft request' (duration: 133.926575ms)","trace[910894880] 'compare' (duration: 13.105985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:10.731938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.211608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:28:10.732059Z","caller":"traceutil/trace.go:171","msg":"trace[2046380773] linearizableReadLoop","detail":"{readStateIndex:21003; appliedIndex:21002; }","duration":"330.037855ms","start":"2026-09-07T11:28:10.401999Z","end":"2026-09-07T11:28:10.732037Z","steps":["trace[2046380773] 'read index received' (duration: 228.571382ms)","trace[2046380773] 'applied index is now lower than readState.Index' (duration: 101.464703ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:10.732074Z","caller":"traceutil/trace.go:171","msg":"trace[503320521] transaction","detail":"{read_only:false; response_revision:20318; number_of_response:1; }","duration":"330.889107ms","start":"2026-09-07T11:28:10.401159Z","end":"2026-09-07T11:28:10.732048Z","steps":["trace[503320521] 'process raft request' (duration: 229.464165ms)","trace[503320521] 'compare' (duration: 101.078823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:10.732171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.401134Z","time spent":"330.982118ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:28:10.732621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.606359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T11:28:10.732744Z","caller":"traceutil/trace.go:171","msg":"trace[1392120154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20318; }","duration":"330.756883ms","start":"2026-09-07T11:28:10.401944Z","end":"2026-09-07T11:28:10.732701Z","steps":["trace[1392120154] 'agreement among raft nodes before linearized reading' (duration: 330.173729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:10.732784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.401914Z","time spent":"330.860595ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-07T11:28:11.155268Z","caller":"traceutil/trace.go:171","msg":"trace[97138751] linearizableReadLoop","detail":"{readStateIndex:21004; appliedIndex:21003; }","duration":"423.152916ms","start":"2026-09-07T11:28:10.732092Z","end":"2026-09-07T11:28:11.155244Z","steps":["trace[97138751] 'read index received' (duration: 422.931591ms)","trace[97138751] 'applied index is now lower than readState.Index' (duration: 220.315µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:11.155468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.233851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T11:28:11.155543Z","caller":"traceutil/trace.go:171","msg":"trace[110052808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20319; }","duration":"475.401434ms","start":"2026-09-07T11:28:10.68012Z","end":"2026-09-07T11:28:11.155521Z","steps":["trace[110052808] 'agreement among raft nodes before linearized reading' (duration: 475.18922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.155485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.627005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T11:28:11.155542Z","caller":"traceutil/trace.go:171","msg":"trace[1929449730] transaction","detail":"{read_only:false; response_revision:20319; number_of_response:1; }","duration":"460.616969ms","start":"2026-09-07T11:28:10.694868Z","end":"2026-09-07T11:28:11.155485Z","steps":["trace[1929449730] 'process raft request' (duration: 460.145698ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:11.155626Z","caller":"traceutil/trace.go:171","msg":"trace[267364434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20319; }","duration":"658.81879ms","start":"2026-09-07T11:28:10.496794Z","end":"2026-09-07T11:28:11.155613Z","steps":["trace[267364434] 'agreement among raft nodes before linearized reading' (duration: 658.593764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.155605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.827929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-07T11:28:11.155698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.496769Z","time spent":"658.887482ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-07T11:28:11.155698Z","caller":"traceutil/trace.go:171","msg":"trace[628098390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20319; }","duration":"579.967132ms","start":"2026-09-07T11:28:10.575706Z","end":"2026-09-07T11:28:11.155673Z","steps":["trace[628098390] 'agreement among raft nodes before linearized reading' (duration: 579.665094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.155743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.575686Z","time spent":"580.045824ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T11:28:11.155811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.694846Z","time spent":"460.821464ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:28:11.155595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.680101Z","time spent":"475.483016ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-07T11:28:11.191531Z","caller":"traceutil/trace.go:171","msg":"trace[2112090737] transaction","detail":"{read_only:false; response_revision:20320; number_of_response:1; }","duration":"454.4487ms","start":"2026-09-07T11:28:10.737048Z","end":"2026-09-07T11:28:11.191497Z","steps":["trace[2112090737] 'process raft request' (duration: 454.221124ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:11.191713Z","caller":"traceutil/trace.go:171","msg":"trace[1781146994] transaction","detail":"{read_only:false; response_revision:20321; number_of_response:1; }","duration":"359.413002ms","start":"2026-09-07T11:28:10.832259Z","end":"2026-09-07T11:28:11.191672Z","steps":["trace[1781146994] 'process raft request' (duration: 359.177786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.191753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.737025Z","time spent":"454.620134ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:28:11.191819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.832235Z","time spent":"359.532985ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:28:11.191855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.834101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-07T11:28:11.191888Z","caller":"traceutil/trace.go:171","msg":"trace[1379685245] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20321; }","duration":"280.892442ms","start":"2026-09-07T11:28:10.910984Z","end":"2026-09-07T11:28:11.191877Z","steps":["trace[1379685245] 'agreement among raft nodes before linearized reading' (duration: 280.80645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.19176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.727028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-07T11:28:11.192015Z","caller":"traceutil/trace.go:171","msg":"trace[653313573] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20321; }","duration":"394.009315ms","start":"2026-09-07T11:28:10.797986Z","end":"2026-09-07T11:28:11.191995Z","steps":["trace[653313573] 'agreement among raft nodes before linearized reading' (duration: 393.615786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.19207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.797965Z","time spent":"394.091077ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-07T11:28:11.192095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.615975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-07T11:28:11.19212Z","caller":"traceutil/trace.go:171","msg":"trace[926536268] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20321; }","duration":"339.641796ms","start":"2026-09-07T11:28:10.85247Z","end":"2026-09-07T11:28:11.192112Z","steps":["trace[926536268] 'agreement among raft nodes before linearized reading' (duration: 339.571203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:11.192146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:10.85241Z","time spent":"339.729588ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-07T11:32:04.822771Z","caller":"traceutil/trace.go:171","msg":"trace[459315389] transaction","detail":"{read_only:false; response_revision:22756; number_of_response:1; }","duration":"342.71905ms","start":"2026-09-07T11:32:04.479988Z","end":"2026-09-07T11:32:04.822707Z","steps":["trace[459315389] 'process raft request' (duration: 342.422103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:32:04.823023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:32:04.479964Z","time spent":"342.907796ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:32:04.825283Z","caller":"traceutil/trace.go:171","msg":"trace[550970784] linearizableReadLoop","detail":"{readStateIndex:23514; appliedIndex:23512; }","duration":"311.694983ms","start":"2026-09-07T11:32:04.513537Z","end":"2026-09-07T11:32:04.825232Z","steps":["trace[550970784] 'read index received' (duration: 309.019179ms)","trace[550970784] 'applied index is now lower than readState.Index' (duration: 2.674144ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:32:04.825423Z","caller":"traceutil/trace.go:171","msg":"trace[49809991] transaction","detail":"{read_only:false; response_revision:22757; number_of_response:1; }","duration":"317.230117ms","start":"2026-09-07T11:32:04.508132Z","end":"2026-09-07T11:32:04.825362Z","steps":["trace[49809991] 'process raft request' (duration: 316.883629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:32:04.82559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:32:04.508104Z","time spent":"317.406321ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:32:04.825639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.074404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T11:32:04.825707Z","caller":"traceutil/trace.go:171","msg":"trace[1853198601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22757; }","duration":"312.188266ms","start":"2026-09-07T11:32:04.513506Z","end":"2026-09-07T11:32:04.825695Z","steps":["trace[1853198601] 'agreement among raft nodes before linearized reading' (duration: 311.880458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:32:04.82575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:32:04.513493Z","time spent":"312.245887ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T11:32:04.825803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.891318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-07T11:32:04.825903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.756421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T11:32:04.825918Z","caller":"traceutil/trace.go:171","msg":"trace[1113841055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22757; }","duration":"302.057402ms","start":"2026-09-07T11:32:04.523843Z","end":"2026-09-07T11:32:04.8259Z","steps":["trace[1113841055] 'agreement among raft nodes before linearized reading' (duration: 301.59424ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:04.825989Z","caller":"traceutil/trace.go:171","msg":"trace[1842336786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22757; }","duration":"263.894523ms","start":"2026-09-07T11:32:04.562075Z","end":"2026-09-07T11:32:04.82597Z","steps":["trace[1842336786] 'agreement among raft nodes before linearized reading' (duration: 263.583036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:32:04.826068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:32:04.523809Z","time spent":"302.198705ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T11:32:04.82606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.336173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T11:32:04.826136Z","caller":"traceutil/trace.go:171","msg":"trace[192784866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22757; }","duration":"271.455816ms","start":"2026-09-07T11:32:04.554661Z","end":"2026-09-07T11:32:04.826117Z","steps":["trace[192784866] 'agreement among raft nodes before linearized reading' (duration: 271.254481ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:32:28.023998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20104} {"level":"info","ts":"2026-09-07T11:32:28.143778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20104,"took":"116.175534ms","hash":2868242139,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":42160128,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-07T11:32:28.143934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2868242139,"revision":20104,"compact-revision":17639} {"level":"info","ts":"2026-09-07T11:36:16.011813Z","caller":"traceutil/trace.go:171","msg":"trace[132025204] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"271.737873ms","start":"2026-09-07T11:36:15.740019Z","end":"2026-09-07T11:36:16.011756Z","steps":["trace[132025204] 'process raft request' (duration: 271.538718ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:16.020621Z","caller":"traceutil/trace.go:171","msg":"trace[2023049816] linearizableReadLoop","detail":"{readStateIndex:25761; appliedIndex:25759; }","duration":"159.642148ms","start":"2026-09-07T11:36:15.860958Z","end":"2026-09-07T11:36:16.0206Z","steps":["trace[2023049816] 'read index received' (duration: 150.575398ms)","trace[2023049816] 'applied index is now lower than readState.Index' (duration: 9.06575ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:16.020719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.75141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:16.020752Z","caller":"traceutil/trace.go:171","msg":"trace[1218131815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24947; }","duration":"159.841432ms","start":"2026-09-07T11:36:15.860901Z","end":"2026-09-07T11:36:16.020743Z","steps":["trace[1218131815] 'agreement among raft nodes before linearized reading' (duration: 159.77267ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:16.020786Z","caller":"traceutil/trace.go:171","msg":"trace[1452953334] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"267.593912ms","start":"2026-09-07T11:36:15.753174Z","end":"2026-09-07T11:36:16.020768Z","steps":["trace[1452953334] 'process raft request' (duration: 267.335606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:16.799815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.309574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T11:36:16.799892Z","caller":"traceutil/trace.go:171","msg":"trace[1383650393] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:24956; }","duration":"231.428177ms","start":"2026-09-07T11:36:16.568442Z","end":"2026-09-07T11:36:16.79987Z","steps":["trace[1383650393] 'count revisions from in-memory index tree' (duration: 231.226832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:16.94103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.353493ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-07T11:36:16.941105Z","caller":"traceutil/trace.go:171","msg":"trace[175607859] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25770; }","duration":"157.701331ms","start":"2026-09-07T11:36:16.783392Z","end":"2026-09-07T11:36:16.941094Z","steps":["trace[175607859] 'read index received' (duration: 26.270077ms)","trace[175607859] 'applied index is now lower than readState.Index' (duration: 131.430294ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:16.941184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.792233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T11:36:16.941216Z","caller":"traceutil/trace.go:171","msg":"trace[1180705134] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24956; }","duration":"157.852274ms","start":"2026-09-07T11:36:16.783344Z","end":"2026-09-07T11:36:16.941196Z","steps":["trace[1180705134] 'agreement among raft nodes before linearized reading' (duration: 157.800293ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:16.958826Z","caller":"traceutil/trace.go:171","msg":"trace[125589295] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"159.356001ms","start":"2026-09-07T11:36:16.799439Z","end":"2026-09-07T11:36:16.958795Z","steps":["trace[125589295] 'process raft request' (duration: 159.104995ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:18.25731Z","caller":"traceutil/trace.go:171","msg":"trace[880003015] linearizableReadLoop","detail":"{readStateIndex:25777; appliedIndex:25776; }","duration":"137.695056ms","start":"2026-09-07T11:36:18.119503Z","end":"2026-09-07T11:36:18.257198Z","steps":["trace[880003015] 'read index received' (duration: 122.136149ms)","trace[880003015] 'applied index is now lower than readState.Index' (duration: 15.557277ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:18.257509Z","caller":"traceutil/trace.go:171","msg":"trace[1506550388] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"138.348241ms","start":"2026-09-07T11:36:18.119111Z","end":"2026-09-07T11:36:18.257459Z","steps":["trace[1506550388] 'process raft request' (duration: 122.590339ms)","trace[1506550388] 'compare' (duration: 15.349283ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:18.257662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.041375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:36:18.257697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.140506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-07T11:36:18.25774Z","caller":"traceutil/trace.go:171","msg":"trace[362769219] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24962; }","duration":"138.176137ms","start":"2026-09-07T11:36:18.119544Z","end":"2026-09-07T11:36:18.25772Z","steps":["trace[362769219] 'agreement among raft nodes before linearized reading' (duration: 138.025334ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:18.257748Z","caller":"traceutil/trace.go:171","msg":"trace[248981026] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24962; }","duration":"138.280869ms","start":"2026-09-07T11:36:18.119451Z","end":"2026-09-07T11:36:18.257732Z","steps":["trace[248981026] 'agreement among raft nodes before linearized reading' (duration: 138.010843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:18.257799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.175277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:18.25792Z","caller":"traceutil/trace.go:171","msg":"trace[1653829756] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24962; }","duration":"138.332742ms","start":"2026-09-07T11:36:18.119569Z","end":"2026-09-07T11:36:18.257901Z","steps":["trace[1653829756] 'agreement among raft nodes before linearized reading' (duration: 138.119946ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:18.420342Z","caller":"traceutil/trace.go:171","msg":"trace[1789773618] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"123.509283ms","start":"2026-09-07T11:36:18.296804Z","end":"2026-09-07T11:36:18.420313Z","steps":["trace[1789773618] 'process raft request' (duration: 123.294277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:18.435856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.662944ms","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-09-07T11:36:18.435948Z","caller":"traceutil/trace.go:171","msg":"trace[340989237] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24965; }","duration":"106.807327ms","start":"2026-09-07T11:36:18.329118Z","end":"2026-09-07T11:36:18.435925Z","steps":["trace[340989237] 'agreement among raft nodes before linearized reading' (duration: 106.638063ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:20.713694Z","caller":"traceutil/trace.go:171","msg":"trace[1874750513] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"175.88501ms","start":"2026-09-07T11:36:20.537787Z","end":"2026-09-07T11:36:20.713672Z","steps":["trace[1874750513] 'process raft request' (duration: 175.702466ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:20.736275Z","caller":"traceutil/trace.go:171","msg":"trace[315480977] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"198.391225ms","start":"2026-09-07T11:36:20.537809Z","end":"2026-09-07T11:36:20.7362Z","steps":["trace[315480977] 'process raft request' (duration: 198.18642ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:20.736362Z","caller":"traceutil/trace.go:171","msg":"trace[583414532] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"198.524608ms","start":"2026-09-07T11:36:20.53782Z","end":"2026-09-07T11:36:20.736344Z","steps":["trace[583414532] 'process raft request' (duration: 198.293413ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:20.736643Z","caller":"traceutil/trace.go:171","msg":"trace[2029439361] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"198.718144ms","start":"2026-09-07T11:36:20.537907Z","end":"2026-09-07T11:36:20.736625Z","steps":["trace[2029439361] 'process raft request' (duration: 198.276683ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:21.54987Z","caller":"traceutil/trace.go:171","msg":"trace[1649110129] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"126.411303ms","start":"2026-09-07T11:36:21.423427Z","end":"2026-09-07T11:36:21.549838Z","steps":["trace[1649110129] 'process raft request' (duration: 67.875235ms)","trace[1649110129] 'compare' (duration: 58.435996ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:21.549948Z","caller":"traceutil/trace.go:171","msg":"trace[1672407474] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"125.645414ms","start":"2026-09-07T11:36:21.424287Z","end":"2026-09-07T11:36:21.549932Z","steps":["trace[1672407474] 'process raft request' (duration: 125.563512ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.260548Z","caller":"traceutil/trace.go:171","msg":"trace[1535679108] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"156.836679ms","start":"2026-09-07T11:36:22.103691Z","end":"2026-09-07T11:36:22.260527Z","steps":["trace[1535679108] 'process raft request' (duration: 156.564993ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.26044Z","caller":"traceutil/trace.go:171","msg":"trace[1893907175] linearizableReadLoop","detail":"{readStateIndex:25808; appliedIndex:25807; }","duration":"132.082361ms","start":"2026-09-07T11:36:22.128333Z","end":"2026-09-07T11:36:22.260415Z","steps":["trace[1893907175] 'read index received' (duration: 131.802454ms)","trace[1893907175] 'applied index is now lower than readState.Index' (duration: 278.817µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:22.260749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.380088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.260846Z","caller":"traceutil/trace.go:171","msg":"trace[2118873754] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24992; }","duration":"132.52701ms","start":"2026-09-07T11:36:22.128299Z","end":"2026-09-07T11:36:22.260826Z","steps":["trace[2118873754] 'agreement among raft nodes before linearized reading' (duration: 132.338846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.260927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.316618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-668684f58-c86mt.18d304ff92c84412\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-09-07T11:36:22.260983Z","caller":"traceutil/trace.go:171","msg":"trace[2145795724] range","detail":"{range_begin:/registry/events/openstack/barbican-api-668684f58-c86mt.18d304ff92c84412; range_end:; response_count:1; response_revision:24992; }","duration":"102.39825ms","start":"2026-09-07T11:36:22.158569Z","end":"2026-09-07T11:36:22.260967Z","steps":["trace[2145795724] 'agreement among raft nodes before linearized reading' (duration: 102.310367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.261402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.374916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.261478Z","caller":"traceutil/trace.go:171","msg":"trace[863610949] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24992; }","duration":"132.974061ms","start":"2026-09-07T11:36:22.128459Z","end":"2026-09-07T11:36:22.261433Z","steps":["trace[863610949] 'agreement among raft nodes before linearized reading' (duration: 132.342886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.443198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.762068ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-07T11:36:22.443644Z","caller":"traceutil/trace.go:171","msg":"trace[1885933178] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"177.100091ms","start":"2026-09-07T11:36:22.266516Z","end":"2026-09-07T11:36:22.443616Z","steps":["trace[1885933178] 'read index received' (duration: 45.910003ms)","trace[1885933178] 'applied index is now lower than readState.Index' (duration: 131.187438ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:22.443766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.232814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.443885Z","caller":"traceutil/trace.go:171","msg":"trace[573563489] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24992; }","duration":"177.393937ms","start":"2026-09-07T11:36:22.266469Z","end":"2026-09-07T11:36:22.443863Z","steps":["trace[573563489] 'agreement among raft nodes before linearized reading' (duration: 177.229954ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.444025Z","caller":"traceutil/trace.go:171","msg":"trace[1711465937] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"136.181409ms","start":"2026-09-07T11:36:22.307825Z","end":"2026-09-07T11:36:22.444006Z","steps":["trace[1711465937] 'process raft request' (duration: 135.597384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.494909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.308871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.495025Z","caller":"traceutil/trace.go:171","msg":"trace[714274132] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24994; }","duration":"228.446734ms","start":"2026-09-07T11:36:22.266556Z","end":"2026-09-07T11:36:22.495003Z","steps":["trace[714274132] 'agreement among raft nodes before linearized reading' (duration: 228.283711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.495258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.840555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-07T11:36:22.495371Z","caller":"traceutil/trace.go:171","msg":"trace[1946575308] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24994; }","duration":"227.046281ms","start":"2026-09-07T11:36:22.268307Z","end":"2026-09-07T11:36:22.495353Z","steps":["trace[1946575308] 'agreement among raft nodes before linearized reading' (duration: 226.484507ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.495506Z","caller":"traceutil/trace.go:171","msg":"trace[1043990471] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"154.803441ms","start":"2026-09-07T11:36:22.34068Z","end":"2026-09-07T11:36:22.495483Z","steps":["trace[1043990471] 'process raft request' (duration: 153.860108ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.662688Z","caller":"traceutil/trace.go:171","msg":"trace[1994517029] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"191.976231ms","start":"2026-09-07T11:36:22.47067Z","end":"2026-09-07T11:36:22.662646Z","steps":["trace[1994517029] 'process raft request' (duration: 191.797797ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.662678Z","caller":"traceutil/trace.go:171","msg":"trace[1119090735] linearizableReadLoop","detail":"{readStateIndex:25813; appliedIndex:25811; }","duration":"167.919339ms","start":"2026-09-07T11:36:22.49474Z","end":"2026-09-07T11:36:22.66266Z","steps":["trace[1119090735] 'read index received' (duration: 101.185271ms)","trace[1119090735] 'applied index is now lower than readState.Index' (duration: 66.733027ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:22.662873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.739685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:36:22.662891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.51209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.662678Z","caller":"traceutil/trace.go:171","msg":"trace[63192077] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"217.739136ms","start":"2026-09-07T11:36:22.444901Z","end":"2026-09-07T11:36:22.66264Z","steps":["trace[63192077] 'process raft request' (duration: 151.003779ms)","trace[63192077] 'compare' (duration: 66.317046ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:22.662935Z","caller":"traceutil/trace.go:171","msg":"trace[1465793296] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24997; }","duration":"198.851478ms","start":"2026-09-07T11:36:22.464069Z","end":"2026-09-07T11:36:22.66292Z","steps":["trace[1465793296] 'agreement among raft nodes before linearized reading' (duration: 198.726225ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.662945Z","caller":"traceutil/trace.go:171","msg":"trace[502702009] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24996; }","duration":"198.618902ms","start":"2026-09-07T11:36:22.464312Z","end":"2026-09-07T11:36:22.662931Z","steps":["trace[502702009] 'agreement among raft nodes before linearized reading' (duration: 198.449838ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.662861Z","caller":"traceutil/trace.go:171","msg":"trace[252150213] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"123.838631ms","start":"2026-09-07T11:36:22.538998Z","end":"2026-09-07T11:36:22.662837Z","steps":["trace[252150213] 'process raft request' (duration: 123.618656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.662829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.555951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:36:22.66287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.744435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:36:22.663006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.753375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-07T11:36:22.663056Z","caller":"traceutil/trace.go:171","msg":"trace[2035397830] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24996; }","duration":"198.843687ms","start":"2026-09-07T11:36:22.464189Z","end":"2026-09-07T11:36:22.663032Z","steps":["trace[2035397830] 'agreement among raft nodes before linearized reading' (duration: 198.586791ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.663094Z","caller":"traceutil/trace.go:171","msg":"trace[100311047] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24997; }","duration":"198.95399ms","start":"2026-09-07T11:36:22.464119Z","end":"2026-09-07T11:36:22.663073Z","steps":["trace[100311047] 'agreement among raft nodes before linearized reading' (duration: 198.672734ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.6631Z","caller":"traceutil/trace.go:171","msg":"trace[2062758446] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24996; }","duration":"198.97ms","start":"2026-09-07T11:36:22.464068Z","end":"2026-09-07T11:36:22.663038Z","steps":["trace[2062758446] 'agreement among raft nodes before linearized reading' (duration: 198.671403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.663371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.190857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T11:36:22.663412Z","caller":"traceutil/trace.go:171","msg":"trace[1120840891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24997; }","duration":"126.281881ms","start":"2026-09-07T11:36:22.537121Z","end":"2026-09-07T11:36:22.663402Z","steps":["trace[1120840891] 'agreement among raft nodes before linearized reading' (duration: 126.150867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.663647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.589653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-07T11:36:22.663703Z","caller":"traceutil/trace.go:171","msg":"trace[794937004] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24997; }","duration":"163.684265ms","start":"2026-09-07T11:36:22.50001Z","end":"2026-09-07T11:36:22.663694Z","steps":["trace[794937004] 'agreement among raft nodes before linearized reading' (duration: 163.559233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.664701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.96026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-dbc3ee91-8901-447b-846a-cb614b0bfc21\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-09-07T11:36:22.664777Z","caller":"traceutil/trace.go:171","msg":"trace[1712100682] range","detail":"{range_begin:/registry/persistentvolumes/pvc-dbc3ee91-8901-447b-846a-cb614b0bfc21; range_end:; response_count:1; response_revision:24997; }","duration":"166.077254ms","start":"2026-09-07T11:36:22.498674Z","end":"2026-09-07T11:36:22.664751Z","steps":["trace[1712100682] 'agreement among raft nodes before linearized reading' (duration: 164.595428ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.842314Z","caller":"traceutil/trace.go:171","msg":"trace[1027429632] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"174.743633ms","start":"2026-09-07T11:36:22.667538Z","end":"2026-09-07T11:36:22.842281Z","steps":["trace[1027429632] 'process raft request' (duration: 95.33263ms)","trace[1027429632] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 79.179418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:22.843129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.361706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T11:36:22.843168Z","caller":"traceutil/trace.go:171","msg":"trace[828374426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24998; }","duration":"125.445748ms","start":"2026-09-07T11:36:22.717712Z","end":"2026-09-07T11:36:22.843157Z","steps":["trace[828374426] 'agreement among raft nodes before linearized reading' (duration: 124.880734ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.843307Z","caller":"traceutil/trace.go:171","msg":"trace[93272616] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25814; }","duration":"124.446754ms","start":"2026-09-07T11:36:22.717754Z","end":"2026-09-07T11:36:22.842201Z","steps":["trace[93272616] 'read index received' (duration: 45.103632ms)","trace[93272616] 'applied index is now lower than readState.Index' (duration: 79.342252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:22.843592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.731255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.843685Z","caller":"traceutil/trace.go:171","msg":"trace[1629560366] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24998; }","duration":"108.874207ms","start":"2026-09-07T11:36:22.734792Z","end":"2026-09-07T11:36:22.843666Z","steps":["trace[1629560366] 'agreement among raft nodes before linearized reading' (duration: 108.715603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.843693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.005871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-07T11:36:22.84379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.397445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T11:36:22.843827Z","caller":"traceutil/trace.go:171","msg":"trace[1787822696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24998; }","duration":"103.449637ms","start":"2026-09-07T11:36:22.740365Z","end":"2026-09-07T11:36:22.843815Z","steps":["trace[1787822696] 'agreement among raft nodes before linearized reading' (duration: 103.371825ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.843809Z","caller":"traceutil/trace.go:171","msg":"trace[1963319644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24998; }","duration":"102.138615ms","start":"2026-09-07T11:36:22.741654Z","end":"2026-09-07T11:36:22.843793Z","steps":["trace[1963319644] 'agreement among raft nodes before linearized reading' (duration: 101.880678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:22.843983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.522814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-07T11:36:22.844052Z","caller":"traceutil/trace.go:171","msg":"trace[368365288] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24998; }","duration":"102.611426ms","start":"2026-09-07T11:36:22.741423Z","end":"2026-09-07T11:36:22.844034Z","steps":["trace[368365288] 'agreement among raft nodes before linearized reading' (duration: 102.129955ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.975852Z","caller":"traceutil/trace.go:171","msg":"trace[593099555] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25815; }","duration":"128.106953ms","start":"2026-09-07T11:36:22.847719Z","end":"2026-09-07T11:36:22.975826Z","steps":["trace[593099555] 'read index received' (duration: 98.037385ms)","trace[593099555] 'applied index is now lower than readState.Index' (duration: 30.068428ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:22.976014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.269637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:22.976044Z","caller":"traceutil/trace.go:171","msg":"trace[1188942730] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24999; }","duration":"128.359629ms","start":"2026-09-07T11:36:22.847674Z","end":"2026-09-07T11:36:22.976034Z","steps":["trace[1188942730] 'agreement among raft nodes before linearized reading' (duration: 128.280247ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:22.976012Z","caller":"traceutil/trace.go:171","msg":"trace[281235725] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"128.39577ms","start":"2026-09-07T11:36:22.847569Z","end":"2026-09-07T11:36:22.975965Z","steps":["trace[281235725] 'process raft request' (duration: 98.154867ms)","trace[281235725] 'compare' (duration: 29.979277ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:23.139133Z","caller":"traceutil/trace.go:171","msg":"trace[502826674] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"291.273866ms","start":"2026-09-07T11:36:22.847831Z","end":"2026-09-07T11:36:23.139105Z","steps":["trace[502826674] 'process raft request' (duration: 272.107922ms)","trace[502826674] 'compare' (duration: 18.963439ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:23.13929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.023406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:23.139334Z","caller":"traceutil/trace.go:171","msg":"trace[1429571977] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"291.372789ms","start":"2026-09-07T11:36:22.847931Z","end":"2026-09-07T11:36:23.139303Z","steps":["trace[1429571977] 'process raft request' (duration: 291.083352ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:23.139403Z","caller":"traceutil/trace.go:171","msg":"trace[508018994] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"290.866076ms","start":"2026-09-07T11:36:22.848524Z","end":"2026-09-07T11:36:23.13939Z","steps":["trace[508018994] 'process raft request' (duration: 290.532888ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:23.139154Z","caller":"traceutil/trace.go:171","msg":"trace[1656771136] linearizableReadLoop","detail":"{readStateIndex:25819; appliedIndex:25816; }","duration":"163.201113ms","start":"2026-09-07T11:36:22.97593Z","end":"2026-09-07T11:36:23.139131Z","steps":["trace[1656771136] 'read index received' (duration: 144.103451ms)","trace[1656771136] 'applied index is now lower than readState.Index' (duration: 19.096732ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:23.139359Z","caller":"traceutil/trace.go:171","msg":"trace[1399849895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25003; }","duration":"278.149748ms","start":"2026-09-07T11:36:22.861191Z","end":"2026-09-07T11:36:23.139341Z","steps":["trace[1399849895] 'agreement among raft nodes before linearized reading' (duration: 278.006415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:23.139692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.544465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-07T11:36:23.139734Z","caller":"traceutil/trace.go:171","msg":"trace[1418182597] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25003; }","duration":"159.601936ms","start":"2026-09-07T11:36:22.980122Z","end":"2026-09-07T11:36:23.139724Z","steps":["trace[1418182597] 'agreement among raft nodes before linearized reading' (duration: 159.445783ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:23.372519Z","caller":"traceutil/trace.go:171","msg":"trace[1585114712] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"143.962687ms","start":"2026-09-07T11:36:23.228514Z","end":"2026-09-07T11:36:23.372476Z","steps":["trace[1585114712] 'process raft request' (duration: 49.806456ms)","trace[1585114712] 'compare' (duration: 93.837694ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:24.486874Z","caller":"traceutil/trace.go:171","msg":"trace[1023745285] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"221.026545ms","start":"2026-09-07T11:36:24.265816Z","end":"2026-09-07T11:36:24.486843Z","steps":["trace[1023745285] 'process raft request' (duration: 220.727377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:24.72805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.759842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:36:24.728283Z","caller":"traceutil/trace.go:171","msg":"trace[681258171] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"278.252731ms","start":"2026-09-07T11:36:24.449997Z","end":"2026-09-07T11:36:24.72825Z","steps":["trace[681258171] 'process raft request' (duration: 89.151711ms)","trace[681258171] 'compare' (duration: 188.406234ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:24.728457Z","caller":"traceutil/trace.go:171","msg":"trace[1381145329] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"227.48868ms","start":"2026-09-07T11:36:24.500944Z","end":"2026-09-07T11:36:24.728432Z","steps":["trace[1381145329] 'process raft request' (duration: 227.249965ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:24.728509Z","caller":"traceutil/trace.go:171","msg":"trace[424673285] linearizableReadLoop","detail":"{readStateIndex:25825; appliedIndex:25824; }","duration":"229.172863ms","start":"2026-09-07T11:36:24.499309Z","end":"2026-09-07T11:36:24.728482Z","steps":["trace[424673285] 'read index received' (duration: 39.826375ms)","trace[424673285] 'applied index is now lower than readState.Index' (duration: 189.179043ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:24.728771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.434458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:24.728859Z","caller":"traceutil/trace.go:171","msg":"trace[1836417028] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25010; }","duration":"229.568372ms","start":"2026-09-07T11:36:24.499266Z","end":"2026-09-07T11:36:24.728835Z","steps":["trace[1836417028] 'agreement among raft nodes before linearized reading' (duration: 229.227574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:24.729035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.776172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-07T11:36:24.729091Z","caller":"traceutil/trace.go:171","msg":"trace[1136579940] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25011; }","duration":"228.901635ms","start":"2026-09-07T11:36:24.500176Z","end":"2026-09-07T11:36:24.729078Z","steps":["trace[1136579940] 'agreement among raft nodes before linearized reading' (duration: 228.68722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:24.730171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.140183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-09-07T11:36:24.730311Z","caller":"traceutil/trace.go:171","msg":"trace[323036295] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25011; }","duration":"187.305327ms","start":"2026-09-07T11:36:24.54298Z","end":"2026-09-07T11:36:24.730285Z","steps":["trace[323036295] 'agreement among raft nodes before linearized reading' (duration: 186.120499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:24.954254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.982146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T11:36:24.954325Z","caller":"traceutil/trace.go:171","msg":"trace[1034364437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25011; }","duration":"108.057897ms","start":"2026-09-07T11:36:24.846252Z","end":"2026-09-07T11:36:24.954309Z","steps":["trace[1034364437] 'range keys from in-memory index tree' (duration: 107.792621ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:25.085817Z","caller":"traceutil/trace.go:171","msg":"trace[199466159] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25828; }","duration":"105.68456ms","start":"2026-09-07T11:36:24.980108Z","end":"2026-09-07T11:36:25.085793Z","steps":["trace[199466159] 'read index received' (duration: 105.395523ms)","trace[199466159] 'applied index is now lower than readState.Index' (duration: 288.117µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:25.086009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.888365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T11:36:25.086048Z","caller":"traceutil/trace.go:171","msg":"trace[374971112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25012; }","duration":"105.974837ms","start":"2026-09-07T11:36:24.980063Z","end":"2026-09-07T11:36:25.086038Z","steps":["trace[374971112] 'agreement among raft nodes before linearized reading' (duration: 105.824853ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:25.086008Z","caller":"traceutil/trace.go:171","msg":"trace[1281722231] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"126.520064ms","start":"2026-09-07T11:36:24.959375Z","end":"2026-09-07T11:36:25.085895Z","steps":["trace[1281722231] 'process raft request' (duration: 126.219637ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:25.494421Z","caller":"traceutil/trace.go:171","msg":"trace[564512824] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"117.028295ms","start":"2026-09-07T11:36:25.377367Z","end":"2026-09-07T11:36:25.494396Z","steps":["trace[564512824] 'process raft request' (duration: 116.775509ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.172391Z","caller":"traceutil/trace.go:171","msg":"trace[2122290683] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"100.207997ms","start":"2026-09-07T11:36:26.072161Z","end":"2026-09-07T11:36:26.172369Z","steps":["trace[2122290683] 'process raft request' (duration: 99.708746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:26.92245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.521723ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-07T11:36:26.922872Z","caller":"traceutil/trace.go:171","msg":"trace[75520493] linearizableReadLoop","detail":"{readStateIndex:25840; appliedIndex:25838; }","duration":"189.290876ms","start":"2026-09-07T11:36:26.733565Z","end":"2026-09-07T11:36:26.922856Z","steps":["trace[75520493] 'read index received' (duration: 79.874076ms)","trace[75520493] 'applied index is now lower than readState.Index' (duration: 109.41571ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:26.922947Z","caller":"traceutil/trace.go:171","msg":"trace[2097939792] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"189.456879ms","start":"2026-09-07T11:36:26.733481Z","end":"2026-09-07T11:36:26.922938Z","steps":["trace[2097939792] 'process raft request' (duration: 189.06285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:26.930686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.105185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:26.930733Z","caller":"traceutil/trace.go:171","msg":"trace[1003986714] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25022; }","duration":"197.200777ms","start":"2026-09-07T11:36:26.733522Z","end":"2026-09-07T11:36:26.930722Z","steps":["trace[1003986714] 'agreement among raft nodes before linearized reading' (duration: 189.44673ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.938608Z","caller":"traceutil/trace.go:171","msg":"trace[1159088082] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"204.888374ms","start":"2026-09-07T11:36:26.733699Z","end":"2026-09-07T11:36:26.938587Z","steps":["trace[1159088082] 'process raft request' (duration: 204.72361ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.938608Z","caller":"traceutil/trace.go:171","msg":"trace[1368932718] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"204.119625ms","start":"2026-09-07T11:36:26.734477Z","end":"2026-09-07T11:36:26.938597Z","steps":["trace[1368932718] 'process raft request' (duration: 204.054373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:26.938893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.964054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:36:26.938884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.011616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T11:36:26.938949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.311444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:26.938963Z","caller":"traceutil/trace.go:171","msg":"trace[978937853] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25025; }","duration":"205.116678ms","start":"2026-09-07T11:36:26.733829Z","end":"2026-09-07T11:36:26.938946Z","steps":["trace[978937853] 'agreement among raft nodes before linearized reading' (duration: 204.968374ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.938937Z","caller":"traceutil/trace.go:171","msg":"trace[1247136420] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25025; }","duration":"205.023116ms","start":"2026-09-07T11:36:26.733904Z","end":"2026-09-07T11:36:26.938927Z","steps":["trace[1247136420] 'agreement among raft nodes before linearized reading' (duration: 204.963995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:26.93884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.820677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-07T11:36:26.939045Z","caller":"traceutil/trace.go:171","msg":"trace[1435477214] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25025; }","duration":"204.057383ms","start":"2026-09-07T11:36:26.734975Z","end":"2026-09-07T11:36:26.939033Z","steps":["trace[1435477214] 'agreement among raft nodes before linearized reading' (duration: 203.756196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:26.939083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.276483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-07T11:36:26.939229Z","caller":"traceutil/trace.go:171","msg":"trace[1970853900] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"203.575882ms","start":"2026-09-07T11:36:26.735645Z","end":"2026-09-07T11:36:26.939221Z","steps":["trace[1970853900] 'process raft request' (duration: 202.919246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:26.939243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.564467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-09-07T11:36:26.939201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.436166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:36:26.939275Z","caller":"traceutil/trace.go:171","msg":"trace[1217690392] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25025; }","duration":"171.622068ms","start":"2026-09-07T11:36:26.767644Z","end":"2026-09-07T11:36:26.939266Z","steps":["trace[1217690392] 'agreement among raft nodes before linearized reading' (duration: 171.518095ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.939304Z","caller":"traceutil/trace.go:171","msg":"trace[1783088335] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25025; }","duration":"205.586551ms","start":"2026-09-07T11:36:26.733697Z","end":"2026-09-07T11:36:26.939283Z","steps":["trace[1783088335] 'agreement among raft nodes before linearized reading' (duration: 205.428406ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.939501Z","caller":"traceutil/trace.go:171","msg":"trace[99113773] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25025; }","duration":"205.712345ms","start":"2026-09-07T11:36:26.733781Z","end":"2026-09-07T11:36:26.939493Z","steps":["trace[99113773] 'agreement among raft nodes before linearized reading' (duration: 205.224892ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:26.938991Z","caller":"traceutil/trace.go:171","msg":"trace[422888461] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25025; }","duration":"205.387656ms","start":"2026-09-07T11:36:26.733592Z","end":"2026-09-07T11:36:26.93898Z","steps":["trace[422888461] 'agreement among raft nodes before linearized reading' (duration: 205.118949ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:27.285762Z","caller":"traceutil/trace.go:171","msg":"trace[372561540] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25843; }","duration":"194.986884ms","start":"2026-09-07T11:36:27.090742Z","end":"2026-09-07T11:36:27.285729Z","steps":["trace[372561540] 'read index received' (duration: 129.758144ms)","trace[372561540] 'applied index is now lower than readState.Index' (duration: 65.2272ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:27.285945Z","caller":"traceutil/trace.go:171","msg":"trace[478384342] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"342.61932ms","start":"2026-09-07T11:36:26.943303Z","end":"2026-09-07T11:36:27.285923Z","steps":["trace[478384342] 'process raft request' (duration: 277.251116ms)","trace[478384342] 'compare' (duration: 64.688337ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:27.286048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:36:26.943279Z","time spent":"342.712072ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:36:27.286127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.365383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T11:36:27.286195Z","caller":"traceutil/trace.go:171","msg":"trace[517164330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25026; }","duration":"195.477416ms","start":"2026-09-07T11:36:27.0907Z","end":"2026-09-07T11:36:27.286178Z","steps":["trace[517164330] 'agreement among raft nodes before linearized reading' (duration: 195.266331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:27.328095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.14507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T11:36:27.328154Z","caller":"traceutil/trace.go:171","msg":"trace[229386522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25027; }","duration":"193.364474ms","start":"2026-09-07T11:36:27.134778Z","end":"2026-09-07T11:36:27.328142Z","steps":["trace[229386522] 'agreement among raft nodes before linearized reading' (duration: 193.205751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:27.534405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.279714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:36:27.534555Z","caller":"traceutil/trace.go:171","msg":"trace[312804790] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"201.570403ms","start":"2026-09-07T11:36:27.332975Z","end":"2026-09-07T11:36:27.534546Z","steps":["trace[312804790] 'process raft request' (duration: 201.540482ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:27.534542Z","caller":"traceutil/trace.go:171","msg":"trace[1673141353] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"243.850177ms","start":"2026-09-07T11:36:27.290663Z","end":"2026-09-07T11:36:27.534513Z","steps":["trace[1673141353] 'process raft request' (duration: 142.39373ms)","trace[1673141353] 'compare' (duration: 101.196581ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:36:27.53466Z","caller":"traceutil/trace.go:171","msg":"trace[984973368] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"201.83751ms","start":"2026-09-07T11:36:27.332815Z","end":"2026-09-07T11:36:27.534652Z","steps":["trace[984973368] 'process raft request' (duration: 201.666506ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:36:27.53483Z","caller":"traceutil/trace.go:171","msg":"trace[682129316] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"206.883311ms","start":"2026-09-07T11:36:27.327928Z","end":"2026-09-07T11:36:27.534811Z","steps":["trace[682129316] 'read index received' (duration: 105.142637ms)","trace[682129316] 'applied index is now lower than readState.Index' (duration: 101.738994ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:36:27.535015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.442552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-07T11:36:27.535077Z","caller":"traceutil/trace.go:171","msg":"trace[111022685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25030; }","duration":"244.614376ms","start":"2026-09-07T11:36:27.290446Z","end":"2026-09-07T11:36:27.53506Z","steps":["trace[111022685] 'agreement among raft nodes before linearized reading' (duration: 244.447332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:36:27.535027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.037369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-07T11:36:27.535159Z","caller":"traceutil/trace.go:171","msg":"trace[1535856573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25030; }","duration":"248.200723ms","start":"2026-09-07T11:36:27.286948Z","end":"2026-09-07T11:36:27.535148Z","steps":["trace[1535856573] 'agreement among raft nodes before linearized reading' (duration: 247.946317ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:37:28.031653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23022} {"level":"info","ts":"2026-09-07T11:37:28.199023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23022,"took":"162.024055ms","hash":4162521043,"current-db-size-bytes":56635392,"current-db-size":"57 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T11:37:28.199095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4162521043,"revision":23022,"compact-revision":20104}