{"level":"warn","ts":"2026-10-03T05:50:56.842104Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T05:50:56.842209Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-03T05:50:56.842293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T05:50:56.84231Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-10-03T05:50:56.84234Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-03T05:50:56.843191Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-10-03T05:50:56.843289Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-03T05:50:56.847471Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.884887ms"} {"level":"info","ts":"2026-10-03T05:50:56.855258Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-10-03T05:50:56.855348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T05:50:56.855372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-10-03T05:50:56.855389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T05:50:56.8554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-10-03T05:50:56.855445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-10-03T05:50:56.866913Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T05:50:56.868422Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T05:50:56.869928Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-03T05:50:56.872721Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T05:50:56.877484Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T05:50:56.877604Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T05:50:56.877891Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T05:50:56.877934Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T05:50:56.87849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-10-03T05:50:56.878653Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-10-03T05:50:56.878726Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-03T05:50:56.878847Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-10-03T05:50:56.878904Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-10-03T05:50:56.879241Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T05:50:56.879293Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T05:50:56.956431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T05:50:56.95649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T05:50:56.956518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-10-03T05:50:56.956539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-10-03T05:50:56.956569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-10-03T05:50:56.956587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-10-03T05:50:56.956612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-10-03T05:50:56.958062Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:50:56.959523Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T05:50:56.959525Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T05:50:56.959568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T05:50:56.960862Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:50:56.96104Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:50:56.961085Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:50:56.961111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T05:50:56.96121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T05:50:56.962042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-10-03T05:50:56.963544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T05:51:08.866232Z","caller":"traceutil/trace.go:171","msg":"trace[1346291670] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"204.913298ms","start":"2026-10-03T05:51:08.661272Z","end":"2026-10-03T05:51:08.866186Z","steps":["trace[1346291670] 'process raft request' (duration: 204.696672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:09.407584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.422307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T05:51:09.407847Z","caller":"traceutil/trace.go:171","msg":"trace[1989718978] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"534.185441ms","start":"2026-10-03T05:51:08.873614Z","end":"2026-10-03T05:51:09.407799Z","steps":["trace[1989718978] 'process raft request' (duration: 338.989343ms)","trace[1989718978] 'compare' (duration: 194.283343ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:51:09.407924Z","caller":"traceutil/trace.go:171","msg":"trace[1930695381] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"375.885615ms","start":"2026-10-03T05:51:09.031983Z","end":"2026-10-03T05:51:09.407869Z","steps":["trace[1930695381] 'read index received' (duration: 180.558803ms)","trace[1930695381] 'applied index is now lower than readState.Index' (duration: 195.320562ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:51:09.408117Z","caller":"traceutil/trace.go:171","msg":"trace[1104628785] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"118.213976ms","start":"2026-10-03T05:51:09.289644Z","end":"2026-10-03T05:51:09.407858Z","steps":["trace[1104628785] 'process raft request' (duration: 118.135644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:09.408101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:08.873584Z","time spent":"534.377887ms","remote":"127.0.0.1:50550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T05:51:09.408254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.232515ms","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-10-03T05:51:09.408331Z","caller":"traceutil/trace.go:171","msg":"trace[1483925291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:354; }","duration":"376.420039ms","start":"2026-10-03T05:51:09.031893Z","end":"2026-10-03T05:51:09.408313Z","steps":["trace[1483925291] 'agreement among raft nodes before linearized reading' (duration: 376.182173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:09.408386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:09.031838Z","time spent":"376.533083ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-03T05:51:09.40864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.65442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T05:51:09.408762Z","caller":"traceutil/trace.go:171","msg":"trace[247401110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:354; }","duration":"300.832805ms","start":"2026-10-03T05:51:09.107908Z","end":"2026-10-03T05:51:09.408741Z","steps":["trace[247401110] 'agreement among raft nodes before linearized reading' (duration: 300.615949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:09.408819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:09.107887Z","time spent":"300.911698ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T05:51:42.473979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.930269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:51:42.474102Z","caller":"traceutil/trace.go:171","msg":"trace[101096634] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:585; }","duration":"200.109375ms","start":"2026-10-03T05:51:42.273968Z","end":"2026-10-03T05:51:42.474077Z","steps":["trace[101096634] 'range keys from in-memory index tree' (duration: 199.847487ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:51:43.371125Z","caller":"traceutil/trace.go:171","msg":"trace[1345914543] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"603.174473ms","start":"2026-10-03T05:51:42.767923Z","end":"2026-10-03T05:51:43.371097Z","steps":["trace[1345914543] 'process raft request' (duration: 602.944267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:43.371282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:42.76789Z","time spent":"603.316407ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T05:51:43.806553Z","caller":"traceutil/trace.go:171","msg":"trace[757124271] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"766.099268ms","start":"2026-10-03T05:51:43.040421Z","end":"2026-10-03T05:51:43.80652Z","steps":["trace[757124271] 'read index received' (duration: 330.312743ms)","trace[757124271] 'applied index is now lower than readState.Index' (duration: 435.785295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:51:43.806751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.310613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:51:43.806724Z","caller":"traceutil/trace.go:171","msg":"trace[497183066] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"497.593248ms","start":"2026-10-03T05:51:43.30908Z","end":"2026-10-03T05:51:43.806673Z","steps":["trace[497183066] 'process raft request' (duration: 497.390992ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:51:43.806804Z","caller":"traceutil/trace.go:171","msg":"trace[2088329693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"766.443687ms","start":"2026-10-03T05:51:43.040349Z","end":"2026-10-03T05:51:43.806793Z","steps":["trace[2088329693] 'agreement among raft nodes before linearized reading' (duration: 766.340584ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:51:43.806747Z","caller":"traceutil/trace.go:171","msg":"trace[705824119] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"869.659486ms","start":"2026-10-03T05:51:42.936994Z","end":"2026-10-03T05:51:43.806653Z","steps":["trace[705824119] 'process raft request' (duration: 868.889065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:43.806836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:43.040323Z","time spent":"766.507159ms","remote":"127.0.0.1:50070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T05:51:43.806939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:43.309044Z","time spent":"497.773823ms","remote":"127.0.0.1:50338","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-10-03T05:51:43.806956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.163441ms","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-10-03T05:51:43.807047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:51:42.936951Z","time spent":"869.939595ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T05:51:43.80706Z","caller":"traceutil/trace.go:171","msg":"trace[941531473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"198.338956ms","start":"2026-10-03T05:51:43.608694Z","end":"2026-10-03T05:51:43.807033Z","steps":["trace[941531473] 'agreement among raft nodes before linearized reading' (duration: 198.030417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:51:43.806957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.442936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-03T05:51:43.80731Z","caller":"traceutil/trace.go:171","msg":"trace[1143728484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"151.857158ms","start":"2026-10-03T05:51:43.655423Z","end":"2026-10-03T05:51:43.80728Z","steps":["trace[1143728484] 'agreement among raft nodes before linearized reading' (duration: 151.359914ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:51:45.137397Z","caller":"traceutil/trace.go:171","msg":"trace[754375587] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"114.280688ms","start":"2026-10-03T05:51:45.023066Z","end":"2026-10-03T05:51:45.137347Z","steps":["trace[754375587] 'process raft request' (duration: 113.185728ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:52:29.576259Z","caller":"traceutil/trace.go:171","msg":"trace[2017298601] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"184.62506ms","start":"2026-10-03T05:52:29.391603Z","end":"2026-10-03T05:52:29.576228Z","steps":["trace[2017298601] 'process raft request' (duration: 184.476706ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:52:50.320979Z","caller":"traceutil/trace.go:171","msg":"trace[173053801] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"137.797472ms","start":"2026-10-03T05:52:50.183122Z","end":"2026-10-03T05:52:50.320919Z","steps":["trace[173053801] 'process raft request' (duration: 137.590566ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:52:50.378309Z","caller":"traceutil/trace.go:171","msg":"trace[1345675251] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1457; }","duration":"113.370842ms","start":"2026-10-03T05:52:50.264906Z","end":"2026-10-03T05:52:50.378277Z","steps":["trace[1345675251] 'read index received' (duration: 55.934145ms)","trace[1345675251] 'applied index is now lower than readState.Index' (duration: 57.435407ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:52:50.378402Z","caller":"traceutil/trace.go:171","msg":"trace[1497196999] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"192.883855ms","start":"2026-10-03T05:52:50.185475Z","end":"2026-10-03T05:52:50.378359Z","steps":["trace[1497196999] 'process raft request' (duration: 192.638358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:52:50.378459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.523776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:52:50.378497Z","caller":"traceutil/trace.go:171","msg":"trace[1090344758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1415; }","duration":"113.617598ms","start":"2026-10-03T05:52:50.264868Z","end":"2026-10-03T05:52:50.378486Z","steps":["trace[1090344758] 'agreement among raft nodes before linearized reading' (duration: 113.526896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:53:15.291381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.955798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T05:53:15.291503Z","caller":"traceutil/trace.go:171","msg":"trace[2077054101] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"114.171194ms","start":"2026-10-03T05:53:15.177315Z","end":"2026-10-03T05:53:15.291486Z","steps":["trace[2077054101] 'read index received' (duration: 7.966779ms)","trace[2077054101] 'applied index is now lower than readState.Index' (duration: 106.203015ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:53:15.29158Z","caller":"traceutil/trace.go:171","msg":"trace[183773840] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"187.442345ms","start":"2026-10-03T05:53:15.104126Z","end":"2026-10-03T05:53:15.291568Z","steps":["trace[183773840] 'process raft request' (duration: 81.215049ms)","trace[183773840] 'compare' (duration: 105.724212ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:53:15.291907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.576105ms","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-10-03T05:53:15.291987Z","caller":"traceutil/trace.go:171","msg":"trace[1139041824] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1819; }","duration":"114.709569ms","start":"2026-10-03T05:53:15.177259Z","end":"2026-10-03T05:53:15.291968Z","steps":["trace[1139041824] 'agreement among raft nodes before linearized reading' (duration: 114.583985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:53:59.12993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.976655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-10-03T05:53:59.129967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.117226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122066"} {"level":"info","ts":"2026-10-03T05:53:59.130115Z","caller":"traceutil/trace.go:171","msg":"trace[934946280] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2114; }","duration":"145.322412ms","start":"2026-10-03T05:53:58.984755Z","end":"2026-10-03T05:53:59.130078Z","steps":["trace[934946280] 'range keys from in-memory index tree' (duration: 144.832319ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:53:59.130029Z","caller":"traceutil/trace.go:171","msg":"trace[1517814869] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2114; }","duration":"136.105638ms","start":"2026-10-03T05:53:58.993902Z","end":"2026-10-03T05:53:59.130007Z","steps":["trace[1517814869] 'range keys from in-memory index tree' (duration: 135.899482ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:54:05.060154Z","caller":"traceutil/trace.go:171","msg":"trace[2058934033] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"159.478332ms","start":"2026-10-03T05:54:04.900643Z","end":"2026-10-03T05:54:05.060122Z","steps":["trace[2058934033] 'process raft request' (duration: 159.299377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:55:30.530067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.624157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T05:55:30.530203Z","caller":"traceutil/trace.go:171","msg":"trace[1728050026] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"195.21886ms","start":"2026-10-03T05:55:30.334964Z","end":"2026-10-03T05:55:30.530183Z","steps":["trace[1728050026] 'process raft request' (duration: 60.399757ms)","trace[1728050026] 'compare' (duration: 134.510354ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:55:30.738855Z","caller":"traceutil/trace.go:171","msg":"trace[1949369738] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"109.188695ms","start":"2026-10-03T05:55:30.629628Z","end":"2026-10-03T05:55:30.738817Z","steps":["trace[1949369738] 'process raft request' (duration: 35.227313ms)","trace[1949369738] 'compare' (duration: 73.797378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:58:53.566708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.756262ms","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-10-03T05:58:53.566862Z","caller":"traceutil/trace.go:171","msg":"trace[1335795509] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4072; }","duration":"144.988208ms","start":"2026-10-03T05:58:53.421832Z","end":"2026-10-03T05:58:53.56682Z","steps":["trace[1335795509] 'range keys from in-memory index tree' (duration: 144.481664ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:12.909824Z","caller":"traceutil/trace.go:171","msg":"trace[595027454] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"100.070095ms","start":"2026-10-03T06:00:12.809713Z","end":"2026-10-03T06:00:12.909783Z","steps":["trace[595027454] 'process raft request' (duration: 99.87874ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:21.730127Z","caller":"traceutil/trace.go:171","msg":"trace[1287022918] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"119.775809ms","start":"2026-10-03T06:00:21.610279Z","end":"2026-10-03T06:00:21.730054Z","steps":["trace[1287022918] 'process raft request' (duration: 119.477601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:23.074003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.909732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5bf06f45-8387-4687-9f90-a1bb1fa034da\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-10-03T06:00:23.074139Z","caller":"traceutil/trace.go:171","msg":"trace[544824465] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5bf06f45-8387-4687-9f90-a1bb1fa034da; range_end:; response_count:1; response_revision:5072; }","duration":"137.118698ms","start":"2026-10-03T06:00:22.936992Z","end":"2026-10-03T06:00:23.074111Z","steps":["trace[544824465] 'range keys from in-memory index tree' (duration: 136.637955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:23.07436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.929571ms","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-10-03T06:00:23.074475Z","caller":"traceutil/trace.go:171","msg":"trace[902533006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5072; }","duration":"104.097396ms","start":"2026-10-03T06:00:22.970353Z","end":"2026-10-03T06:00:23.074451Z","steps":["trace[902533006] 'range keys from in-memory index tree' (duration: 103.798978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:23.075011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.833081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526871"} {"level":"info","ts":"2026-10-03T06:00:23.075064Z","caller":"traceutil/trace.go:171","msg":"trace[618508677] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5072; }","duration":"153.936293ms","start":"2026-10-03T06:00:22.921113Z","end":"2026-10-03T06:00:23.075049Z","steps":["trace[618508677] 'range keys from in-memory index tree' (duration: 153.261025ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:28.609731Z","caller":"traceutil/trace.go:171","msg":"trace[58622738] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"204.256052ms","start":"2026-10-03T06:00:28.405433Z","end":"2026-10-03T06:00:28.609689Z","steps":["trace[58622738] 'process raft request' (duration: 157.800329ms)","trace[58622738] 'compare' (duration: 46.130154ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:00:28.609995Z","caller":"traceutil/trace.go:171","msg":"trace[1794537511] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"203.649426ms","start":"2026-10-03T06:00:28.40631Z","end":"2026-10-03T06:00:28.60996Z","steps":["trace[1794537511] 'process raft request' (duration: 203.401759ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:28.610123Z","caller":"traceutil/trace.go:171","msg":"trace[1122550880] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"193.011402ms","start":"2026-10-03T06:00:28.417098Z","end":"2026-10-03T06:00:28.61011Z","steps":["trace[1122550880] 'process raft request' (duration: 192.717494ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:28.610244Z","caller":"traceutil/trace.go:171","msg":"trace[1091727958] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"204.067527ms","start":"2026-10-03T06:00:28.406163Z","end":"2026-10-03T06:00:28.610231Z","steps":["trace[1091727958] 'process raft request' (duration: 203.43275ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:28.610277Z","caller":"traceutil/trace.go:171","msg":"trace[255976920] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"203.561503ms","start":"2026-10-03T06:00:28.406705Z","end":"2026-10-03T06:00:28.610267Z","steps":["trace[255976920] 'process raft request' (duration: 203.052049ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:28.610329Z","caller":"traceutil/trace.go:171","msg":"trace[579735095] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5390; }","duration":"160.664168ms","start":"2026-10-03T06:00:28.449633Z","end":"2026-10-03T06:00:28.610297Z","steps":["trace[579735095] 'read index received' (duration: 113.616028ms)","trace[579735095] 'applied index is now lower than readState.Index' (duration: 47.0468ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:00:28.610406Z","caller":"traceutil/trace.go:171","msg":"trace[175495787] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"204.13913ms","start":"2026-10-03T06:00:28.406255Z","end":"2026-10-03T06:00:28.610394Z","steps":["trace[175495787] 'process raft request' (duration: 203.41174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:28.610702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.629075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-10-03T06:00:28.61071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.029799ms","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-10-03T06:00:28.610752Z","caller":"traceutil/trace.go:171","msg":"trace[1901908880] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5197; }","duration":"119.746568ms","start":"2026-10-03T06:00:28.49099Z","end":"2026-10-03T06:00:28.610736Z","steps":["trace[1901908880] 'agreement among raft nodes before linearized reading' (duration: 119.518092ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:28.610774Z","caller":"traceutil/trace.go:171","msg":"trace[821416438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5197; }","duration":"161.167573ms","start":"2026-10-03T06:00:28.449587Z","end":"2026-10-03T06:00:28.610754Z","steps":["trace[821416438] 'agreement among raft nodes before linearized reading' (duration: 160.887685ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:31.901947Z","caller":"traceutil/trace.go:171","msg":"trace[424787058] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"372.50124ms","start":"2026-10-03T06:00:31.529416Z","end":"2026-10-03T06:00:31.901917Z","steps":["trace[424787058] 'process raft request' (duration: 307.442883ms)","trace[424787058] 'compare' (duration: 64.844341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:00:31.902097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:00:31.529391Z","time spent":"372.637995ms","remote":"127.0.0.1:50418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1001,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T06:00:31.921226Z","caller":"traceutil/trace.go:171","msg":"trace[353580714] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"129.855887ms","start":"2026-10-03T06:00:31.791354Z","end":"2026-10-03T06:00:31.92121Z","steps":["trace[353580714] 'process raft request' (duration: 129.724324ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:31.921224Z","caller":"traceutil/trace.go:171","msg":"trace[1027840091] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"368.87117ms","start":"2026-10-03T06:00:31.552322Z","end":"2026-10-03T06:00:31.921193Z","steps":["trace[1027840091] 'process raft request' (duration: 368.615113ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:31.921145Z","caller":"traceutil/trace.go:171","msg":"trace[453936595] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5512; }","duration":"310.103887ms","start":"2026-10-03T06:00:31.611013Z","end":"2026-10-03T06:00:31.921116Z","steps":["trace[453936595] 'read index received' (duration: 225.86478ms)","trace[453936595] 'applied index is now lower than readState.Index' (duration: 84.237927ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:00:31.921471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.433526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:00:31.921539Z","caller":"traceutil/trace.go:171","msg":"trace[920402495] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5312; }","duration":"310.56573ms","start":"2026-10-03T06:00:31.610955Z","end":"2026-10-03T06:00:31.921521Z","steps":["trace[920402495] 'agreement among raft nodes before linearized reading' (duration: 310.430276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:31.921607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:00:31.610931Z","time spent":"310.661152ms","remote":"127.0.0.1:54112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:00:31.921675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:00:31.552291Z","time spent":"369.128217ms","remote":"127.0.0.1:50338","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-10-03T06:00:31.925897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.637146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T06:00:31.925955Z","caller":"traceutil/trace.go:171","msg":"trace[1531329093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5313; }","duration":"106.755269ms","start":"2026-10-03T06:00:31.819185Z","end":"2026-10-03T06:00:31.92594Z","steps":["trace[1531329093] 'agreement among raft nodes before linearized reading' (duration: 106.541763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:31.926218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.32736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5476053-1066-484b-8579-d1936d2263eb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-03T06:00:31.92626Z","caller":"traceutil/trace.go:171","msg":"trace[348212893] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5476053-1066-484b-8579-d1936d2263eb; range_end:; response_count:1; response_revision:5313; }","duration":"252.412053ms","start":"2026-10-03T06:00:31.673836Z","end":"2026-10-03T06:00:31.926248Z","steps":["trace[348212893] 'agreement among raft nodes before linearized reading' (duration: 251.807647ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:34.13895Z","caller":"traceutil/trace.go:171","msg":"trace[1304210547] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"156.240897ms","start":"2026-10-03T06:00:33.98267Z","end":"2026-10-03T06:00:34.138911Z","steps":["trace[1304210547] 'process raft request' (duration: 95.56824ms)","trace[1304210547] 'compare' (duration: 60.043689ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:00:47.012923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.485281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T06:00:47.013279Z","caller":"traceutil/trace.go:171","msg":"trace[2035010132] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"120.602442ms","start":"2026-10-03T06:00:46.892662Z","end":"2026-10-03T06:00:47.013264Z","steps":["trace[2035010132] 'process raft request' (duration: 120.55608ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:47.013323Z","caller":"traceutil/trace.go:171","msg":"trace[1004200639] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"122.834743ms","start":"2026-10-03T06:00:46.890476Z","end":"2026-10-03T06:00:47.013311Z","steps":["trace[1004200639] 'process raft request' (duration: 19.863198ms)","trace[1004200639] 'compare' (duration: 102.374788ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:00:47.013303Z","caller":"traceutil/trace.go:171","msg":"trace[800029891] transaction","detail":"{read_only:false; response_revision:5576; number_of_response:1; }","duration":"122.247937ms","start":"2026-10-03T06:00:46.891005Z","end":"2026-10-03T06:00:47.013253Z","steps":["trace[800029891] 'process raft request' (duration: 122.128764ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:47.013268Z","caller":"traceutil/trace.go:171","msg":"trace[1145599898] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"122.71976ms","start":"2026-10-03T06:00:46.890519Z","end":"2026-10-03T06:00:47.013238Z","steps":["trace[1145599898] 'process raft request' (duration: 122.540035ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:47.013502Z","caller":"traceutil/trace.go:171","msg":"trace[1292196113] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"120.984382ms","start":"2026-10-03T06:00:46.892493Z","end":"2026-10-03T06:00:47.013477Z","steps":["trace[1292196113] 'process raft request' (duration: 120.684874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:47.546413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.496524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T06:00:47.546669Z","caller":"traceutil/trace.go:171","msg":"trace[1882385853] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"257.580815ms","start":"2026-10-03T06:00:47.289031Z","end":"2026-10-03T06:00:47.546612Z","steps":["trace[1882385853] 'process raft request' (duration: 113.752532ms)","trace[1882385853] 'compare' (duration: 142.916878ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:00:55.881741Z","caller":"traceutil/trace.go:171","msg":"trace[2050342417] linearizableReadLoop","detail":"{readStateIndex:5975; appliedIndex:5974; }","duration":"202.907275ms","start":"2026-10-03T06:00:55.678807Z","end":"2026-10-03T06:00:55.881714Z","steps":["trace[2050342417] 'read index received' (duration: 46.094903ms)","trace[2050342417] 'applied index is now lower than readState.Index' (duration: 156.811322ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:00:55.8818Z","caller":"traceutil/trace.go:171","msg":"trace[1853918081] transaction","detail":"{read_only:false; response_revision:5761; number_of_response:1; }","duration":"217.977261ms","start":"2026-10-03T06:00:55.663772Z","end":"2026-10-03T06:00:55.881749Z","steps":["trace[1853918081] 'process raft request' (duration: 138.352962ms)","trace[1853918081] 'compare' (duration: 79.456305ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:00:55.881923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.10105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-899beaa6-8c95-4337-a777-318679a20036\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-03T06:00:55.881966Z","caller":"traceutil/trace.go:171","msg":"trace[135639353] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-899beaa6-8c95-4337-a777-318679a20036; range_end:; response_count:1; response_revision:5761; }","duration":"203.202044ms","start":"2026-10-03T06:00:55.67875Z","end":"2026-10-03T06:00:55.881952Z","steps":["trace[135639353] 'agreement among raft nodes before linearized reading' (duration: 203.06076ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:56.096743Z","caller":"traceutil/trace.go:171","msg":"trace[2091074197] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"111.239343ms","start":"2026-10-03T06:00:55.985455Z","end":"2026-10-03T06:00:56.096694Z","steps":["trace[2091074197] 'process raft request' (duration: 110.931015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:00:56.193877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.10419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-10-03T06:00:56.193878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.876903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-03T06:00:56.194086Z","caller":"traceutil/trace.go:171","msg":"trace[995342371] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5763; }","duration":"145.147059ms","start":"2026-10-03T06:00:56.048892Z","end":"2026-10-03T06:00:56.194039Z","steps":["trace[995342371] 'agreement among raft nodes before linearized reading' (duration: 144.81733ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:56.193987Z","caller":"traceutil/trace.go:171","msg":"trace[1878561679] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5763; }","duration":"136.303295ms","start":"2026-10-03T06:00:56.05766Z","end":"2026-10-03T06:00:56.193963Z","steps":["trace[1878561679] 'agreement among raft nodes before linearized reading' (duration: 135.961606ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:00:57.487531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2953} {"level":"info","ts":"2026-10-03T06:00:57.57218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2953,"took":"82.619572ms","hash":2325391372,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":31969280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T06:00:57.572248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2325391372,"revision":2953,"compact-revision":-1} {"level":"info","ts":"2026-10-03T06:05:57.49505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5782} {"level":"info","ts":"2026-10-03T06:05:57.586015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5782,"took":"88.829924ms","hash":1180523395,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":26456064,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-03T06:05:57.586141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1180523395,"revision":5782,"compact-revision":2953} {"level":"info","ts":"2026-10-03T06:07:05.370153Z","caller":"traceutil/trace.go:171","msg":"trace[1107344643] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"389.372898ms","start":"2026-10-03T06:07:04.980742Z","end":"2026-10-03T06:07:05.370115Z","steps":["trace[1107344643] 'process raft request' (duration: 389.146342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:05.370397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:04.980684Z","time spent":"389.583214ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:07:05.371087Z","caller":"traceutil/trace.go:171","msg":"trace[352174817] linearizableReadLoop","detail":"{readStateIndex:8904; appliedIndex:8902; }","duration":"329.655131ms","start":"2026-10-03T06:07:05.041397Z","end":"2026-10-03T06:07:05.371052Z","steps":["trace[352174817] 'read index received' (duration: 328.567481ms)","trace[352174817] 'applied index is now lower than readState.Index' (duration: 1.08665ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:07:05.371136Z","caller":"traceutil/trace.go:171","msg":"trace[2084610129] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"354.905148ms","start":"2026-10-03T06:07:05.016197Z","end":"2026-10-03T06:07:05.371102Z","steps":["trace[2084610129] 'process raft request' (duration: 354.730523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:05.371328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:05.016156Z","time spent":"355.062402ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:07:05.371277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.855247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:07:05.371432Z","caller":"traceutil/trace.go:171","msg":"trace[51017315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8599; }","duration":"330.081933ms","start":"2026-10-03T06:07:05.041322Z","end":"2026-10-03T06:07:05.371404Z","steps":["trace[51017315] 'agreement among raft nodes before linearized reading' (duration: 329.821406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:05.371542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:05.041294Z","time spent":"330.235477ms","remote":"127.0.0.1:50070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T06:07:17.626961Z","caller":"traceutil/trace.go:171","msg":"trace[1875644095] linearizableReadLoop","detail":"{readStateIndex:8970; appliedIndex:8969; }","duration":"152.832995ms","start":"2026-10-03T06:07:17.474086Z","end":"2026-10-03T06:07:17.626919Z","steps":["trace[1875644095] 'read index received' (duration: 152.487966ms)","trace[1875644095] 'applied index is now lower than readState.Index' (duration: 343.339µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:07:17.62706Z","caller":"traceutil/trace.go:171","msg":"trace[913375341] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"201.122577ms","start":"2026-10-03T06:07:17.425879Z","end":"2026-10-03T06:07:17.627001Z","steps":["trace[913375341] 'process raft request' (duration: 200.819978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:17.627263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.156834ms","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-10-03T06:07:17.627314Z","caller":"traceutil/trace.go:171","msg":"trace[2139047335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8661; }","duration":"153.274777ms","start":"2026-10-03T06:07:17.474025Z","end":"2026-10-03T06:07:17.6273Z","steps":["trace[2139047335] 'agreement among raft nodes before linearized reading' (duration: 153.092272ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:07:18.014399Z","caller":"traceutil/trace.go:171","msg":"trace[850289098] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"290.360107ms","start":"2026-10-03T06:07:17.724006Z","end":"2026-10-03T06:07:18.014366Z","steps":["trace[850289098] 'process raft request' (duration: 290.249584ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:07:18.014807Z","caller":"traceutil/trace.go:171","msg":"trace[385112871] linearizableReadLoop","detail":"{readStateIndex:8971; appliedIndex:8970; }","duration":"381.42559ms","start":"2026-10-03T06:07:17.633314Z","end":"2026-10-03T06:07:18.01474Z","steps":["trace[385112871] 'read index received' (duration: 359.414133ms)","trace[385112871] 'applied index is now lower than readState.Index' (duration: 22.005967ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:07:18.014917Z","caller":"traceutil/trace.go:171","msg":"trace[1089045274] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"382.233911ms","start":"2026-10-03T06:07:17.632601Z","end":"2026-10-03T06:07:18.014835Z","steps":["trace[1089045274] 'process raft request' (duration: 360.104591ms)","trace[1089045274] 'compare' (duration: 21.099412ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:07:18.015203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.753315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-03T06:07:18.015254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:17.63257Z","time spent":"382.55217ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:07:18.015321Z","caller":"traceutil/trace.go:171","msg":"trace[1120224108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8663; }","duration":"316.946401ms","start":"2026-10-03T06:07:17.698347Z","end":"2026-10-03T06:07:18.015293Z","steps":["trace[1120224108] 'agreement among raft nodes before linearized reading' (duration: 316.644373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:18.015325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.986665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223843"} {"level":"info","ts":"2026-10-03T06:07:18.015378Z","caller":"traceutil/trace.go:171","msg":"trace[494644374] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8663; }","duration":"382.096018ms","start":"2026-10-03T06:07:17.633266Z","end":"2026-10-03T06:07:18.015362Z","steps":["trace[494644374] 'agreement among raft nodes before linearized reading' (duration: 381.597494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:18.015373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:17.698313Z","time spent":"317.046534ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T06:07:18.015411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:17.633249Z","time spent":"382.152249ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":223867,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-03T06:07:18.623307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.329929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T06:07:18.623424Z","caller":"traceutil/trace.go:171","msg":"trace[701408804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8667; }","duration":"157.522785ms","start":"2026-10-03T06:07:18.465873Z","end":"2026-10-03T06:07:18.623396Z","steps":["trace[701408804] 'range keys from in-memory index tree' (duration: 156.931668ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:07:19.003216Z","caller":"traceutil/trace.go:171","msg":"trace[802531526] linearizableReadLoop","detail":"{readStateIndex:8977; appliedIndex:8976; }","duration":"359.760542ms","start":"2026-10-03T06:07:18.643425Z","end":"2026-10-03T06:07:19.003185Z","steps":["trace[802531526] 'read index received' (duration: 359.538886ms)","trace[802531526] 'applied index is now lower than readState.Index' (duration: 220.136µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:07:19.003298Z","caller":"traceutil/trace.go:171","msg":"trace[1757301983] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"374.066527ms","start":"2026-10-03T06:07:18.629188Z","end":"2026-10-03T06:07:19.003254Z","steps":["trace[1757301983] 'process raft request' (duration: 373.794379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:19.00357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.105891ms","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":"warn","ts":"2026-10-03T06:07:19.0036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.412597ms","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-10-03T06:07:19.003645Z","caller":"traceutil/trace.go:171","msg":"trace[747618224] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8668; }","duration":"167.467989ms","start":"2026-10-03T06:07:18.836163Z","end":"2026-10-03T06:07:19.003631Z","steps":["trace[747618224] 'agreement among raft nodes before linearized reading' (duration: 167.226452ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:07:19.003638Z","caller":"traceutil/trace.go:171","msg":"trace[768748420] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8668; }","duration":"360.208435ms","start":"2026-10-03T06:07:18.643413Z","end":"2026-10-03T06:07:19.003622Z","steps":["trace[768748420] 'agreement among raft nodes before linearized reading' (duration: 359.994059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:07:19.003704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:18.643362Z","time spent":"360.329838ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:07:19.00394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:07:18.629154Z","time spent":"374.292043ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:07:19.756392Z","caller":"traceutil/trace.go:171","msg":"trace[1084819788] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"122.363464ms","start":"2026-10-03T06:07:19.634005Z","end":"2026-10-03T06:07:19.756368Z","steps":["trace[1084819788] 'process raft request' (duration: 122.19458ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:07:48.031598Z","caller":"traceutil/trace.go:171","msg":"trace[1289156981] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"131.068474ms","start":"2026-10-03T06:07:47.900493Z","end":"2026-10-03T06:07:48.031561Z","steps":["trace[1289156981] 'process raft request' (duration: 130.827908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:08:02.823962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.673242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tttwm\" ","response":"range_response_count:1 size:4665"} {"level":"warn","ts":"2026-10-03T06:08:02.824014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.801062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tttwm\" ","response":"range_response_count:1 size:4665"} {"level":"info","ts":"2026-10-03T06:08:02.824102Z","caller":"traceutil/trace.go:171","msg":"trace[995055899] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tttwm; range_end:; response_count:1; response_revision:8995; }","duration":"102.862647ms","start":"2026-10-03T06:08:02.721208Z","end":"2026-10-03T06:08:02.824071Z","steps":["trace[995055899] 'range keys from in-memory index tree' (duration: 102.551028ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:08:02.824141Z","caller":"traceutil/trace.go:171","msg":"trace[55929198] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tttwm; range_end:; response_count:1; response_revision:8995; }","duration":"103.964117ms","start":"2026-10-03T06:08:02.72015Z","end":"2026-10-03T06:08:02.824114Z","steps":["trace[55929198] 'range keys from in-memory index tree' (duration: 103.6949ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:08:03.314578Z","caller":"traceutil/trace.go:171","msg":"trace[1593259030] transaction","detail":"{read_only:false; response_revision:8999; number_of_response:1; }","duration":"167.732745ms","start":"2026-10-03T06:08:03.146801Z","end":"2026-10-03T06:08:03.314534Z","steps":["trace[1593259030] 'process raft request' (duration: 167.498549ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:08:03.436846Z","caller":"traceutil/trace.go:171","msg":"trace[298426510] transaction","detail":"{read_only:false; response_revision:9000; number_of_response:1; }","duration":"115.513916ms","start":"2026-10-03T06:08:03.321296Z","end":"2026-10-03T06:08:03.43681Z","steps":["trace[298426510] 'process raft request' (duration: 94.604799ms)","trace[298426510] 'compare' (duration: 20.507285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:08:03.660655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.646574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T06:08:03.660962Z","caller":"traceutil/trace.go:171","msg":"trace[1840167151] linearizableReadLoop","detail":"{readStateIndex:9324; appliedIndex:9322; }","duration":"121.241154ms","start":"2026-10-03T06:08:03.539671Z","end":"2026-10-03T06:08:03.660913Z","steps":["trace[1840167151] 'read index received' (duration: 3.213919ms)","trace[1840167151] 'applied index is now lower than readState.Index' (duration: 118.026065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:08:03.661272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.575863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T06:08:03.661378Z","caller":"traceutil/trace.go:171","msg":"trace[1205201314] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:9002; }","duration":"121.747488ms","start":"2026-10-03T06:08:03.539603Z","end":"2026-10-03T06:08:03.661351Z","steps":["trace[1205201314] 'agreement among raft nodes before linearized reading' (duration: 121.547583ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:08:03.661331Z","caller":"traceutil/trace.go:171","msg":"trace[1603804987] transaction","detail":"{read_only:false; response_revision:9001; number_of_response:1; }","duration":"220.997335ms","start":"2026-10-03T06:08:03.440136Z","end":"2026-10-03T06:08:03.661134Z","steps":["trace[1603804987] 'process raft request' (duration: 102.748424ms)","trace[1603804987] 'compare' (duration: 117.329676ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:08:03.661854Z","caller":"traceutil/trace.go:171","msg":"trace[367015707] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"219.084142ms","start":"2026-10-03T06:08:03.441982Z","end":"2026-10-03T06:08:03.661066Z","steps":["trace[367015707] 'process raft request' (duration: 218.810365ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:08:16.308036Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d8.wal"} {"level":"info","ts":"2026-10-03T06:09:14.898594Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T06:09:14.905921Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-03T06:09:14.906061Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-03T06:09:24.467851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.049055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T06:09:24.468055Z","caller":"traceutil/trace.go:171","msg":"trace[1878197769] transaction","detail":"{read_only:false; response_revision:9712; number_of_response:1; }","duration":"144.485655ms","start":"2026-10-03T06:09:24.32355Z","end":"2026-10-03T06:09:24.468036Z","steps":["trace[1878197769] 'process raft request' (duration: 144.411973ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:09:24.4681Z","caller":"traceutil/trace.go:171","msg":"trace[1419111733] transaction","detail":"{read_only:false; response_revision:9711; number_of_response:1; }","duration":"244.463584ms","start":"2026-10-03T06:09:24.223589Z","end":"2026-10-03T06:09:24.468053Z","steps":["trace[1419111733] 'process raft request' (duration: 80.082729ms)","trace[1419111733] 'compare' (duration: 163.943693ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:10:57.503073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8010} {"level":"info","ts":"2026-10-03T06:10:57.58205Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8010,"took":"76.047988ms","hash":3727384146,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":28467200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T06:10:57.582132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3727384146,"revision":8010,"compact-revision":5782} {"level":"info","ts":"2026-10-03T06:13:07.598574Z","caller":"traceutil/trace.go:171","msg":"trace[1565995076] transaction","detail":"{read_only:false; response_revision:11650; number_of_response:1; }","duration":"107.548139ms","start":"2026-10-03T06:13:07.490991Z","end":"2026-10-03T06:13:07.598539Z","steps":["trace[1565995076] 'process raft request' (duration: 107.313403ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:15:57.509994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10465} {"level":"info","ts":"2026-10-03T06:15:57.605427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10465,"took":"91.554216ms","hash":1500811843,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":28160000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T06:15:57.605519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1500811843,"revision":10465,"compact-revision":8010} {"level":"info","ts":"2026-10-03T06:18:53.401576Z","caller":"traceutil/trace.go:171","msg":"trace[156633535] linearizableReadLoop","detail":"{readStateIndex:14503; appliedIndex:14502; }","duration":"137.371941ms","start":"2026-10-03T06:18:53.264174Z","end":"2026-10-03T06:18:53.401546Z","steps":["trace[156633535] 'read index received' (duration: 137.064493ms)","trace[156633535] 'applied index is now lower than readState.Index' (duration: 305.758µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:18:53.40193Z","caller":"traceutil/trace.go:171","msg":"trace[642779328] transaction","detail":"{read_only:false; response_revision:14019; number_of_response:1; }","duration":"139.110749ms","start":"2026-10-03T06:18:53.262804Z","end":"2026-10-03T06:18:53.401914Z","steps":["trace[642779328] 'process raft request' (duration: 138.539233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:18:53.402465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.265526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-03T06:18:53.402551Z","caller":"traceutil/trace.go:171","msg":"trace[1058046526] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:14019; }","duration":"138.388929ms","start":"2026-10-03T06:18:53.264141Z","end":"2026-10-03T06:18:53.40253Z","steps":["trace[1058046526] 'agreement among raft nodes before linearized reading' (duration: 138.173133ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:19:41.81876Z","caller":"traceutil/trace.go:171","msg":"trace[1083068541] transaction","detail":"{read_only:false; response_revision:14292; number_of_response:1; }","duration":"266.896066ms","start":"2026-10-03T06:19:41.551831Z","end":"2026-10-03T06:19:41.818727Z","steps":["trace[1083068541] 'process raft request' (duration: 266.69913ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:19:42.916649Z","caller":"traceutil/trace.go:171","msg":"trace[16458922] linearizableReadLoop","detail":"{readStateIndex:14798; appliedIndex:14796; }","duration":"139.17542ms","start":"2026-10-03T06:19:42.777422Z","end":"2026-10-03T06:19:42.916597Z","steps":["trace[16458922] 'read index received' (duration: 8.566586ms)","trace[16458922] 'applied index is now lower than readState.Index' (duration: 130.607404ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:19:42.916984Z","caller":"traceutil/trace.go:171","msg":"trace[398183337] transaction","detail":"{read_only:false; response_revision:14303; number_of_response:1; }","duration":"146.449981ms","start":"2026-10-03T06:19:42.770518Z","end":"2026-10-03T06:19:42.916968Z","steps":["trace[398183337] 'process raft request' (duration: 91.65602ms)","trace[398183337] 'compare' (duration: 54.104902ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:19:42.917552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.112126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:16 size:151633"} {"level":"info","ts":"2026-10-03T06:19:42.917602Z","caller":"traceutil/trace.go:171","msg":"trace[314400266] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:16; response_revision:14303; }","duration":"140.224529ms","start":"2026-10-03T06:19:42.777365Z","end":"2026-10-03T06:19:42.917589Z","steps":["trace[314400266] 'agreement among raft nodes before linearized reading' (duration: 139.825648ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:19:50.987854Z","caller":"traceutil/trace.go:171","msg":"trace[1929671595] transaction","detail":"{read_only:false; response_revision:14353; number_of_response:1; }","duration":"157.897317ms","start":"2026-10-03T06:19:50.8299Z","end":"2026-10-03T06:19:50.987797Z","steps":["trace[1929671595] 'process raft request' (duration: 157.524137ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:19:51.160858Z","caller":"traceutil/trace.go:171","msg":"trace[191810292] linearizableReadLoop","detail":"{readStateIndex:14851; appliedIndex:14849; }","duration":"153.848156ms","start":"2026-10-03T06:19:51.00698Z","end":"2026-10-03T06:19:51.160828Z","steps":["trace[191810292] 'read index received' (duration: 95.408963ms)","trace[191810292] 'applied index is now lower than readState.Index' (duration: 58.437533ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:19:51.161362Z","caller":"traceutil/trace.go:171","msg":"trace[1046651083] transaction","detail":"{read_only:false; response_revision:14354; number_of_response:1; }","duration":"167.686428ms","start":"2026-10-03T06:19:50.993654Z","end":"2026-10-03T06:19:51.16134Z","steps":["trace[1046651083] 'process raft request' (duration: 167.0273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:19:51.161469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.452012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fc9qj\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-03T06:19:51.161438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.913427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:19:51.161694Z","caller":"traceutil/trace.go:171","msg":"trace[862677422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14354; }","duration":"121.278057ms","start":"2026-10-03T06:19:51.040386Z","end":"2026-10-03T06:19:51.161664Z","steps":["trace[862677422] 'agreement among raft nodes before linearized reading' (duration: 120.891406ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:19:51.161609Z","caller":"traceutil/trace.go:171","msg":"trace[1600990101] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fc9qj; range_end:; response_count:1; response_revision:14354; }","duration":"154.633287ms","start":"2026-10-03T06:19:51.006945Z","end":"2026-10-03T06:19:51.161578Z","steps":["trace[1600990101] 'agreement among raft nodes before linearized reading' (duration: 154.110823ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:20:57.51968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12794} {"level":"info","ts":"2026-10-03T06:20:57.612902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12794,"took":"90.124907ms","hash":2246551800,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T06:20:57.613044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2246551800,"revision":12794,"compact-revision":10465} {"level":"info","ts":"2026-10-03T06:21:25.916382Z","caller":"traceutil/trace.go:171","msg":"trace[553330610] transaction","detail":"{read_only:false; response_revision:15383; number_of_response:1; }","duration":"133.237857ms","start":"2026-10-03T06:21:25.783118Z","end":"2026-10-03T06:21:25.916356Z","steps":["trace[553330610] 'process raft request' (duration: 133.091893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:21:26.145212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.113546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T06:21:26.145453Z","caller":"traceutil/trace.go:171","msg":"trace[292275074] transaction","detail":"{read_only:false; response_revision:15384; number_of_response:1; }","duration":"362.05679ms","start":"2026-10-03T06:21:25.783369Z","end":"2026-10-03T06:21:26.145425Z","steps":["trace[292275074] 'process raft request' (duration: 238.588164ms)","trace[292275074] 'compare' (duration: 122.802718ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:21:26.145582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:21:25.78333Z","time spent":"362.204394ms","remote":"127.0.0.1:50338","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-10-03T06:21:26.145584Z","caller":"traceutil/trace.go:171","msg":"trace[1406359751] linearizableReadLoop","detail":"{readStateIndex:15915; appliedIndex:15913; }","duration":"358.143373ms","start":"2026-10-03T06:21:25.787397Z","end":"2026-10-03T06:21:26.14554Z","steps":["trace[1406359751] 'read index received' (duration: 128.798454ms)","trace[1406359751] 'applied index is now lower than readState.Index' (duration: 229.342548ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:21:26.147356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.324913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:21:26.147413Z","caller":"traceutil/trace.go:171","msg":"trace[1711508767] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15384; }","duration":"239.430397ms","start":"2026-10-03T06:21:25.90797Z","end":"2026-10-03T06:21:26.1474Z","steps":["trace[1711508767] 'agreement among raft nodes before linearized reading' (duration: 239.341695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:21:26.147842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.440536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-03T06:21:26.147885Z","caller":"traceutil/trace.go:171","msg":"trace[707332158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15384; }","duration":"360.535068ms","start":"2026-10-03T06:21:25.78734Z","end":"2026-10-03T06:21:26.147875Z","steps":["trace[707332158] 'agreement among raft nodes before linearized reading' (duration: 358.372208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:21:26.147913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:21:25.787322Z","time spent":"360.5837ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T06:21:26.148274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.076811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T06:21:26.148306Z","caller":"traceutil/trace.go:171","msg":"trace[1991406826] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:15384; }","duration":"229.131043ms","start":"2026-10-03T06:21:25.919164Z","end":"2026-10-03T06:21:26.148295Z","steps":["trace[1991406826] 'agreement among raft nodes before linearized reading' (duration: 229.071261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:21:26.148534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.527158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-03T06:21:26.148561Z","caller":"traceutil/trace.go:171","msg":"trace[744328468] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15384; }","duration":"231.597811ms","start":"2026-10-03T06:21:25.916955Z","end":"2026-10-03T06:21:26.148553Z","steps":["trace[744328468] 'agreement among raft nodes before linearized reading' (duration: 231.487548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:21:26.148761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.416278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-10-03T06:21:26.148812Z","caller":"traceutil/trace.go:171","msg":"trace[932424782] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15384; }","duration":"234.521471ms","start":"2026-10-03T06:21:25.914282Z","end":"2026-10-03T06:21:26.148803Z","steps":["trace[932424782] 'agreement among raft nodes before linearized reading' (duration: 234.414748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:21:26.149279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.107274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T06:21:26.149282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.351717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-03T06:21:26.149345Z","caller":"traceutil/trace.go:171","msg":"trace[1533851253] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15384; }","duration":"241.171905ms","start":"2026-10-03T06:21:25.908141Z","end":"2026-10-03T06:21:26.149313Z","steps":["trace[1533851253] 'agreement among raft nodes before linearized reading' (duration: 241.094513ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:21:26.149382Z","caller":"traceutil/trace.go:171","msg":"trace[1502957010] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15384; }","duration":"238.489771ms","start":"2026-10-03T06:21:25.91087Z","end":"2026-10-03T06:21:26.14936Z","steps":["trace[1502957010] 'agreement among raft nodes before linearized reading' (duration: 238.181153ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:25:57.527673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15230} {"level":"info","ts":"2026-10-03T06:25:57.666872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15230,"took":"127.041993ms","hash":4080882965,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":31653888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T06:25:57.666957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4080882965,"revision":15230,"compact-revision":12794} {"level":"info","ts":"2026-10-03T06:27:22.11015Z","caller":"traceutil/trace.go:171","msg":"trace[6082862] transaction","detail":"{read_only:false; response_revision:18008; number_of_response:1; }","duration":"148.921387ms","start":"2026-10-03T06:27:21.961186Z","end":"2026-10-03T06:27:22.110107Z","steps":["trace[6082862] 'process raft request' (duration: 148.571697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:27:22.456028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.2431ms","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-10-03T06:27:22.45614Z","caller":"traceutil/trace.go:171","msg":"trace[868439392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18008; }","duration":"114.427306ms","start":"2026-10-03T06:27:22.341686Z","end":"2026-10-03T06:27:22.456114Z","steps":["trace[868439392] 'range keys from in-memory index tree' (duration: 113.963103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:27:22.4562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.014018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417608"} {"level":"info","ts":"2026-10-03T06:27:22.456444Z","caller":"traceutil/trace.go:171","msg":"trace[1505747963] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:18008; }","duration":"269.304616ms","start":"2026-10-03T06:27:22.187104Z","end":"2026-10-03T06:27:22.456408Z","steps":["trace[1505747963] 'range keys from in-memory index tree' (duration: 268.635467ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:27:22.704362Z","caller":"traceutil/trace.go:171","msg":"trace[698475617] linearizableReadLoop","detail":"{readStateIndex:18627; appliedIndex:18626; }","duration":"241.913271ms","start":"2026-10-03T06:27:22.462415Z","end":"2026-10-03T06:27:22.704328Z","steps":["trace[698475617] 'read index received' (duration: 241.806768ms)","trace[698475617] 'applied index is now lower than readState.Index' (duration: 105.033µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:27:22.704523Z","caller":"traceutil/trace.go:171","msg":"trace[568220096] transaction","detail":"{read_only:false; response_revision:18009; number_of_response:1; }","duration":"242.994001ms","start":"2026-10-03T06:27:22.461462Z","end":"2026-10-03T06:27:22.704457Z","steps":["trace[568220096] 'process raft request' (duration: 242.675632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:27:22.704782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.27588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:27:22.704965Z","caller":"traceutil/trace.go:171","msg":"trace[2048102163] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:18009; }","duration":"242.503427ms","start":"2026-10-03T06:27:22.462384Z","end":"2026-10-03T06:27:22.704887Z","steps":["trace[2048102163] 'agreement among raft nodes before linearized reading' (duration: 242.105316ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:27:22.790119Z","caller":"traceutil/trace.go:171","msg":"trace[1071268624] transaction","detail":"{read_only:false; response_revision:18010; number_of_response:1; }","duration":"310.020979ms","start":"2026-10-03T06:27:22.480062Z","end":"2026-10-03T06:27:22.790083Z","steps":["trace[1071268624] 'process raft request' (duration: 309.098704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:27:22.790313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:27:22.48003Z","time spent":"310.176653ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:27:22.790617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.661199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:27:22.79066Z","caller":"traceutil/trace.go:171","msg":"trace[1541901858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18010; }","duration":"214.758302ms","start":"2026-10-03T06:27:22.57589Z","end":"2026-10-03T06:27:22.790648Z","steps":["trace[1541901858] 'agreement among raft nodes before linearized reading' (duration: 214.574317ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:28:10.001738Z","caller":"traceutil/trace.go:171","msg":"trace[2084697247] transaction","detail":"{read_only:false; response_revision:18580; number_of_response:1; }","duration":"123.339711ms","start":"2026-10-03T06:28:09.878358Z","end":"2026-10-03T06:28:10.001698Z","steps":["trace[2084697247] 'process raft request' (duration: 55.018217ms)","trace[2084697247] 'compare' (duration: 68.099788ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:28:10.001719Z","caller":"traceutil/trace.go:171","msg":"trace[758023782] linearizableReadLoop","detail":"{readStateIndex:19215; appliedIndex:19214; }","duration":"100.10695ms","start":"2026-10-03T06:28:09.901568Z","end":"2026-10-03T06:28:10.001675Z","steps":["trace[758023782] 'read index received' (duration: 31.798467ms)","trace[758023782] 'applied index is now lower than readState.Index' (duration: 68.306813ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:28:10.002002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.405719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-10-03T06:28:10.002089Z","caller":"traceutil/trace.go:171","msg":"trace[2023099287] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:18580; }","duration":"100.559953ms","start":"2026-10-03T06:28:09.901506Z","end":"2026-10-03T06:28:10.002066Z","steps":["trace[2023099287] 'agreement among raft nodes before linearized reading' (duration: 100.300696ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:29:53.706192Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T06:29:53.71257Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-03T06:29:53.71267Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-03T06:30:57.535518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17433} {"level":"info","ts":"2026-10-03T06:30:57.65339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17433,"took":"113.954022ms","hash":2741215607,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":34725888,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-03T06:30:57.653447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2741215607,"revision":17433,"compact-revision":15230} {"level":"warn","ts":"2026-10-03T06:33:01.136553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.830264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:33:01.136761Z","caller":"traceutil/trace.go:171","msg":"trace[1564388608] linearizableReadLoop","detail":"{readStateIndex:21346; appliedIndex:21344; }","duration":"166.232732ms","start":"2026-10-03T06:33:00.970506Z","end":"2026-10-03T06:33:01.136738Z","steps":["trace[1564388608] 'read index received' (duration: 62.994007ms)","trace[1564388608] 'applied index is now lower than readState.Index' (duration: 103.237475ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:33:01.136914Z","caller":"traceutil/trace.go:171","msg":"trace[1999089170] transaction","detail":"{read_only:false; response_revision:20641; number_of_response:1; }","duration":"218.517793ms","start":"2026-10-03T06:33:00.918343Z","end":"2026-10-03T06:33:01.136861Z","steps":["trace[1999089170] 'process raft request' (duration: 115.246407ms)","trace[1999089170] 'compare' (duration: 102.609958ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:33:01.136911Z","caller":"traceutil/trace.go:171","msg":"trace[376393378] transaction","detail":"{read_only:false; response_revision:20642; number_of_response:1; }","duration":"184.66247ms","start":"2026-10-03T06:33:00.952212Z","end":"2026-10-03T06:33:01.136875Z","steps":["trace[376393378] 'process raft request' (duration: 184.465724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:33:01.137079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.568331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-03T06:33:01.137153Z","caller":"traceutil/trace.go:171","msg":"trace[975983709] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:20642; }","duration":"166.614262ms","start":"2026-10-03T06:33:00.970496Z","end":"2026-10-03T06:33:01.13711Z","steps":["trace[975983709] 'agreement among raft nodes before linearized reading' (duration: 166.305044ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:33:01.511004Z","caller":"traceutil/trace.go:171","msg":"trace[950009225] linearizableReadLoop","detail":"{readStateIndex:21349; appliedIndex:21348; }","duration":"203.135291ms","start":"2026-10-03T06:33:01.307839Z","end":"2026-10-03T06:33:01.510974Z","steps":["trace[950009225] 'read index received' (duration: 202.844163ms)","trace[950009225] 'applied index is now lower than readState.Index' (duration: 289.608µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:33:01.511046Z","caller":"traceutil/trace.go:171","msg":"trace[1468011281] transaction","detail":"{read_only:false; response_revision:20644; number_of_response:1; }","duration":"243.888394ms","start":"2026-10-03T06:33:01.267123Z","end":"2026-10-03T06:33:01.511011Z","steps":["trace[1468011281] 'process raft request' (duration: 243.671808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:33:01.511305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.395857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-03T06:33:01.511298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.930511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:33:01.511359Z","caller":"traceutil/trace.go:171","msg":"trace[1768021221] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20644; }","duration":"203.639045ms","start":"2026-10-03T06:33:01.307701Z","end":"2026-10-03T06:33:01.51134Z","steps":["trace[1768021221] 'agreement among raft nodes before linearized reading' (duration: 203.417959ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:33:01.511381Z","caller":"traceutil/trace.go:171","msg":"trace[944346752] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:20644; }","duration":"188.082275ms","start":"2026-10-03T06:33:01.323273Z","end":"2026-10-03T06:33:01.511355Z","steps":["trace[944346752] 'agreement among raft nodes before linearized reading' (duration: 187.916101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:33:01.511473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.399798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:33:01.511608Z","caller":"traceutil/trace.go:171","msg":"trace[680451650] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:20644; }","duration":"104.733118ms","start":"2026-10-03T06:33:01.406852Z","end":"2026-10-03T06:33:01.511585Z","steps":["trace[680451650] 'agreement among raft nodes before linearized reading' (duration: 104.397028ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:33:01.701551Z","caller":"traceutil/trace.go:171","msg":"trace[1557273128] transaction","detail":"{read_only:false; response_revision:20646; number_of_response:1; }","duration":"183.564641ms","start":"2026-10-03T06:33:01.517917Z","end":"2026-10-03T06:33:01.701481Z","steps":["trace[1557273128] 'process raft request' (duration: 172.65299ms)","trace[1557273128] 'compare' (duration: 10.626423ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:35:57.567994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19810} {"level":"info","ts":"2026-10-03T06:35:57.715817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19810,"took":"141.606914ms","hash":3303292251,"current-db-size-bytes":56135680,"current-db-size":"56 MB","current-db-size-in-use-bytes":30330880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T06:35:57.715886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3303292251,"revision":19810,"compact-revision":17433} {"level":"info","ts":"2026-10-03T06:37:03.500585Z","caller":"traceutil/trace.go:171","msg":"trace[1831084786] transaction","detail":"{read_only:false; response_revision:23141; number_of_response:1; }","duration":"247.210546ms","start":"2026-10-03T06:37:03.253287Z","end":"2026-10-03T06:37:03.500498Z","steps":["trace[1831084786] 'process raft request' (duration: 210.389351ms)","trace[1831084786] 'compare' (duration: 36.434904ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:37:03.500586Z","caller":"traceutil/trace.go:171","msg":"trace[1462411986] linearizableReadLoop","detail":"{readStateIndex:23924; appliedIndex:23923; }","duration":"236.417629ms","start":"2026-10-03T06:37:03.264141Z","end":"2026-10-03T06:37:03.500558Z","steps":["trace[1462411986] 'read index received' (duration: 199.520211ms)","trace[1462411986] 'applied index is now lower than readState.Index' (duration: 36.895317ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:37:03.500889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.715396ms","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-10-03T06:37:03.501077Z","caller":"traceutil/trace.go:171","msg":"trace[2037478061] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23141; }","duration":"236.953963ms","start":"2026-10-03T06:37:03.264093Z","end":"2026-10-03T06:37:03.501047Z","steps":["trace[2037478061] 'agreement among raft nodes before linearized reading' (duration: 236.49874ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:03.563611Z","caller":"traceutil/trace.go:171","msg":"trace[1268331816] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"182.110796ms","start":"2026-10-03T06:40:03.381454Z","end":"2026-10-03T06:40:03.563565Z","steps":["trace[1268331816] 'process raft request' (duration: 176.44785ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:03.563731Z","caller":"traceutil/trace.go:171","msg":"trace[1644274487] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"116.153829ms","start":"2026-10-03T06:40:03.447526Z","end":"2026-10-03T06:40:03.56368Z","steps":["trace[1644274487] 'process raft request' (duration: 115.871031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:49.131347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.345383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:49.131467Z","caller":"traceutil/trace.go:171","msg":"trace[1051290408] linearizableReadLoop","detail":"{readStateIndex:26207; appliedIndex:26206; }","duration":"266.318606ms","start":"2026-10-03T06:40:48.865133Z","end":"2026-10-03T06:40:49.131451Z","steps":["trace[1051290408] 'read index received' (duration: 63.052477ms)","trace[1051290408] 'applied index is now lower than readState.Index' (duration: 203.264679ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:49.131552Z","caller":"traceutil/trace.go:171","msg":"trace[1793687149] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"275.048497ms","start":"2026-10-03T06:40:48.856468Z","end":"2026-10-03T06:40:49.131516Z","steps":["trace[1793687149] 'process raft request' (duration: 145.425856ms)","trace[1793687149] 'compare' (duration: 129.185659ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:49.131679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.541032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:40:49.131705Z","caller":"traceutil/trace.go:171","msg":"trace[647052847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25366; }","duration":"266.606964ms","start":"2026-10-03T06:40:48.865091Z","end":"2026-10-03T06:40:49.131698Z","steps":["trace[647052847] 'agreement among raft nodes before linearized reading' (duration: 266.46577ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:49.346228Z","caller":"traceutil/trace.go:171","msg":"trace[1444923128] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"209.977964ms","start":"2026-10-03T06:40:49.136193Z","end":"2026-10-03T06:40:49.346171Z","steps":["trace[1444923128] 'process raft request' (duration: 131.993636ms)","trace[1444923128] 'compare' (duration: 77.767372ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:49.8313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.990466ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:49.831513Z","caller":"traceutil/trace.go:171","msg":"trace[1365556875] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25367; }","duration":"324.223422ms","start":"2026-10-03T06:40:49.507253Z","end":"2026-10-03T06:40:49.831476Z","steps":["trace[1365556875] 'range keys from in-memory index tree' (duration: 323.962504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:49.831812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.299071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:49.832112Z","caller":"traceutil/trace.go:171","msg":"trace[915212690] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"287.707366ms","start":"2026-10-03T06:40:49.544381Z","end":"2026-10-03T06:40:49.832088Z","steps":["trace[915212690] 'process raft request' (duration: 287.642514ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:49.832261Z","caller":"traceutil/trace.go:171","msg":"trace[727942300] linearizableReadLoop","detail":"{readStateIndex:26209; appliedIndex:26208; }","duration":"553.029374ms","start":"2026-10-03T06:40:49.279215Z","end":"2026-10-03T06:40:49.832244Z","steps":["trace[727942300] 'read index received' (duration: 402.199179ms)","trace[727942300] 'applied index is now lower than readState.Index' (duration: 150.828455ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:49.832332Z","caller":"traceutil/trace.go:171","msg":"trace[1841283467] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"500.296592ms","start":"2026-10-03T06:40:49.332013Z","end":"2026-10-03T06:40:49.83231Z","steps":["trace[1841283467] 'process raft request' (duration: 499.942412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:49.83258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:49.331974Z","time spent":"500.456647ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:49.832634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.390975ms","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-10-03T06:40:49.832322Z","caller":"traceutil/trace.go:171","msg":"trace[1423835375] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"695.898101ms","start":"2026-10-03T06:40:49.136358Z","end":"2026-10-03T06:40:49.832256Z","steps":["trace[1423835375] 'process raft request' (duration: 545.034275ms)","trace[1423835375] 'compare' (duration: 150.086624ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:49.832726Z","caller":"traceutil/trace.go:171","msg":"trace[1095391183] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25370; }","duration":"553.542199ms","start":"2026-10-03T06:40:49.279161Z","end":"2026-10-03T06:40:49.832703Z","steps":["trace[1095391183] 'agreement among raft nodes before linearized reading' (duration: 553.22104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:49.832771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:49.279136Z","time spent":"553.623511ms","remote":"127.0.0.1:50338","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":"warn","ts":"2026-10-03T06:40:49.83284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:49.136332Z","time spent":"696.401584ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:49.833343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.401474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T06:40:49.833435Z","caller":"traceutil/trace.go:171","msg":"trace[1006020138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25370; }","duration":"529.539157ms","start":"2026-10-03T06:40:49.303868Z","end":"2026-10-03T06:40:49.833407Z","steps":["trace[1006020138] 'agreement among raft nodes before linearized reading' (duration: 528.52559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:49.833521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:49.30384Z","time spent":"529.63727ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T06:40:50.137349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.638142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:50.137512Z","caller":"traceutil/trace.go:171","msg":"trace[2086939981] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"298.200463ms","start":"2026-10-03T06:40:49.839265Z","end":"2026-10-03T06:40:50.137465Z","steps":["trace[2086939981] 'process raft request' (duration: 148.327025ms)","trace[2086939981] 'compare' (duration: 149.421936ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:50.171001Z","caller":"traceutil/trace.go:171","msg":"trace[391205081] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"331.599374ms","start":"2026-10-03T06:40:49.839351Z","end":"2026-10-03T06:40:50.170951Z","steps":["trace[391205081] 'process raft request' (duration: 331.280815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:50.171306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:49.839319Z","time spent":"331.80491ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:50.17176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.219272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:40:50.171834Z","caller":"traceutil/trace.go:171","msg":"trace[578960897] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"255.742945ms","start":"2026-10-03T06:40:49.915736Z","end":"2026-10-03T06:40:50.171479Z","steps":["trace[578960897] 'process raft request' (duration: 255.115337ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:50.171931Z","caller":"traceutil/trace.go:171","msg":"trace[172368172] linearizableReadLoop","detail":"{readStateIndex:26215; appliedIndex:26212; }","duration":"177.022056ms","start":"2026-10-03T06:40:49.99452Z","end":"2026-10-03T06:40:50.171542Z","steps":["trace[172368172] 'read index received' (duration: 176.020949ms)","trace[172368172] 'applied index is now lower than readState.Index' (duration: 999.897µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:50.171965Z","caller":"traceutil/trace.go:171","msg":"trace[1354157269] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"229.083249ms","start":"2026-10-03T06:40:49.942606Z","end":"2026-10-03T06:40:50.171689Z","steps":["trace[1354157269] 'process raft request' (duration: 228.717299ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:50.171883Z","caller":"traceutil/trace.go:171","msg":"trace[554070161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25374; }","duration":"177.424967ms","start":"2026-10-03T06:40:49.99444Z","end":"2026-10-03T06:40:50.171865Z","steps":["trace[554070161] 'agreement among raft nodes before linearized reading' (duration: 177.107558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:50.467253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.321815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:50.467478Z","caller":"traceutil/trace.go:171","msg":"trace[360985428] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"290.285507ms","start":"2026-10-03T06:40:50.177132Z","end":"2026-10-03T06:40:50.467417Z","steps":["trace[360985428] 'process raft request' (duration: 116.673764ms)","trace[360985428] 'compare' (duration: 172.333398ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:50.467622Z","caller":"traceutil/trace.go:171","msg":"trace[464533055] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"271.929541ms","start":"2026-10-03T06:40:50.195669Z","end":"2026-10-03T06:40:50.467599Z","steps":["trace[464533055] 'process raft request' (duration: 271.807308ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:50.467625Z","caller":"traceutil/trace.go:171","msg":"trace[504407749] linearizableReadLoop","detail":"{readStateIndex:26216; appliedIndex:26215; }","duration":"289.914047ms","start":"2026-10-03T06:40:50.177669Z","end":"2026-10-03T06:40:50.467583Z","steps":["trace[504407749] 'read index received' (duration: 116.115669ms)","trace[504407749] 'applied index is now lower than readState.Index' (duration: 173.794098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:50.467873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.697097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:50.467924Z","caller":"traceutil/trace.go:171","msg":"trace[2105600147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25376; }","duration":"201.81177ms","start":"2026-10-03T06:40:50.266094Z","end":"2026-10-03T06:40:50.467906Z","steps":["trace[2105600147] 'agreement among raft nodes before linearized reading' (duration: 201.699146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:50.467929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.258886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-03T06:40:50.467987Z","caller":"traceutil/trace.go:171","msg":"trace[1070412216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25376; }","duration":"290.377749ms","start":"2026-10-03T06:40:50.177592Z","end":"2026-10-03T06:40:50.46797Z","steps":["trace[1070412216] 'agreement among raft nodes before linearized reading' (duration: 290.163003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:50.46808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.487535ms","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-10-03T06:40:50.468127Z","caller":"traceutil/trace.go:171","msg":"trace[142893939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25376; }","duration":"138.654669ms","start":"2026-10-03T06:40:50.329459Z","end":"2026-10-03T06:40:50.468113Z","steps":["trace[142893939] 'agreement among raft nodes before linearized reading' (duration: 138.410873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:50.749983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.002859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:50.750134Z","caller":"traceutil/trace.go:171","msg":"trace[59093344] linearizableReadLoop","detail":"{readStateIndex:26218; appliedIndex:26217; }","duration":"191.140375ms","start":"2026-10-03T06:40:50.558969Z","end":"2026-10-03T06:40:50.75011Z","steps":["trace[59093344] 'read index received' (duration: 43.854318ms)","trace[59093344] 'applied index is now lower than readState.Index' (duration: 147.284017ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:50.750239Z","caller":"traceutil/trace.go:171","msg":"trace[556959526] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"274.954714ms","start":"2026-10-03T06:40:50.475222Z","end":"2026-10-03T06:40:50.750176Z","steps":["trace[556959526] 'process raft request' (duration: 127.596605ms)","trace[556959526] 'compare' (duration: 146.171126ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:50.750312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.329991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:50.750416Z","caller":"traceutil/trace.go:171","msg":"trace[1967769871] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25377; }","duration":"191.503745ms","start":"2026-10-03T06:40:50.55889Z","end":"2026-10-03T06:40:50.750394Z","steps":["trace[1967769871] 'agreement among raft nodes before linearized reading' (duration: 191.32598ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:50.753075Z","caller":"traceutil/trace.go:171","msg":"trace[1270226983] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"115.305336ms","start":"2026-10-03T06:40:50.637732Z","end":"2026-10-03T06:40:50.753037Z","steps":["trace[1270226983] 'process raft request' (duration: 115.057469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:50.753191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.194123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-03T06:40:50.753251Z","caller":"traceutil/trace.go:171","msg":"trace[6708753] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25378; }","duration":"115.315536ms","start":"2026-10-03T06:40:50.637917Z","end":"2026-10-03T06:40:50.753233Z","steps":["trace[6708753] 'agreement among raft nodes before linearized reading' (duration: 115.07405ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:51.279252Z","caller":"traceutil/trace.go:171","msg":"trace[559877625] linearizableReadLoop","detail":"{readStateIndex:26220; appliedIndex:26219; }","duration":"281.341731ms","start":"2026-10-03T06:40:50.997858Z","end":"2026-10-03T06:40:51.279199Z","steps":["trace[559877625] 'read index received' (duration: 280.869238ms)","trace[559877625] 'applied index is now lower than readState.Index' (duration: 470.223µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:51.279328Z","caller":"traceutil/trace.go:171","msg":"trace[536864593] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"345.59746ms","start":"2026-10-03T06:40:50.933688Z","end":"2026-10-03T06:40:51.279285Z","steps":["trace[536864593] 'process raft request' (duration: 344.988123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:51.279579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:50.933654Z","time spent":"345.770975ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:51.279699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.811183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-03T06:40:51.279772Z","caller":"traceutil/trace.go:171","msg":"trace[1331019441] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:25379; }","duration":"281.974838ms","start":"2026-10-03T06:40:50.997778Z","end":"2026-10-03T06:40:51.279752Z","steps":["trace[1331019441] 'agreement among raft nodes before linearized reading' (duration: 281.67423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:51.30787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.5406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:51.307936Z","caller":"traceutil/trace.go:171","msg":"trace[2085180663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25380; }","duration":"267.665064ms","start":"2026-10-03T06:40:51.040256Z","end":"2026-10-03T06:40:51.307921Z","steps":["trace[2085180663] 'agreement among raft nodes before linearized reading' (duration: 267.53478ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:51.307944Z","caller":"traceutil/trace.go:171","msg":"trace[880020233] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"170.126907ms","start":"2026-10-03T06:40:51.137768Z","end":"2026-10-03T06:40:51.307895Z","steps":["trace[880020233] 'process raft request' (duration: 169.849359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:51.308097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.609779ms","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":"info","ts":"2026-10-03T06:40:51.3082Z","caller":"traceutil/trace.go:171","msg":"trace[124653343] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25380; }","duration":"150.791774ms","start":"2026-10-03T06:40:51.157386Z","end":"2026-10-03T06:40:51.308177Z","steps":["trace[124653343] 'agreement among raft nodes before linearized reading' (duration: 150.590728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:51.308728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.702609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T06:40:51.308882Z","caller":"traceutil/trace.go:171","msg":"trace[921047203] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25380; }","duration":"106.917035ms","start":"2026-10-03T06:40:51.201951Z","end":"2026-10-03T06:40:51.308868Z","steps":["trace[921047203] 'agreement among raft nodes before linearized reading' (duration: 106.719129ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:56.894417Z","caller":"traceutil/trace.go:171","msg":"trace[499322450] linearizableReadLoop","detail":"{readStateIndex:26268; appliedIndex:26267; }","duration":"272.148067ms","start":"2026-10-03T06:40:56.622236Z","end":"2026-10-03T06:40:56.894384Z","steps":["trace[499322450] 'read index received' (duration: 271.959062ms)","trace[499322450] 'applied index is now lower than readState.Index' (duration: 187.825µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:56.894544Z","caller":"traceutil/trace.go:171","msg":"trace[1348959932] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"301.723142ms","start":"2026-10-03T06:40:56.592733Z","end":"2026-10-03T06:40:56.894456Z","steps":["trace[1348959932] 'process raft request' (duration: 301.454104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:56.894657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.383283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:56.894707Z","caller":"traceutil/trace.go:171","msg":"trace[1042000412] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25425; }","duration":"272.504056ms","start":"2026-10-03T06:40:56.622186Z","end":"2026-10-03T06:40:56.89469Z","steps":["trace[1042000412] 'agreement among raft nodes before linearized reading' (duration: 272.389213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:56.894826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:56.592706Z","time spent":"301.949708ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:56.911841Z","caller":"traceutil/trace.go:171","msg":"trace[1775761508] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"134.481795ms","start":"2026-10-03T06:40:56.777322Z","end":"2026-10-03T06:40:56.911804Z","steps":["trace[1775761508] 'process raft request' (duration: 134.28973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:56.912514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.198098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-10-03T06:40:56.91255Z","caller":"traceutil/trace.go:171","msg":"trace[554636594] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:25426; }","duration":"219.318401ms","start":"2026-10-03T06:40:56.693221Z","end":"2026-10-03T06:40:56.91254Z","steps":["trace[554636594] 'agreement among raft nodes before linearized reading' (duration: 219.148907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:56.912846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.707454ms","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-10-03T06:40:56.912873Z","caller":"traceutil/trace.go:171","msg":"trace[1172641970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25426; }","duration":"137.783926ms","start":"2026-10-03T06:40:56.775081Z","end":"2026-10-03T06:40:56.912865Z","steps":["trace[1172641970] 'agreement among raft nodes before linearized reading' (duration: 137.714794ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:57.054659Z","caller":"traceutil/trace.go:171","msg":"trace[832537292] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"137.23737ms","start":"2026-10-03T06:40:56.917382Z","end":"2026-10-03T06:40:57.054619Z","steps":["trace[832537292] 'process raft request' (duration: 121.338682ms)","trace[832537292] 'compare' (duration: 15.315412ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:57.44181Z","caller":"traceutil/trace.go:171","msg":"trace[383036387] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"140.310555ms","start":"2026-10-03T06:40:57.301466Z","end":"2026-10-03T06:40:57.441777Z","steps":["trace[383036387] 'process raft request' (duration: 140.037348ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:57.457896Z","caller":"traceutil/trace.go:171","msg":"trace[1095908678] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"128.86956ms","start":"2026-10-03T06:40:57.328995Z","end":"2026-10-03T06:40:57.457864Z","steps":["trace[1095908678] 'process raft request' (duration: 128.620543ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:57.458168Z","caller":"traceutil/trace.go:171","msg":"trace[1211146666] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"119.303846ms","start":"2026-10-03T06:40:57.338836Z","end":"2026-10-03T06:40:57.45814Z","steps":["trace[1211146666] 'process raft request' (duration: 118.976517ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:57.659154Z","caller":"traceutil/trace.go:171","msg":"trace[1273188508] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"196.095123ms","start":"2026-10-03T06:40:57.463022Z","end":"2026-10-03T06:40:57.659117Z","steps":["trace[1273188508] 'process raft request' (duration: 195.882687ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:57.770195Z","caller":"traceutil/trace.go:171","msg":"trace[1159252848] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"194.533929ms","start":"2026-10-03T06:40:57.575613Z","end":"2026-10-03T06:40:57.770147Z","steps":["trace[1159252848] 'process raft request' (duration: 180.694258ms)","trace[1159252848] 'compare' (duration: 13.271056ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:57.938293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22030} {"level":"warn","ts":"2026-10-03T06:40:57.9392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.005114ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-10-03T06:40:57.939473Z","caller":"traceutil/trace.go:171","msg":"trace[1783788991] compact","detail":"{revision:22030; response_revision:25432; }","duration":"166.563588ms","start":"2026-10-03T06:40:57.772863Z","end":"2026-10-03T06:40:57.939427Z","steps":["trace[1783788991] 'process raft request' (duration: 27.784975ms)","trace[1783788991] 'check and update compact revision' (duration: 136.872161ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:57.939547Z","caller":"traceutil/trace.go:171","msg":"trace[1789050967] linearizableReadLoop","detail":"{readStateIndex:26276; appliedIndex:26275; }","duration":"124.105579ms","start":"2026-10-03T06:40:57.8154Z","end":"2026-10-03T06:40:57.939506Z","steps":["trace[1789050967] 'read index received' (duration: 58.412µs)","trace[1789050967] 'applied index is now lower than readState.Index' (duration: 124.042097ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:57.939783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.337635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:57.939914Z","caller":"traceutil/trace.go:171","msg":"trace[2079113537] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25432; }","duration":"124.52998ms","start":"2026-10-03T06:40:57.815356Z","end":"2026-10-03T06:40:57.939886Z","steps":["trace[2079113537] 'agreement among raft nodes before linearized reading' (duration: 124.317254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:58.299243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.768479ms","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-10-03T06:40:58.299349Z","caller":"traceutil/trace.go:171","msg":"trace[2125483891] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25434; }","duration":"103.940224ms","start":"2026-10-03T06:40:58.195385Z","end":"2026-10-03T06:40:58.299325Z","steps":["trace[2125483891] 'range keys from in-memory index tree' (duration: 103.528542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:58.401297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.049799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:58.401462Z","caller":"traceutil/trace.go:171","msg":"trace[928037528] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"194.811406ms","start":"2026-10-03T06:40:58.206609Z","end":"2026-10-03T06:40:58.401421Z","steps":["trace[928037528] 'process raft request' (duration: 91.509161ms)","trace[928037528] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:530; } (duration: 101.241288ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:58.626298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.622862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:58.626641Z","caller":"traceutil/trace.go:171","msg":"trace[1169533248] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"411.996299ms","start":"2026-10-03T06:40:58.214604Z","end":"2026-10-03T06:40:58.6266Z","steps":["trace[1169533248] 'process raft request' (duration: 245.926695ms)","trace[1169533248] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:506; } (duration: 164.912663ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:58.626822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.214569Z","time spent":"412.190095ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:59.129574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.895114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:59.129706Z","caller":"traceutil/trace.go:171","msg":"trace[1034996169] linearizableReadLoop","detail":"{readStateIndex:26280; appliedIndex:26278; }","duration":"896.98603ms","start":"2026-10-03T06:40:58.232705Z","end":"2026-10-03T06:40:59.129691Z","steps":["trace[1034996169] 'read index received' (duration: 65.417293ms)","trace[1034996169] 'applied index is now lower than readState.Index' (duration: 831.567537ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:59.129771Z","caller":"traceutil/trace.go:171","msg":"trace[1795927439] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"833.221983ms","start":"2026-10-03T06:40:58.296534Z","end":"2026-10-03T06:40:59.129756Z","steps":["trace[1795927439] 'process raft request' (duration: 330.042572ms)","trace[1795927439] 'compare' (duration: 146.64554ms)","trace[1795927439] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi; req_size:625; } (duration: 355.318578ms)"],"step_count":3} {"level":"warn","ts":"2026-10-03T06:40:59.129808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.296499Z","time spent":"833.288575ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:59.28307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050333615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-03T06:40:59.283184Z","caller":"traceutil/trace.go:171","msg":"trace[1651355191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25437; }","duration":"1.050501679s","start":"2026-10-03T06:40:58.232651Z","end":"2026-10-03T06:40:59.283153Z","steps":["trace[1651355191] 'agreement among raft nodes before linearized reading' (duration: 897.307499ms)","trace[1651355191] 'range keys from in-memory index tree' (duration: 152.742848ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:59.283229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.232638Z","time spent":"1.050580511s","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-03T06:40:59.542363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22030,"took":"1.596809638s","hash":65607922,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":44761088,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-03T06:40:59.542478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":65607922,"revision":22030,"compact-revision":19810} {"level":"warn","ts":"2026-10-03T06:40:59.543643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.794399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:59.543879Z","caller":"traceutil/trace.go:171","msg":"trace[411677351] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"1.239139975s","start":"2026-10-03T06:40:58.3047Z","end":"2026-10-03T06:40:59.54384Z","steps":["trace[411677351] 'process raft request' (duration: 824.983166ms)","trace[411677351] 'compare' (duration: 153.078717ms)","trace[411677351] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:536; } (duration: 259.667933ms)"],"step_count":3} {"level":"warn","ts":"2026-10-03T06:40:59.543975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.304672Z","time spent":"1.239261199s","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:59.577962Z","caller":"traceutil/trace.go:171","msg":"trace[1249887110] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"983.481173ms","start":"2026-10-03T06:40:58.594449Z","end":"2026-10-03T06:40:59.57793Z","steps":["trace[1249887110] 'process raft request' (duration: 949.367523ms)","trace[1249887110] 'compare' (duration: 33.428381ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:59.578163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.594407Z","time spent":"983.649247ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:59.578608Z","caller":"traceutil/trace.go:171","msg":"trace[682418699] linearizableReadLoop","detail":"{readStateIndex:26284; appliedIndex:26281; }","duration":"448.870756ms","start":"2026-10-03T06:40:59.129723Z","end":"2026-10-03T06:40:59.578594Z","steps":["trace[682418699] 'read index received' (duration: 1.591184ms)","trace[682418699] 'applied index is now lower than readState.Index' (duration: 447.278432ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:59.578729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296369173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:59.578775Z","caller":"traceutil/trace.go:171","msg":"trace[1654052617] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25440; }","duration":"1.296484146s","start":"2026-10-03T06:40:58.28228Z","end":"2026-10-03T06:40:59.578764Z","steps":["trace[1654052617] 'agreement among raft nodes before linearized reading' (duration: 1.296369132s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.57881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.282251Z","time spent":"1.296546157s","remote":"127.0.0.1:46876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"info","ts":"2026-10-03T06:40:59.579034Z","caller":"traceutil/trace.go:171","msg":"trace[1109611904] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"659.18272ms","start":"2026-10-03T06:40:58.919837Z","end":"2026-10-03T06:40:59.57902Z","steps":["trace[1109611904] 'process raft request' (duration: 658.034798ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.579104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.919801Z","time spent":"659.269122ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:59.579221Z","caller":"traceutil/trace.go:171","msg":"trace[781785966] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"291.154081ms","start":"2026-10-03T06:40:59.288045Z","end":"2026-10-03T06:40:59.579199Z","steps":["trace[781785966] 'process raft request' (duration: 290.500533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.579674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.154979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:40:59.579786Z","caller":"traceutil/trace.go:171","msg":"trace[1908700029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25441; }","duration":"679.341594ms","start":"2026-10-03T06:40:58.90042Z","end":"2026-10-03T06:40:59.579761Z","steps":["trace[1908700029] 'agreement among raft nodes before linearized reading' (duration: 678.962404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.579283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.558531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:59.579973Z","caller":"traceutil/trace.go:171","msg":"trace[1595204411] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25441; }","duration":"896.218548ms","start":"2026-10-03T06:40:58.683667Z","end":"2026-10-03T06:40:59.579886Z","steps":["trace[1595204411] 'agreement among raft nodes before linearized reading' (duration: 895.576791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.580119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.683642Z","time spent":"896.415114ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:40:59.579324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.367867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:59.58024Z","caller":"traceutil/trace.go:171","msg":"trace[666857955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25441; }","duration":"496.306293ms","start":"2026-10-03T06:40:59.083918Z","end":"2026-10-03T06:40:59.580224Z","steps":["trace[666857955] 'agreement among raft nodes before linearized reading' (duration: 495.243033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.580283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:59.083899Z","time spent":"496.373674ms","remote":"127.0.0.1:50070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T06:40:59.579366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.272653ms","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-10-03T06:40:59.580385Z","caller":"traceutil/trace.go:171","msg":"trace[806998679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25441; }","duration":"517.330652ms","start":"2026-10-03T06:40:59.063033Z","end":"2026-10-03T06:40:59.580364Z","steps":["trace[806998679] 'agreement among raft nodes before linearized reading' (duration: 516.137299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.579396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.180114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-10-03T06:40:59.58046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:59.06301Z","time spent":"517.430874ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T06:40:59.579844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.900395Z","time spent":"679.440357ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T06:40:59.580474Z","caller":"traceutil/trace.go:171","msg":"trace[345203562] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25441; }","duration":"874.324666ms","start":"2026-10-03T06:40:58.706133Z","end":"2026-10-03T06:40:59.580458Z","steps":["trace[345203562] 'agreement among raft nodes before linearized reading' (duration: 872.99698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.580693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:58.706098Z","time spent":"874.577083ms","remote":"127.0.0.1:50214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"info","ts":"2026-10-03T06:40:59.777323Z","caller":"traceutil/trace.go:171","msg":"trace[1176157109] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"313.29958ms","start":"2026-10-03T06:40:59.463991Z","end":"2026-10-03T06:40:59.777291Z","steps":["trace[1176157109] 'process raft request' (duration: 313.244089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.777548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:59.463968Z","time spent":"313.452345ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:59.778109Z","caller":"traceutil/trace.go:171","msg":"trace[624965576] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"330.292118ms","start":"2026-10-03T06:40:59.447798Z","end":"2026-10-03T06:40:59.77809Z","steps":["trace[624965576] 'process raft request' (duration: 329.228019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.778197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:59.447766Z","time spent":"330.386331ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:59.779035Z","caller":"traceutil/trace.go:171","msg":"trace[1632364221] linearizableReadLoop","detail":"{readStateIndex:26287; appliedIndex:26287; }","duration":"197.753688ms","start":"2026-10-03T06:40:59.581261Z","end":"2026-10-03T06:40:59.779015Z","steps":["trace[1632364221] 'read index received' (duration: 197.742687ms)","trace[1632364221] 'applied index is now lower than readState.Index' (duration: 9.331µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:59.779171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.900272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:59.779212Z","caller":"traceutil/trace.go:171","msg":"trace[77751453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25443; }","duration":"197.970153ms","start":"2026-10-03T06:40:59.581232Z","end":"2026-10-03T06:40:59.779202Z","steps":["trace[77751453] 'agreement among raft nodes before linearized reading' (duration: 197.87819ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:59.814242Z","caller":"traceutil/trace.go:171","msg":"trace[1798122271] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"218.688224ms","start":"2026-10-03T06:40:59.595524Z","end":"2026-10-03T06:40:59.814212Z","steps":["trace[1798122271] 'process raft request' (duration: 218.493579ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:59.814478Z","caller":"traceutil/trace.go:171","msg":"trace[1381888112] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"218.820668ms","start":"2026-10-03T06:40:59.595616Z","end":"2026-10-03T06:40:59.814437Z","steps":["trace[1381888112] 'process raft request' (duration: 218.54271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.814659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.711348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ca19785f-c0d3-44c8-9892-5a9632c0c99e\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-10-03T06:40:59.814739Z","caller":"traceutil/trace.go:171","msg":"trace[1997499245] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ca19785f-c0d3-44c8-9892-5a9632c0c99e; range_end:; response_count:1; response_revision:25445; }","duration":"221.874822ms","start":"2026-10-03T06:40:59.592843Z","end":"2026-10-03T06:40:59.814718Z","steps":["trace[1997499245] 'agreement among raft nodes before linearized reading' (duration: 221.569004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:59.814754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.091934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:40:59.814846Z","caller":"traceutil/trace.go:171","msg":"trace[1933641012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25445; }","duration":"150.272339ms","start":"2026-10-03T06:40:59.664556Z","end":"2026-10-03T06:40:59.814828Z","steps":["trace[1933641012] 'agreement among raft nodes before linearized reading' (duration: 149.986011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:00.126753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.426298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:00.12728Z","caller":"traceutil/trace.go:171","msg":"trace[872663249] linearizableReadLoop","detail":"{readStateIndex:26291; appliedIndex:26289; }","duration":"136.324275ms","start":"2026-10-03T06:40:59.990941Z","end":"2026-10-03T06:41:00.127266Z","steps":["trace[872663249] 'read index received' (duration: 22.901741ms)","trace[872663249] 'applied index is now lower than readState.Index' (duration: 113.421484ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:00.127419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.477969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:41:00.127438Z","caller":"traceutil/trace.go:171","msg":"trace[1687295984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25447; }","duration":"136.49953ms","start":"2026-10-03T06:40:59.990933Z","end":"2026-10-03T06:41:00.127432Z","steps":["trace[1687295984] 'agreement among raft nodes before linearized reading' (duration: 136.370556ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:00.127557Z","caller":"traceutil/trace.go:171","msg":"trace[54587202] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"180.540833ms","start":"2026-10-03T06:40:59.946981Z","end":"2026-10-03T06:41:00.127522Z","steps":["trace[54587202] 'process raft request' (duration: 180.195354ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:00.127572Z","caller":"traceutil/trace.go:171","msg":"trace[2023189823] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"307.075019ms","start":"2026-10-03T06:40:59.820427Z","end":"2026-10-03T06:41:00.127502Z","steps":["trace[2023189823] 'process raft request' (duration: 136.745976ms)","trace[2023189823] 'compare' (duration: 169.052397ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:00.127844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:59.820399Z","time spent":"307.302485ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:00.339161Z","caller":"traceutil/trace.go:171","msg":"trace[430491619] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"206.785776ms","start":"2026-10-03T06:41:00.132335Z","end":"2026-10-03T06:41:00.339121Z","steps":["trace[430491619] 'process raft request' (duration: 184.04688ms)","trace[430491619] 'compare' (duration: 21.621416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:00.682132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.268304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:00.682364Z","caller":"traceutil/trace.go:171","msg":"trace[2007644223] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"273.937906ms","start":"2026-10-03T06:41:00.408394Z","end":"2026-10-03T06:41:00.682332Z","steps":["trace[2007644223] 'process raft request' (duration: 129.362093ms)","trace[2007644223] 'compare' (duration: 144.127571ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:01.128676Z","caller":"traceutil/trace.go:171","msg":"trace[951159280] linearizableReadLoop","detail":"{readStateIndex:26295; appliedIndex:26294; }","duration":"128.319285ms","start":"2026-10-03T06:41:01.00032Z","end":"2026-10-03T06:41:01.12864Z","steps":["trace[951159280] 'read index received' (duration: 128.1202ms)","trace[951159280] 'applied index is now lower than readState.Index' (duration: 197.535µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:01.128887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.539021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:01.128961Z","caller":"traceutil/trace.go:171","msg":"trace[1644565012] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25450; }","duration":"128.634284ms","start":"2026-10-03T06:41:01.000286Z","end":"2026-10-03T06:41:01.128921Z","steps":["trace[1644565012] 'agreement among raft nodes before linearized reading' (duration: 128.50522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:01.361371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.872024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:01.361547Z","caller":"traceutil/trace.go:171","msg":"trace[2110666146] linearizableReadLoop","detail":"{readStateIndex:26296; appliedIndex:26295; }","duration":"226.87281ms","start":"2026-10-03T06:41:01.134649Z","end":"2026-10-03T06:41:01.361521Z","steps":["trace[2110666146] 'read index received' (duration: 70.651556ms)","trace[2110666146] 'applied index is now lower than readState.Index' (duration: 156.217744ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:01.361741Z","caller":"traceutil/trace.go:171","msg":"trace[1855980616] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"231.499067ms","start":"2026-10-03T06:41:01.130198Z","end":"2026-10-03T06:41:01.361697Z","steps":["trace[1855980616] 'process raft request' (duration: 75.14083ms)","trace[1855980616] 'compare' (duration: 155.634397ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:01.361847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.187699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-03T06:41:01.361906Z","caller":"traceutil/trace.go:171","msg":"trace[1044793368] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25451; }","duration":"227.283761ms","start":"2026-10-03T06:41:01.134607Z","end":"2026-10-03T06:41:01.361891Z","steps":["trace[1044793368] 'agreement among raft nodes before linearized reading' (duration: 226.996303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:01.671092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.896771ms","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-10-03T06:41:01.671204Z","caller":"traceutil/trace.go:171","msg":"trace[113974987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25451; }","duration":"121.082825ms","start":"2026-10-03T06:41:01.550094Z","end":"2026-10-03T06:41:01.671177Z","steps":["trace[113974987] 'range keys from in-memory index tree' (duration: 120.630043ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:01.744084Z","caller":"traceutil/trace.go:171","msg":"trace[2001721178] linearizableReadLoop","detail":"{readStateIndex:26298; appliedIndex:26297; }","duration":"126.411432ms","start":"2026-10-03T06:41:01.617644Z","end":"2026-10-03T06:41:01.744055Z","steps":["trace[2001721178] 'read index received' (duration: 56.545357ms)","trace[2001721178] 'applied index is now lower than readState.Index' (duration: 69.864965ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:01.744189Z","caller":"traceutil/trace.go:171","msg":"trace[115057298] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"146.491926ms","start":"2026-10-03T06:41:01.597658Z","end":"2026-10-03T06:41:01.74415Z","steps":["trace[115057298] 'process raft request' (duration: 146.207298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:01.744426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.779222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T06:41:01.744543Z","caller":"traceutil/trace.go:171","msg":"trace[616612199] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25453; }","duration":"126.916996ms","start":"2026-10-03T06:41:01.617603Z","end":"2026-10-03T06:41:01.74452Z","steps":["trace[616612199] 'agreement among raft nodes before linearized reading' (duration: 126.591867ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:01.929376Z","caller":"traceutil/trace.go:171","msg":"trace[1631021587] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"144.310255ms","start":"2026-10-03T06:41:01.785027Z","end":"2026-10-03T06:41:01.929338Z","steps":["trace[1631021587] 'process raft request' (duration: 130.974038ms)","trace[1631021587] 'compare' (duration: 12.788282ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:01.966152Z","caller":"traceutil/trace.go:171","msg":"trace[624188355] linearizableReadLoop","detail":"{readStateIndex:26302; appliedIndex:26300; }","duration":"146.972658ms","start":"2026-10-03T06:41:01.819145Z","end":"2026-10-03T06:41:01.966118Z","steps":["trace[624188355] 'read index received' (duration: 96.867128ms)","trace[624188355] 'applied index is now lower than readState.Index' (duration: 50.10375ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:01.966339Z","caller":"traceutil/trace.go:171","msg":"trace[330277953] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"180.78646ms","start":"2026-10-03T06:41:01.78554Z","end":"2026-10-03T06:41:01.966326Z","steps":["trace[330277953] 'process raft request' (duration: 180.372509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:01.966702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.537754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:41:01.966803Z","caller":"traceutil/trace.go:171","msg":"trace[677064061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25457; }","duration":"147.652718ms","start":"2026-10-03T06:41:01.819126Z","end":"2026-10-03T06:41:01.966779Z","steps":["trace[677064061] 'agreement among raft nodes before linearized reading' (duration: 147.39205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:01.966745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.521554ms","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-10-03T06:41:01.966874Z","caller":"traceutil/trace.go:171","msg":"trace[1692453225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25457; }","duration":"147.709219ms","start":"2026-10-03T06:41:01.819142Z","end":"2026-10-03T06:41:01.966851Z","steps":["trace[1692453225] 'agreement among raft nodes before linearized reading' (duration: 147.38586ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.160866Z","caller":"traceutil/trace.go:171","msg":"trace[1459684547] linearizableReadLoop","detail":"{readStateIndex:26304; appliedIndex:26303; }","duration":"134.005222ms","start":"2026-10-03T06:41:02.026834Z","end":"2026-10-03T06:41:02.16084Z","steps":["trace[1459684547] 'read index received' (duration: 133.818967ms)","trace[1459684547] 'applied index is now lower than readState.Index' (duration: 184.725µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:02.160964Z","caller":"traceutil/trace.go:171","msg":"trace[1529199892] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"188.525434ms","start":"2026-10-03T06:41:01.972389Z","end":"2026-10-03T06:41:02.160915Z","steps":["trace[1529199892] 'process raft request' (duration: 188.254006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.161125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.257028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T06:41:02.161194Z","caller":"traceutil/trace.go:171","msg":"trace[202705236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25459; }","duration":"134.351731ms","start":"2026-10-03T06:41:02.026825Z","end":"2026-10-03T06:41:02.161177Z","steps":["trace[202705236] 'agreement among raft nodes before linearized reading' (duration: 134.124055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.175061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.85754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:02.175182Z","caller":"traceutil/trace.go:171","msg":"trace[368845590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25460; }","duration":"133.036215ms","start":"2026-10-03T06:41:02.042118Z","end":"2026-10-03T06:41:02.175154Z","steps":["trace[368845590] 'agreement among raft nodes before linearized reading' (duration: 132.811329ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.657024Z","caller":"traceutil/trace.go:171","msg":"trace[1806457744] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"165.780637ms","start":"2026-10-03T06:41:02.49121Z","end":"2026-10-03T06:41:02.656991Z","steps":["trace[1806457744] 'process raft request' (duration: 153.753686ms)","trace[1806457744] 'compare' (duration: 11.014153ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:02.928129Z","caller":"traceutil/trace.go:171","msg":"trace[1794809705] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"234.690695ms","start":"2026-10-03T06:41:02.693398Z","end":"2026-10-03T06:41:02.928089Z","steps":["trace[1794809705] 'process raft request' (duration: 200.262347ms)","trace[1794809705] 'compare' (duration: 34.232573ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:02.960071Z","caller":"traceutil/trace.go:171","msg":"trace[417902975] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"259.814017ms","start":"2026-10-03T06:41:02.700237Z","end":"2026-10-03T06:41:02.960051Z","steps":["trace[417902975] 'process raft request' (duration: 259.605441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.271006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.928709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T06:41:03.27109Z","caller":"traceutil/trace.go:171","msg":"trace[1601995595] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25465; }","duration":"216.082343ms","start":"2026-10-03T06:41:03.054985Z","end":"2026-10-03T06:41:03.271067Z","steps":["trace[1601995595] 'count revisions from in-memory index tree' (duration: 215.795064ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.870222Z","caller":"traceutil/trace.go:171","msg":"trace[520836680] linearizableReadLoop","detail":"{readStateIndex:26312; appliedIndex:26311; }","duration":"105.952748ms","start":"2026-10-03T06:41:03.764241Z","end":"2026-10-03T06:41:03.870193Z","steps":["trace[520836680] 'read index received' (duration: 104.186529ms)","trace[520836680] 'applied index is now lower than readState.Index' (duration: 1.764779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:03.870542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.6232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T06:41:03.870601Z","caller":"traceutil/trace.go:171","msg":"trace[283980198] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25467; }","duration":"105.885947ms","start":"2026-10-03T06:41:03.764699Z","end":"2026-10-03T06:41:03.870585Z","steps":["trace[283980198] 'agreement among raft nodes before linearized reading' (duration: 105.680011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.870849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.618087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T06:41:03.87089Z","caller":"traceutil/trace.go:171","msg":"trace[207776108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25467; }","duration":"106.714039ms","start":"2026-10-03T06:41:03.764167Z","end":"2026-10-03T06:41:03.870881Z","steps":["trace[207776108] 'agreement among raft nodes before linearized reading' (duration: 106.617146ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.870345Z","caller":"traceutil/trace.go:171","msg":"trace[631346958] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"121.349163ms","start":"2026-10-03T06:41:03.748985Z","end":"2026-10-03T06:41:03.870334Z","steps":["trace[631346958] 'process raft request' (duration: 119.536713ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:05.03754Z","caller":"traceutil/trace.go:171","msg":"trace[2067698638] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"102.500433ms","start":"2026-10-03T06:41:04.935012Z","end":"2026-10-03T06:41:05.037513Z","steps":["trace[2067698638] 'process raft request' (duration: 102.311848ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:13.220332Z","caller":"traceutil/trace.go:171","msg":"trace[81806873] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"156.934633ms","start":"2026-10-03T06:41:13.063368Z","end":"2026-10-03T06:41:13.220303Z","steps":["trace[81806873] 'process raft request' (duration: 99.792379ms)","trace[81806873] 'compare' (duration: 56.929288ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:13.220774Z","caller":"traceutil/trace.go:171","msg":"trace[150722845] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"135.376589ms","start":"2026-10-03T06:41:13.085364Z","end":"2026-10-03T06:41:13.220741Z","steps":["trace[150722845] 'process raft request' (duration: 134.899896ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.060154Z","caller":"traceutil/trace.go:171","msg":"trace[691572348] linearizableReadLoop","detail":"{readStateIndex:26447; appliedIndex:26446; }","duration":"171.545116ms","start":"2026-10-03T06:41:13.888573Z","end":"2026-10-03T06:41:14.060118Z","steps":["trace[691572348] 'read index received' (duration: 171.309159ms)","trace[691572348] 'applied index is now lower than readState.Index' (duration: 234.087µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:14.060207Z","caller":"traceutil/trace.go:171","msg":"trace[843270403] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"291.144441ms","start":"2026-10-03T06:41:13.769037Z","end":"2026-10-03T06:41:14.060181Z","steps":["trace[843270403] 'process raft request' (duration: 290.841892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:14.060757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.159262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-474a8cf5-f2e9-4f2d-b333-e1580b00c3a7\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-10-03T06:41:14.060843Z","caller":"traceutil/trace.go:171","msg":"trace[755457168] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-474a8cf5-f2e9-4f2d-b333-e1580b00c3a7; range_end:; response_count:1; response_revision:25599; }","duration":"172.309036ms","start":"2026-10-03T06:41:13.888515Z","end":"2026-10-03T06:41:14.060824Z","steps":["trace[755457168] 'agreement among raft nodes before linearized reading' (duration: 171.792982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:14.150059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.82674ms","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-10-03T06:41:14.150036Z","caller":"traceutil/trace.go:171","msg":"trace[8920612] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"240.775523ms","start":"2026-10-03T06:41:13.909193Z","end":"2026-10-03T06:41:14.149968Z","steps":["trace[8920612] 'process raft request' (duration: 192.673348ms)","trace[8920612] 'compare' (duration: 47.703434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:14.150147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.897286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T06:41:14.150116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.507861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:14.150168Z","caller":"traceutil/trace.go:171","msg":"trace[1505780253] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25602; }","duration":"134.970288ms","start":"2026-10-03T06:41:14.01519Z","end":"2026-10-03T06:41:14.15016Z","steps":["trace[1505780253] 'agreement among raft nodes before linearized reading' (duration: 134.928977ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.150196Z","caller":"traceutil/trace.go:171","msg":"trace[366972766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25602; }","duration":"111.615025ms","start":"2026-10-03T06:41:14.038561Z","end":"2026-10-03T06:41:14.150176Z","steps":["trace[366972766] 'agreement among raft nodes before linearized reading' (duration: 111.404859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:14.15031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.38962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T06:41:14.150331Z","caller":"traceutil/trace.go:171","msg":"trace[1649985299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25602; }","duration":"208.463913ms","start":"2026-10-03T06:41:13.941861Z","end":"2026-10-03T06:41:14.150325Z","steps":["trace[1649985299] 'agreement among raft nodes before linearized reading' (duration: 208.411311ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.150407Z","caller":"traceutil/trace.go:171","msg":"trace[1967239219] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"173.551221ms","start":"2026-10-03T06:41:13.976846Z","end":"2026-10-03T06:41:14.150397Z","steps":["trace[1967239219] 'process raft request' (duration: 172.987145ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.150106Z","caller":"traceutil/trace.go:171","msg":"trace[292866211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25602; }","duration":"111.880352ms","start":"2026-10-03T06:41:14.038216Z","end":"2026-10-03T06:41:14.150096Z","steps":["trace[292866211] 'agreement among raft nodes before linearized reading' (duration: 111.757168ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.150508Z","caller":"traceutil/trace.go:171","msg":"trace[761339204] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"139.39513ms","start":"2026-10-03T06:41:14.011104Z","end":"2026-10-03T06:41:14.150499Z","steps":["trace[761339204] 'process raft request' (duration: 138.794043ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.373215Z","caller":"traceutil/trace.go:171","msg":"trace[541211327] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"218.004955ms","start":"2026-10-03T06:41:14.155177Z","end":"2026-10-03T06:41:14.373182Z","steps":["trace[541211327] 'process raft request' (duration: 217.583024ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.373308Z","caller":"traceutil/trace.go:171","msg":"trace[1912074652] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"216.608637ms","start":"2026-10-03T06:41:14.156678Z","end":"2026-10-03T06:41:14.373286Z","steps":["trace[1912074652] 'process raft request' (duration: 216.454113ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.373285Z","caller":"traceutil/trace.go:171","msg":"trace[738698595] linearizableReadLoop","detail":"{readStateIndex:26454; appliedIndex:26451; }","duration":"170.061284ms","start":"2026-10-03T06:41:14.203194Z","end":"2026-10-03T06:41:14.373255Z","steps":["trace[738698595] 'read index received' (duration: 169.500229ms)","trace[738698595] 'applied index is now lower than readState.Index' (duration: 559.565µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:14.373211Z","caller":"traceutil/trace.go:171","msg":"trace[585500793] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"216.827364ms","start":"2026-10-03T06:41:14.15636Z","end":"2026-10-03T06:41:14.373188Z","steps":["trace[585500793] 'process raft request' (duration: 216.6857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:14.373536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.311182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:14.373591Z","caller":"traceutil/trace.go:171","msg":"trace[924545052] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25606; }","duration":"170.472416ms","start":"2026-10-03T06:41:14.2031Z","end":"2026-10-03T06:41:14.373573Z","steps":["trace[924545052] 'agreement among raft nodes before linearized reading' (duration: 170.301281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:14.37559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.943416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-03T06:41:14.375657Z","caller":"traceutil/trace.go:171","msg":"trace[952378372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25607; }","duration":"148.10598ms","start":"2026-10-03T06:41:14.227534Z","end":"2026-10-03T06:41:14.37564Z","steps":["trace[952378372] 'agreement among raft nodes before linearized reading' (duration: 147.7397ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:14.37568Z","caller":"traceutil/trace.go:171","msg":"trace[77253071] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"136.009117ms","start":"2026-10-03T06:41:14.239605Z","end":"2026-10-03T06:41:14.375614Z","steps":["trace[77253071] 'process raft request' (duration: 135.558055ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:16.39113Z","caller":"traceutil/trace.go:171","msg":"trace[1738778453] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"233.7902ms","start":"2026-10-03T06:41:16.157306Z","end":"2026-10-03T06:41:16.391096Z","steps":["trace[1738778453] 'process raft request' (duration: 233.508952ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:16.391228Z","caller":"traceutil/trace.go:171","msg":"trace[222289241] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"231.60682ms","start":"2026-10-03T06:41:16.159576Z","end":"2026-10-03T06:41:16.391183Z","steps":["trace[222289241] 'process raft request' (duration: 231.408355ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:16.395198Z","caller":"traceutil/trace.go:171","msg":"trace[131507433] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"110.203316ms","start":"2026-10-03T06:41:16.284963Z","end":"2026-10-03T06:41:16.395167Z","steps":["trace[131507433] 'process raft request' (duration: 109.780474ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:19.373592Z","caller":"traceutil/trace.go:171","msg":"trace[511364422] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"131.939584ms","start":"2026-10-03T06:41:19.241625Z","end":"2026-10-03T06:41:19.373564Z","steps":["trace[511364422] 'process raft request' (duration: 131.723388ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:19.54629Z","caller":"traceutil/trace.go:171","msg":"trace[1025580246] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"303.761158ms","start":"2026-10-03T06:41:19.242343Z","end":"2026-10-03T06:41:19.546104Z","steps":["trace[1025580246] 'process raft request' (duration: 250.901812ms)","trace[1025580246] 'compare' (duration: 52.63655ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:19.546545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:19.242305Z","time spent":"304.102828ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:22.751275Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067b3.wal"} {"level":"info","ts":"2026-10-03T06:41:48.920183Z","caller":"traceutil/trace.go:171","msg":"trace[822413947] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"704.233171ms","start":"2026-10-03T06:41:48.215909Z","end":"2026-10-03T06:41:48.920142Z","steps":["trace[822413947] 'process raft request' (duration: 704.014925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:48.920595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.215877Z","time spent":"704.481498ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:49.116299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.099252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:49.116558Z","caller":"traceutil/trace.go:171","msg":"trace[834682631] linearizableReadLoop","detail":"{readStateIndex:26752; appliedIndex:26748; }","duration":"524.420147ms","start":"2026-10-03T06:41:48.592124Z","end":"2026-10-03T06:41:49.116544Z","steps":["trace[834682631] 'read index received' (duration: 328.050188ms)","trace[834682631] 'applied index is now lower than readState.Index' (duration: 196.368859ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.116625Z","caller":"traceutil/trace.go:171","msg":"trace[521118448] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"548.824059ms","start":"2026-10-03T06:41:48.567796Z","end":"2026-10-03T06:41:49.11662Z","steps":["trace[521118448] 'process raft request' (duration: 548.593763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.116665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.567753Z","time spent":"548.8894ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:49.116721Z","caller":"traceutil/trace.go:171","msg":"trace[651806547] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"729.311881ms","start":"2026-10-03T06:41:48.387348Z","end":"2026-10-03T06:41:49.11666Z","steps":["trace[651806547] 'process raft request' (duration: 569.752436ms)","trace[651806547] 'compare' (duration: 158.859216ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.116891Z","caller":"traceutil/trace.go:171","msg":"trace[1809079955] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"548.944002ms","start":"2026-10-03T06:41:48.56794Z","end":"2026-10-03T06:41:49.116884Z","steps":["trace[1809079955] 'process raft request' (duration: 548.530711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.116939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.567931Z","time spent":"548.976343ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:49.116899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.387322Z","time spent":"729.481076ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:49.117024Z","caller":"traceutil/trace.go:171","msg":"trace[1989090844] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"549.026004ms","start":"2026-10-03T06:41:48.567992Z","end":"2026-10-03T06:41:49.117018Z","steps":["trace[1989090844] 'process raft request' (duration: 548.52402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.117057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.567987Z","time spent":"549.053044ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:49.117179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.056134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:41:49.117191Z","caller":"traceutil/trace.go:171","msg":"trace[925676424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25896; }","duration":"525.103905ms","start":"2026-10-03T06:41:48.592082Z","end":"2026-10-03T06:41:49.117186Z","steps":["trace[925676424] 'agreement among raft nodes before linearized reading' (duration: 525.017253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.117206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.592062Z","time spent":"525.141436ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T06:41:49.117279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.269127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T06:41:49.117295Z","caller":"traceutil/trace.go:171","msg":"trace[1938448098] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25896; }","duration":"510.316798ms","start":"2026-10-03T06:41:48.606969Z","end":"2026-10-03T06:41:49.117286Z","steps":["trace[1938448098] 'agreement among raft nodes before linearized reading' (duration: 510.274387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.117308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.606948Z","time spent":"510.354339ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-03T06:41:49.117409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.18914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:41:49.11742Z","caller":"traceutil/trace.go:171","msg":"trace[191561556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25896; }","duration":"312.245202ms","start":"2026-10-03T06:41:48.805171Z","end":"2026-10-03T06:41:49.117416Z","steps":["trace[191561556] 'agreement among raft nodes before linearized reading' (duration: 312.202951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.11743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.805144Z","time spent":"312.282802ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-03T06:41:49.118036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.076391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:41:49.118052Z","caller":"traceutil/trace.go:171","msg":"trace[1256106230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25896; }","duration":"502.124362ms","start":"2026-10-03T06:41:48.615923Z","end":"2026-10-03T06:41:49.118047Z","steps":["trace[1256106230] 'agreement among raft nodes before linearized reading' (duration: 502.070611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.118064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.615904Z","time spent":"502.157223ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-03T06:41:49.118859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.738549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T06:41:49.118954Z","caller":"traceutil/trace.go:171","msg":"trace[295672452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25896; }","duration":"502.861622ms","start":"2026-10-03T06:41:48.616065Z","end":"2026-10-03T06:41:49.118927Z","steps":["trace[295672452] 'agreement among raft nodes before linearized reading' (duration: 502.531223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.119001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.616061Z","time spent":"502.930894ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T06:41:49.119585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.524601ms","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-10-03T06:41:49.119647Z","caller":"traceutil/trace.go:171","msg":"trace[1501459120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25896; }","duration":"503.605943ms","start":"2026-10-03T06:41:48.616026Z","end":"2026-10-03T06:41:49.119632Z","steps":["trace[1501459120] 'agreement among raft nodes before linearized reading' (duration: 503.391507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.119687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:48.61602Z","time spent":"503.658644ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T06:41:49.370792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.126798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:49.371039Z","caller":"traceutil/trace.go:171","msg":"trace[1685603270] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"246.448419ms","start":"2026-10-03T06:41:49.124561Z","end":"2026-10-03T06:41:49.371009Z","steps":["trace[1685603270] 'process raft request' (duration: 141.879268ms)","trace[1685603270] 'compare' (duration: 103.925623ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.44384Z","caller":"traceutil/trace.go:171","msg":"trace[1346191186] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"318.538305ms","start":"2026-10-03T06:41:49.125272Z","end":"2026-10-03T06:41:49.443811Z","steps":["trace[1346191186] 'process raft request' (duration: 318.447643ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:49.443899Z","caller":"traceutil/trace.go:171","msg":"trace[41113288] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"319.209854ms","start":"2026-10-03T06:41:49.124648Z","end":"2026-10-03T06:41:49.443858Z","steps":["trace[41113288] 'process raft request' (duration: 318.934266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.444104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:49.12526Z","time spent":"318.668499ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:49.44415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:49.124615Z","time spent":"319.364768ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:49.444317Z","caller":"traceutil/trace.go:171","msg":"trace[1915823390] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"318.955947ms","start":"2026-10-03T06:41:49.12535Z","end":"2026-10-03T06:41:49.444306Z","steps":["trace[1915823390] 'process raft request' (duration: 318.417032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.444364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:49.125342Z","time spent":"319.006658ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:49.444351Z","caller":"traceutil/trace.go:171","msg":"trace[518630908] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"318.920995ms","start":"2026-10-03T06:41:49.1254Z","end":"2026-10-03T06:41:49.444321Z","steps":["trace[518630908] 'process raft request' (duration: 318.72077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.44456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:49.125393Z","time spent":"319.048259ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:49.444884Z","caller":"traceutil/trace.go:171","msg":"trace[395540698] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"318.395201ms","start":"2026-10-03T06:41:49.126452Z","end":"2026-10-03T06:41:49.444848Z","steps":["trace[395540698] 'process raft request' (duration: 317.813815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.445097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:49.126419Z","time spent":"318.580216ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:49.445823Z","caller":"traceutil/trace.go:171","msg":"trace[417539533] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"214.351105ms","start":"2026-10-03T06:41:49.231451Z","end":"2026-10-03T06:41:49.445802Z","steps":["trace[417539533] 'process raft request' (duration: 213.348708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.710543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.829568ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:49.710728Z","caller":"traceutil/trace.go:171","msg":"trace[2042928535] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25903; }","duration":"215.060674ms","start":"2026-10-03T06:41:49.495639Z","end":"2026-10-03T06:41:49.7107Z","steps":["trace[2042928535] 'range keys from in-memory index tree' (duration: 214.801598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.711123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.056636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:49.711278Z","caller":"traceutil/trace.go:171","msg":"trace[1557457625] linearizableReadLoop","detail":"{readStateIndex:26760; appliedIndex:26759; }","duration":"139.005459ms","start":"2026-10-03T06:41:49.572236Z","end":"2026-10-03T06:41:49.711242Z","steps":["trace[1557457625] 'read index received' (duration: 34.585153ms)","trace[1557457625] 'applied index is now lower than readState.Index' (duration: 104.418086ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.711329Z","caller":"traceutil/trace.go:171","msg":"trace[590854171] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"208.313148ms","start":"2026-10-03T06:41:49.502995Z","end":"2026-10-03T06:41:49.711308Z","steps":["trace[590854171] 'process raft request' (duration: 103.941583ms)","trace[590854171] 'compare' (duration: 103.178162ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:49.711411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.169904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T06:41:49.711446Z","caller":"traceutil/trace.go:171","msg":"trace[1481555049] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25904; }","duration":"139.256666ms","start":"2026-10-03T06:41:49.572174Z","end":"2026-10-03T06:41:49.71143Z","steps":["trace[1481555049] 'agreement among raft nodes before linearized reading' (duration: 139.171273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.75373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.421911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:49.753848Z","caller":"traceutil/trace.go:171","msg":"trace[96064821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25905; }","duration":"143.638456ms","start":"2026-10-03T06:41:49.610184Z","end":"2026-10-03T06:41:49.753823Z","steps":["trace[96064821] 'agreement among raft nodes before linearized reading' (duration: 143.384039ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.039004Z","caller":"traceutil/trace.go:171","msg":"trace[539408805] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"111.221104ms","start":"2026-10-03T06:41:50.927356Z","end":"2026-10-03T06:41:51.038577Z","steps":["trace[539408805] 'process raft request' (duration: 110.559736ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.231061Z","caller":"traceutil/trace.go:171","msg":"trace[145264009] linearizableReadLoop","detail":"{readStateIndex:26765; appliedIndex:26764; }","duration":"189.997164ms","start":"2026-10-03T06:41:51.041019Z","end":"2026-10-03T06:41:51.231016Z","steps":["trace[145264009] 'read index received' (duration: 125.753204ms)","trace[145264009] 'applied index is now lower than readState.Index' (duration: 64.242309ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:51.231215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.174459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:51.231246Z","caller":"traceutil/trace.go:171","msg":"trace[557254918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25908; }","duration":"190.275662ms","start":"2026-10-03T06:41:51.040959Z","end":"2026-10-03T06:41:51.231234Z","steps":["trace[557254918] 'agreement among raft nodes before linearized reading' (duration: 190.184739ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.412703Z","caller":"traceutil/trace.go:171","msg":"trace[1972646884] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"288.118287ms","start":"2026-10-03T06:41:51.12455Z","end":"2026-10-03T06:41:51.412668Z","steps":["trace[1972646884] 'process raft request' (duration: 288.009094ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.412827Z","caller":"traceutil/trace.go:171","msg":"trace[948292764] transaction","detail":"{read_only:false; response_revision:25909; number_of_response:1; }","duration":"288.652202ms","start":"2026-10-03T06:41:51.124147Z","end":"2026-10-03T06:41:51.412799Z","steps":["trace[948292764] 'process raft request' (duration: 244.217968ms)","trace[948292764] 'compare' (duration: 43.551599ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:51.413029Z","caller":"traceutil/trace.go:171","msg":"trace[506439593] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"288.800485ms","start":"2026-10-03T06:41:51.124175Z","end":"2026-10-03T06:41:51.412975Z","steps":["trace[506439593] 'process raft request' (duration: 288.183998ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.413319Z","caller":"traceutil/trace.go:171","msg":"trace[564905004] transaction","detail":"{read_only:false; response_revision:25913; number_of_response:1; }","duration":"181.14377ms","start":"2026-10-03T06:41:51.23216Z","end":"2026-10-03T06:41:51.413304Z","steps":["trace[564905004] 'process raft request' (duration: 180.858933ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.413309Z","caller":"traceutil/trace.go:171","msg":"trace[192832021] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"288.57403ms","start":"2026-10-03T06:41:51.124704Z","end":"2026-10-03T06:41:51.413278Z","steps":["trace[192832021] 'process raft request' (duration: 287.912592ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.613715Z","caller":"traceutil/trace.go:171","msg":"trace[1891073887] linearizableReadLoop","detail":"{readStateIndex:26771; appliedIndex:26770; }","duration":"194.815747ms","start":"2026-10-03T06:41:51.418847Z","end":"2026-10-03T06:41:51.613662Z","steps":["trace[1891073887] 'read index received' (duration: 113.002383ms)","trace[1891073887] 'applied index is now lower than readState.Index' (duration: 81.811854ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:51.614521Z","caller":"traceutil/trace.go:171","msg":"trace[1065453519] transaction","detail":"{read_only:false; response_revision:25914; number_of_response:1; }","duration":"196.235845ms","start":"2026-10-03T06:41:51.418256Z","end":"2026-10-03T06:41:51.614491Z","steps":["trace[1065453519] 'process raft request' (duration: 113.587789ms)","trace[1065453519] 'compare' (duration: 81.610128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:51.614835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.948358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:41:51.614957Z","caller":"traceutil/trace.go:171","msg":"trace[320006776] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25914; }","duration":"196.086431ms","start":"2026-10-03T06:41:51.418843Z","end":"2026-10-03T06:41:51.614929Z","steps":["trace[320006776] 'agreement among raft nodes before linearized reading' (duration: 195.717791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:51.615028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.819423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:41:51.615091Z","caller":"traceutil/trace.go:171","msg":"trace[156526800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25914; }","duration":"163.928776ms","start":"2026-10-03T06:41:51.451147Z","end":"2026-10-03T06:41:51.615076Z","steps":["trace[156526800] 'agreement among raft nodes before linearized reading' (duration: 163.763771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:51.615322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.958376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-03T06:41:51.6153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.954826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:41:51.61536Z","caller":"traceutil/trace.go:171","msg":"trace[821942102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25914; }","duration":"164.028968ms","start":"2026-10-03T06:41:51.451319Z","end":"2026-10-03T06:41:51.615348Z","steps":["trace[821942102] 'agreement among raft nodes before linearized reading' (duration: 163.74039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:51.615314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.009828ms","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-10-03T06:41:51.61539Z","caller":"traceutil/trace.go:171","msg":"trace[120004744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25914; }","duration":"164.08797ms","start":"2026-10-03T06:41:51.451281Z","end":"2026-10-03T06:41:51.615369Z","steps":["trace[120004744] 'agreement among raft nodes before linearized reading' (duration: 163.74751ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.615422Z","caller":"traceutil/trace.go:171","msg":"trace[1233088963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25914; }","duration":"164.158532ms","start":"2026-10-03T06:41:51.451237Z","end":"2026-10-03T06:41:51.615395Z","steps":["trace[1233088963] 'agreement among raft nodes before linearized reading' (duration: 163.798602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:51.616107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.781209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T06:41:51.61617Z","caller":"traceutil/trace.go:171","msg":"trace[1514150665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25914; }","duration":"164.858171ms","start":"2026-10-03T06:41:51.451292Z","end":"2026-10-03T06:41:51.61615Z","steps":["trace[1514150665] 'agreement among raft nodes before linearized reading' (duration: 163.638267ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:51.799861Z","caller":"traceutil/trace.go:171","msg":"trace[1352466101] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"179.490934ms","start":"2026-10-03T06:41:51.620341Z","end":"2026-10-03T06:41:51.799832Z","steps":["trace[1352466101] 'process raft request' (duration: 151.130893ms)","trace[1352466101] 'compare' (duration: 28.019322ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:52.073839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.232943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:52.074437Z","caller":"traceutil/trace.go:171","msg":"trace[940319291] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"453.822302ms","start":"2026-10-03T06:41:51.620585Z","end":"2026-10-03T06:41:52.074407Z","steps":["trace[940319291] 'process raft request' (duration: 312.931041ms)","trace[940319291] 'compare' (duration: 139.704749ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:52.074605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:51.620563Z","time spent":"453.953425ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:52.074729Z","caller":"traceutil/trace.go:171","msg":"trace[1081988073] linearizableReadLoop","detail":"{readStateIndex:26778; appliedIndex:26772; }","duration":"138.085983ms","start":"2026-10-03T06:41:51.936621Z","end":"2026-10-03T06:41:52.074707Z","steps":["trace[1081988073] 'read index received' (duration: 42.371µs)","trace[1081988073] 'applied index is now lower than readState.Index' (duration: 138.041422ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:52.074868Z","caller":"traceutil/trace.go:171","msg":"trace[1119245040] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"453.966586ms","start":"2026-10-03T06:41:51.620893Z","end":"2026-10-03T06:41:52.074859Z","steps":["trace[1119245040] 'process raft request' (duration: 453.484872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:52.074916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:51.62085Z","time spent":"454.040648ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:52.07491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.274689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:52.07503Z","caller":"traceutil/trace.go:171","msg":"trace[15522867] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25921; }","duration":"138.440314ms","start":"2026-10-03T06:41:51.936574Z","end":"2026-10-03T06:41:52.075014Z","steps":["trace[15522867] 'agreement among raft nodes before linearized reading' (duration: 138.204437ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:52.075058Z","caller":"traceutil/trace.go:171","msg":"trace[761945306] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"454.13637ms","start":"2026-10-03T06:41:51.62089Z","end":"2026-10-03T06:41:52.075026Z","steps":["trace[761945306] 'process raft request' (duration: 453.378129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:52.075226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:51.620861Z","time spent":"454.276814ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:52.075298Z","caller":"traceutil/trace.go:171","msg":"trace[324813148] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"358.091684ms","start":"2026-10-03T06:41:51.717197Z","end":"2026-10-03T06:41:52.075289Z","steps":["trace[324813148] 'process raft request' (duration: 357.254981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:52.075349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:51.717166Z","time spent":"358.158276ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:52.075407Z","caller":"traceutil/trace.go:171","msg":"trace[1986919858] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"453.559425ms","start":"2026-10-03T06:41:51.621841Z","end":"2026-10-03T06:41:52.0754Z","steps":["trace[1986919858] 'process raft request' (duration: 452.571477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:52.075445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:51.621826Z","time spent":"453.599756ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:52.075452Z","caller":"traceutil/trace.go:171","msg":"trace[1277250084] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"315.012608ms","start":"2026-10-03T06:41:51.760399Z","end":"2026-10-03T06:41:52.075411Z","steps":["trace[1277250084] 'process raft request' (duration: 314.105863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:52.075583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:51.76036Z","time spent":"315.144051ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:52.315706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.699586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:52.315822Z","caller":"traceutil/trace.go:171","msg":"trace[349841384] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25921; }","duration":"124.906721ms","start":"2026-10-03T06:41:52.190886Z","end":"2026-10-03T06:41:52.315792Z","steps":["trace[349841384] 'count revisions from in-memory index tree' (duration: 124.570392ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:53.562948Z","caller":"traceutil/trace.go:171","msg":"trace[1434821496] linearizableReadLoop","detail":"{readStateIndex:26783; appliedIndex:26780; }","duration":"128.740916ms","start":"2026-10-03T06:41:53.434171Z","end":"2026-10-03T06:41:53.562912Z","steps":["trace[1434821496] 'read index received' (duration: 93.869565ms)","trace[1434821496] 'applied index is now lower than readState.Index' (duration: 34.869241ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:53.563032Z","caller":"traceutil/trace.go:171","msg":"trace[1586453327] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"143.176085ms","start":"2026-10-03T06:41:53.41983Z","end":"2026-10-03T06:41:53.563006Z","steps":["trace[1586453327] 'process raft request' (duration: 142.926528ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:53.563053Z","caller":"traceutil/trace.go:171","msg":"trace[441245168] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"143.264717ms","start":"2026-10-03T06:41:53.419743Z","end":"2026-10-03T06:41:53.563007Z","steps":["trace[441245168] 'process raft request' (duration: 108.273423ms)","trace[441245168] 'compare' (duration: 34.48341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:53.563154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.936952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:53.563216Z","caller":"traceutil/trace.go:171","msg":"trace[1334962653] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25926; }","duration":"129.068445ms","start":"2026-10-03T06:41:53.43413Z","end":"2026-10-03T06:41:53.563198Z","steps":["trace[1334962653] 'agreement among raft nodes before linearized reading' (duration: 128.907191ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:53.563284Z","caller":"traceutil/trace.go:171","msg":"trace[478913368] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"143.330868ms","start":"2026-10-03T06:41:53.419843Z","end":"2026-10-03T06:41:53.563174Z","steps":["trace[478913368] 'process raft request' (duration: 142.997139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:53.835127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.522508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5bf06f45-8387-4687-9f90-a1bb1fa034da\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-10-03T06:41:53.835243Z","caller":"traceutil/trace.go:171","msg":"trace[854834876] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5bf06f45-8387-4687-9f90-a1bb1fa034da; range_end:; response_count:1; response_revision:25928; }","duration":"170.742583ms","start":"2026-10-03T06:41:53.66447Z","end":"2026-10-03T06:41:53.835213Z","steps":["trace[854834876] 'range keys from in-memory index tree' (duration: 170.26112ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:54.79959Z","caller":"traceutil/trace.go:171","msg":"trace[90317159] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"113.088555ms","start":"2026-10-03T06:41:54.686471Z","end":"2026-10-03T06:41:54.799559Z","steps":["trace[90317159] 'process raft request' (duration: 112.876309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:56.01245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.20361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-03T06:41:56.0126Z","caller":"traceutil/trace.go:171","msg":"trace[1038990448] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25943; }","duration":"137.436207ms","start":"2026-10-03T06:41:55.875137Z","end":"2026-10-03T06:41:56.012573Z","steps":["trace[1038990448] 'range keys from in-memory index tree' (duration: 136.920332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:56.013108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.415823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:41:56.013207Z","caller":"traceutil/trace.go:171","msg":"trace[1428566333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25943; }","duration":"150.574659ms","start":"2026-10-03T06:41:55.862609Z","end":"2026-10-03T06:41:56.013184Z","steps":["trace[1428566333] 'range keys from in-memory index tree' (duration: 149.803127ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:58.33714Z","caller":"traceutil/trace.go:171","msg":"trace[806779293] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"155.637148ms","start":"2026-10-03T06:41:58.181467Z","end":"2026-10-03T06:41:58.337104Z","steps":["trace[806779293] 'process raft request' (duration: 155.275488ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:58.337429Z","caller":"traceutil/trace.go:171","msg":"trace[1102747167] linearizableReadLoop","detail":"{readStateIndex:26823; appliedIndex:26820; }","duration":"131.075471ms","start":"2026-10-03T06:41:58.206323Z","end":"2026-10-03T06:41:58.337399Z","steps":["trace[1102747167] 'read index received' (duration: 34.454669ms)","trace[1102747167] 'applied index is now lower than readState.Index' (duration: 96.618782ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:58.337561Z","caller":"traceutil/trace.go:171","msg":"trace[624458599] transaction","detail":"{read_only:false; response_revision:25965; number_of_response:1; }","duration":"155.614787ms","start":"2026-10-03T06:41:58.181903Z","end":"2026-10-03T06:41:58.337517Z","steps":["trace[624458599] 'process raft request' (duration: 155.146165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:58.337695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.353478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-03T06:41:58.337739Z","caller":"traceutil/trace.go:171","msg":"trace[1920143394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25965; }","duration":"131.467471ms","start":"2026-10-03T06:41:58.206257Z","end":"2026-10-03T06:41:58.337725Z","steps":["trace[1920143394] 'agreement among raft nodes before linearized reading' (duration: 131.261606ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:45:57.948611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25432} {"level":"info","ts":"2026-10-03T06:45:58.140008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25432,"took":"186.362246ms","hash":2551930610,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":35545088,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-03T06:45:58.140092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2551930610,"revision":25432,"compact-revision":22030} {"level":"info","ts":"2026-10-03T06:47:28.781983Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T06:47:28.789404Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-03T06:47:28.78959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-03T06:50:57.956979Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28345} {"level":"info","ts":"2026-10-03T06:50:58.129321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28345,"took":"166.501711ms","hash":330390409,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31363072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T06:50:58.129459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":330390409,"revision":28345,"compact-revision":25432} {"level":"info","ts":"2026-10-03T06:55:57.965924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30623} {"level":"info","ts":"2026-10-03T06:55:58.099108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30623,"took":"127.748472ms","hash":719477597,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T06:55:58.099211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":719477597,"revision":30623,"compact-revision":28345} {"level":"info","ts":"2026-10-03T07:00:57.976107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32878} {"level":"info","ts":"2026-10-03T07:00:58.143325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32878,"took":"160.791061ms","hash":3164568548,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T07:00:58.143418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3164568548,"revision":32878,"compact-revision":30623} {"level":"info","ts":"2026-10-03T07:05:57.984375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35169} {"level":"info","ts":"2026-10-03T07:05:58.137309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35169,"took":"144.648956ms","hash":4000001824,"current-db-size-bytes":59564032,"current-db-size":"60 MB","current-db-size-in-use-bytes":32632832,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T07:05:58.137378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4000001824,"revision":35169,"compact-revision":32878} {"level":"info","ts":"2026-10-03T07:08:49.458645Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T07:08:49.468052Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-03T07:08:49.46823Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-03T07:10:57.995801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37494} {"level":"info","ts":"2026-10-03T07:10:58.141724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37494,"took":"141.749257ms","hash":4191938745,"current-db-size-bytes":60850176,"current-db-size":"61 MB","current-db-size-in-use-bytes":33034240,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T07:10:58.141834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4191938745,"revision":37494,"compact-revision":35169} {"level":"info","ts":"2026-10-03T07:15:58.004012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39765} {"level":"info","ts":"2026-10-03T07:15:58.151147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39765,"took":"142.152043ms","hash":3399955509,"current-db-size-bytes":61272064,"current-db-size":"61 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T07:15:58.151218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3399955509,"revision":39765,"compact-revision":37494}