{"level":"warn","ts":"2026-09-09T01:09:46.200492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:09:46.200614Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.112: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.112:2380","--initial-cluster=instance=https://162.253.55.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.112:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-09T01:09:46.200704Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:09:46.200718Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-09T01:09:46.200744Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T01:09:46.201533Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"]} {"level":"info","ts":"2026-09-09T01:09:46.201624Z","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.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.112:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-09T01:09:46.206747Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.824368ms"} {"level":"info","ts":"2026-09-09T01:09:46.217876Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1b80133015df7bbe","cluster-id":"96c35284d1622e20"} {"level":"info","ts":"2026-09-09T01:09:46.218059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:09:46.218102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 0"} {"level":"info","ts":"2026-09-09T01:09:46.218127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1b80133015df7bbe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:09:46.218141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 1"} {"level":"info","ts":"2026-09-09T01:09:46.218217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"warn","ts":"2026-09-09T01:09:46.231455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:09:46.234552Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:09:46.236285Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-09T01:09:46.239459Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1b80133015df7bbe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:09:46.239865Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1b80133015df7bbe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:09:46.239885Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T01:09:46.240042Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T01:09:46.240054Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T01:09:46.24227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"info","ts":"2026-09-09T01:09:46.242401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","added-peer-id":"1b80133015df7bbe","added-peer-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-09T01:09:46.243624Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T01:09:46.243723Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-09T01:09:46.243771Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-09T01:09:46.243927Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1b80133015df7bbe","initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:09:46.243975Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:09:46.718833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:09:46.71889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:09:46.718925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgPreVoteResp from 1b80133015df7bbe at term 1"} {"level":"info","ts":"2026-09-09T01:09:46.718941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:09:46.718949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgVoteResp from 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-09T01:09:46.718959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became leader at term 2"} {"level":"info","ts":"2026-09-09T01:09:46.718968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1b80133015df7bbe elected leader 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-09T01:09:46.720786Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1b80133015df7bbe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.112:2379]}","request-path":"/0/members/1b80133015df7bbe/attributes","cluster-id":"96c35284d1622e20","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:09:46.726577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:09:46.726621Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:46.726735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:09:46.726792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:09:46.726887Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:09:46.727433Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:46.727528Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:46.727553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:46.729131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:09:46.729752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.112:2379"} {"level":"info","ts":"2026-09-09T01:10:02.685536Z","caller":"traceutil/trace.go:171","msg":"trace[277670764] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"380.164141ms","start":"2026-09-09T01:10:02.305336Z","end":"2026-09-09T01:10:02.685501Z","steps":["trace[277670764] 'process raft request' (duration: 379.957416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:02.68656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:02.305303Z","time spent":"380.397688ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4402,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:36.030989Z","caller":"traceutil/trace.go:171","msg":"trace[1603917271] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"104.917805ms","start":"2026-09-09T01:10:35.926027Z","end":"2026-09-09T01:10:36.030944Z","steps":["trace[1603917271] 'process raft request' (duration: 93.238684ms)","trace[1603917271] 'compare' (duration: 11.016824ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:36.070389Z","caller":"traceutil/trace.go:171","msg":"trace[42446356] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"122.702389ms","start":"2026-09-09T01:10:35.947641Z","end":"2026-09-09T01:10:36.070343Z","steps":["trace[42446356] 'process raft request' (duration: 122.272387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:41.489131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.30297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-29fmm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-09T01:10:41.489268Z","caller":"traceutil/trace.go:171","msg":"trace[1956567781] range","detail":"{range_begin:/registry/pods/kube-system/cilium-29fmm; range_end:; response_count:1; response_revision:614; }","duration":"321.550577ms","start":"2026-09-09T01:10:41.167698Z","end":"2026-09-09T01:10:41.489248Z","steps":["trace[1956567781] 'range keys from in-memory index tree' (duration: 321.099175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:41.489309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.167661Z","time spent":"321.637899ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-29fmm\" "} {"level":"info","ts":"2026-09-09T01:10:41.550889Z","caller":"traceutil/trace.go:171","msg":"trace[756275597] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"283.615306ms","start":"2026-09-09T01:10:41.267244Z","end":"2026-09-09T01:10:41.550859Z","steps":["trace[756275597] 'read index received' (duration: 282.720393ms)","trace[756275597] 'applied index is now lower than readState.Index' (duration: 893.773µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:41.55096Z","caller":"traceutil/trace.go:171","msg":"trace[1014027502] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"335.037587ms","start":"2026-09-09T01:10:41.215901Z","end":"2026-09-09T01:10:41.550939Z","steps":["trace[1014027502] 'process raft request' (duration: 334.258116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:41.551064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.838412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T01:10:41.551104Z","caller":"traceutil/trace.go:171","msg":"trace[80934315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"283.930975ms","start":"2026-09-09T01:10:41.267159Z","end":"2026-09-09T01:10:41.55109Z","steps":["trace[80934315] 'agreement among raft nodes before linearized reading' (duration: 283.815501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:41.551127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.215866Z","time spent":"335.15258ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:10:41.967914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.590487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:10:41.968051Z","caller":"traceutil/trace.go:171","msg":"trace[919721245] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"454.097489ms","start":"2026-09-09T01:10:41.513928Z","end":"2026-09-09T01:10:41.968025Z","steps":["trace[919721245] 'process raft request' (duration: 75.081141ms)","trace[919721245] 'compare' (duration: 378.404172ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:41.968131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.513892Z","time spent":"454.201092ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:42.23562Z","caller":"traceutil/trace.go:171","msg":"trace[418517775] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"332.889599ms","start":"2026-09-09T01:10:41.902708Z","end":"2026-09-09T01:10:42.235597Z","steps":["trace[418517775] 'read index received' (duration: 263.86747ms)","trace[418517775] 'applied index is now lower than readState.Index' (duration: 69.021319ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:42.235878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.25311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:10:42.23585Z","caller":"traceutil/trace.go:171","msg":"trace[1388719375] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"678.351443ms","start":"2026-09-09T01:10:41.557471Z","end":"2026-09-09T01:10:42.235823Z","steps":["trace[1388719375] 'process raft request' (duration: 609.014336ms)","trace[1388719375] 'compare' (duration: 68.927346ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:42.235911Z","caller":"traceutil/trace.go:171","msg":"trace[27249692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"300.467205ms","start":"2026-09-09T01:10:41.935435Z","end":"2026-09-09T01:10:42.235902Z","steps":["trace[27249692] 'agreement among raft nodes before linearized reading' (duration: 300.298301ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:42.235909Z","caller":"traceutil/trace.go:171","msg":"trace[1144097833] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"609.841029ms","start":"2026-09-09T01:10:41.626005Z","end":"2026-09-09T01:10:42.235846Z","steps":["trace[1144097833] 'process raft request' (duration: 609.557151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:42.236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.557442Z","time spent":"678.489977ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:10:42.236055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.311271ms","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-09-09T01:10:42.236108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.625976Z","time spent":"610.040513ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T01:10:42.236168Z","caller":"traceutil/trace.go:171","msg":"trace[877814928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"333.488836ms","start":"2026-09-09T01:10:41.902651Z","end":"2026-09-09T01:10:42.236139Z","steps":["trace[877814928] 'agreement among raft nodes before linearized reading' (duration: 333.080575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:42.235962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.935407Z","time spent":"300.549568ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:10:42.236251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:41.902629Z","time spent":"333.581838ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:12:19.514796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.057867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b909057-634c-466f-9a46-e055205a9354\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-09T01:12:19.514905Z","caller":"traceutil/trace.go:171","msg":"trace[388955278] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b909057-634c-466f-9a46-e055205a9354; range_end:; response_count:1; response_revision:1866; }","duration":"201.233482ms","start":"2026-09-09T01:12:19.313647Z","end":"2026-09-09T01:12:19.514881Z","steps":["trace[388955278] 'range keys from in-memory index tree' (duration: 200.844912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:19.515565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.267868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-09T01:12:19.51562Z","caller":"traceutil/trace.go:171","msg":"trace[1022263960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1866; }","duration":"457.369312ms","start":"2026-09-09T01:12:19.058235Z","end":"2026-09-09T01:12:19.515605Z","steps":["trace[1022263960] 'range keys from in-memory index tree' (duration: 457.119985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:19.515671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:12:19.058208Z","time spent":"457.449933ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-09T01:12:19.646677Z","caller":"traceutil/trace.go:171","msg":"trace[1180640503] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"125.676556ms","start":"2026-09-09T01:12:19.520956Z","end":"2026-09-09T01:12:19.646633Z","steps":["trace[1180640503] 'process raft request' (duration: 125.309486ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:19.919615Z","caller":"traceutil/trace.go:171","msg":"trace[1184933505] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"192.8498ms","start":"2026-09-09T01:12:19.726735Z","end":"2026-09-09T01:12:19.919585Z","steps":["trace[1184933505] 'read index received' (duration: 130.024802ms)","trace[1184933505] 'applied index is now lower than readState.Index' (duration: 62.824238ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:12:19.919752Z","caller":"traceutil/trace.go:171","msg":"trace[790360072] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"215.153501ms","start":"2026-09-09T01:12:19.704589Z","end":"2026-09-09T01:12:19.919742Z","steps":["trace[790360072] 'process raft request' (duration: 152.282622ms)","trace[790360072] 'compare' (duration: 62.554231ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:19.919939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.602426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:12:19.920032Z","caller":"traceutil/trace.go:171","msg":"trace[876312685] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"162.76046ms","start":"2026-09-09T01:12:19.757248Z","end":"2026-09-09T01:12:19.920009Z","steps":["trace[876312685] 'agreement among raft nodes before linearized reading' (duration: 162.530494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:19.920125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.366263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b909057-634c-466f-9a46-e055205a9354\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-09T01:12:19.920153Z","caller":"traceutil/trace.go:171","msg":"trace[949719694] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b909057-634c-466f-9a46-e055205a9354; range_end:; response_count:1; response_revision:1868; }","duration":"193.449446ms","start":"2026-09-09T01:12:19.726694Z","end":"2026-09-09T01:12:19.920144Z","steps":["trace[949719694] 'agreement among raft nodes before linearized reading' (duration: 193.297802ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:20.172343Z","caller":"traceutil/trace.go:171","msg":"trace[1247819220] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"191.415051ms","start":"2026-09-09T01:12:19.980887Z","end":"2026-09-09T01:12:20.172302Z","steps":["trace[1247819220] 'process raft request' (duration: 191.188735ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:28.20846Z","caller":"traceutil/trace.go:171","msg":"trace[1036997008] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"126.512949ms","start":"2026-09-09T01:12:28.081906Z","end":"2026-09-09T01:12:28.208419Z","steps":["trace[1036997008] 'read index received' (duration: 95.442974ms)","trace[1036997008] 'applied index is now lower than readState.Index' (duration: 31.067745ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:12:28.208553Z","caller":"traceutil/trace.go:171","msg":"trace[1499751146] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"179.556956ms","start":"2026-09-09T01:12:28.028955Z","end":"2026-09-09T01:12:28.208512Z","steps":["trace[1499751146] 'process raft request' (duration: 148.478521ms)","trace[1499751146] 'compare' (duration: 30.615023ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:28.208706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.757975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:12:28.208783Z","caller":"traceutil/trace.go:171","msg":"trace[1068193885] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1953; }","duration":"126.92847ms","start":"2026-09-09T01:12:28.081828Z","end":"2026-09-09T01:12:28.208757Z","steps":["trace[1068193885] 'agreement among raft nodes before linearized reading' (duration: 126.782426ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:33.166709Z","caller":"traceutil/trace.go:171","msg":"trace[1619040560] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"121.32661ms","start":"2026-09-09T01:12:33.045349Z","end":"2026-09-09T01:12:33.166675Z","steps":["trace[1619040560] 'read index received' (duration: 121.051853ms)","trace[1619040560] 'applied index is now lower than readState.Index' (duration: 273.647µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:12:33.166821Z","caller":"traceutil/trace.go:171","msg":"trace[2125736535] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"126.016805ms","start":"2026-09-09T01:12:33.04075Z","end":"2026-09-09T01:12:33.166766Z","steps":["trace[2125736535] 'process raft request' (duration: 125.742138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:33.167019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.635189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:12:33.167093Z","caller":"traceutil/trace.go:171","msg":"trace[618148890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1985; }","duration":"121.743091ms","start":"2026-09-09T01:12:33.045333Z","end":"2026-09-09T01:12:33.167076Z","steps":["trace[618148890] 'agreement among raft nodes before linearized reading' (duration: 121.477444ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:05.34123Z","caller":"traceutil/trace.go:171","msg":"trace[1559889640] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"115.66449ms","start":"2026-09-09T01:13:05.225513Z","end":"2026-09-09T01:13:05.341178Z","steps":["trace[1559889640] 'process raft request' (duration: 17.950316ms)","trace[1559889640] 'compare' (duration: 97.491258ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:13:10.638687Z","caller":"traceutil/trace.go:171","msg":"trace[580344680] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"195.11358ms","start":"2026-09-09T01:13:10.443548Z","end":"2026-09-09T01:13:10.638661Z","steps":["trace[580344680] 'read index received' (duration: 194.826112ms)","trace[580344680] 'applied index is now lower than readState.Index' (duration: 285.858µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:13:10.638773Z","caller":"traceutil/trace.go:171","msg":"trace[1834045560] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"209.432969ms","start":"2026-09-09T01:13:10.429291Z","end":"2026-09-09T01:13:10.638724Z","steps":["trace[1834045560] 'process raft request' (duration: 209.173292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:10.638956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.323776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:13:10.639131Z","caller":"traceutil/trace.go:171","msg":"trace[1164102314] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2200; }","duration":"195.637283ms","start":"2026-09-09T01:13:10.443456Z","end":"2026-09-09T01:13:10.639093Z","steps":["trace[1164102314] 'agreement among raft nodes before linearized reading' (duration: 195.307864ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:00.967096Z","caller":"traceutil/trace.go:171","msg":"trace[2082930311] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"102.342676ms","start":"2026-09-09T01:14:00.864455Z","end":"2026-09-09T01:14:00.966798Z","steps":["trace[2082930311] 'process raft request' (duration: 102.190352ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:34.798645Z","caller":"traceutil/trace.go:171","msg":"trace[330856830] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"113.56021ms","start":"2026-09-09T01:14:34.685044Z","end":"2026-09-09T01:14:34.798605Z","steps":["trace[330856830] 'process raft request' (duration: 113.210401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.311231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.663529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:14:35.311925Z","caller":"traceutil/trace.go:171","msg":"trace[1835656681] linearizableReadLoop","detail":"{readStateIndex:2956; appliedIndex:2955; }","duration":"274.702746ms","start":"2026-09-09T01:14:35.037197Z","end":"2026-09-09T01:14:35.3119Z","steps":["trace[1835656681] 'read index received' (duration: 148.343688ms)","trace[1835656681] 'applied index is now lower than readState.Index' (duration: 126.357318ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:14:35.312149Z","caller":"traceutil/trace.go:171","msg":"trace[533113326] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"508.102091ms","start":"2026-09-09T01:14:34.804027Z","end":"2026-09-09T01:14:35.312129Z","steps":["trace[533113326] 'process raft request' (duration: 381.390394ms)","trace[533113326] 'compare' (duration: 125.511555ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:14:35.312357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:14:34.803994Z","time spent":"508.287356ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:14:35.31272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.499647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d38209ab26fff5\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-09T01:14:35.312839Z","caller":"traceutil/trace.go:171","msg":"trace[521149124] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d38209ab26fff5; range_end:; response_count:1; response_revision:2848; }","duration":"275.675772ms","start":"2026-09-09T01:14:35.037138Z","end":"2026-09-09T01:14:35.312814Z","steps":["trace[521149124] 'agreement among raft nodes before linearized reading' (duration: 275.021745ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:35.393239Z","caller":"traceutil/trace.go:171","msg":"trace[215671363] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"295.268539ms","start":"2026-09-09T01:14:35.097927Z","end":"2026-09-09T01:14:35.393196Z","steps":["trace[215671363] 'process raft request' (duration: 295.075164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.393285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.733828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T01:14:35.393451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.893352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-09T01:14:35.393505Z","caller":"traceutil/trace.go:171","msg":"trace[223315150] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2849; }","duration":"194.006725ms","start":"2026-09-09T01:14:35.19948Z","end":"2026-09-09T01:14:35.393486Z","steps":["trace[223315150] 'agreement among raft nodes before linearized reading' (duration: 193.847471ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:35.393521Z","caller":"traceutil/trace.go:171","msg":"trace[1204378451] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2849; }","duration":"269.030336ms","start":"2026-09-09T01:14:35.12444Z","end":"2026-09-09T01:14:35.39347Z","steps":["trace[1204378451] 'agreement among raft nodes before linearized reading' (duration: 268.736249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:14:35.393808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.396968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-09T01:14:35.393937Z","caller":"traceutil/trace.go:171","msg":"trace[1658851680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2849; }","duration":"154.640894ms","start":"2026-09-09T01:14:35.239271Z","end":"2026-09-09T01:14:35.393912Z","steps":["trace[1658851680] 'agreement among raft nodes before linearized reading' (duration: 153.974476ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:17:53.242269Z","caller":"traceutil/trace.go:171","msg":"trace[1949277703] transaction","detail":"{read_only:false; response_revision:4097; number_of_response:1; }","duration":"191.653309ms","start":"2026-09-09T01:17:53.050578Z","end":"2026-09-09T01:17:53.242231Z","steps":["trace[1949277703] 'process raft request' (duration: 179.554699ms)","trace[1949277703] 'compare' (duration: 11.977097ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:27.83299Z","caller":"traceutil/trace.go:171","msg":"trace[866377604] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"101.243961ms","start":"2026-09-09T01:19:27.731728Z","end":"2026-09-09T01:19:27.832972Z","steps":["trace[866377604] 'process raft request' (duration: 100.858971ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:31.365649Z","caller":"traceutil/trace.go:171","msg":"trace[1604466409] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"123.49679ms","start":"2026-09-09T01:19:31.242123Z","end":"2026-09-09T01:19:31.365619Z","steps":["trace[1604466409] 'process raft request' (duration: 123.300775ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:31.366035Z","caller":"traceutil/trace.go:171","msg":"trace[20775502] linearizableReadLoop","detail":"{readStateIndex:5527; appliedIndex:5527; }","duration":"103.842089ms","start":"2026-09-09T01:19:31.262177Z","end":"2026-09-09T01:19:31.366019Z","steps":["trace[20775502] 'read index received' (duration: 103.835069ms)","trace[20775502] 'applied index is now lower than readState.Index' (duration: 5.42µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:31.366493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.302131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-09T01:19:31.366559Z","caller":"traceutil/trace.go:171","msg":"trace[1791368162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5323; }","duration":"104.405814ms","start":"2026-09-09T01:19:31.262137Z","end":"2026-09-09T01:19:31.366543Z","steps":["trace[1791368162] 'agreement among raft nodes before linearized reading' (duration: 104.190829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:31.366756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.295682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:19:31.366824Z","caller":"traceutil/trace.go:171","msg":"trace[448575152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5323; }","duration":"101.388464ms","start":"2026-09-09T01:19:31.265415Z","end":"2026-09-09T01:19:31.366804Z","steps":["trace[448575152] 'agreement among raft nodes before linearized reading' (duration: 101.22735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:33.595094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.808744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:19:33.595212Z","caller":"traceutil/trace.go:171","msg":"trace[573972512] linearizableReadLoop","detail":"{readStateIndex:5562; appliedIndex:5561; }","duration":"192.139968ms","start":"2026-09-09T01:19:33.403054Z","end":"2026-09-09T01:19:33.595194Z","steps":["trace[573972512] 'read index received' (duration: 81.117678ms)","trace[573972512] 'applied index is now lower than readState.Index' (duration: 111.020959ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:33.595302Z","caller":"traceutil/trace.go:171","msg":"trace[134715900] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"197.279325ms","start":"2026-09-09T01:19:33.397956Z","end":"2026-09-09T01:19:33.595235Z","steps":["trace[134715900] 'process raft request' (duration: 86.244414ms)","trace[134715900] 'compare' (duration: 110.655951ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:33.595772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.695852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:19:33.59592Z","caller":"traceutil/trace.go:171","msg":"trace[615797531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5357; }","duration":"192.865207ms","start":"2026-09-09T01:19:33.403031Z","end":"2026-09-09T01:19:33.595896Z","steps":["trace[615797531] 'agreement among raft nodes before linearized reading' (duration: 192.259841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:46.874296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.77341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:19:46.874469Z","caller":"traceutil/trace.go:171","msg":"trace[387899608] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"355.877684ms","start":"2026-09-09T01:19:46.518577Z","end":"2026-09-09T01:19:46.874455Z","steps":["trace[387899608] 'process raft request' (duration: 61.876028ms)","trace[387899608] 'compare' (duration: 293.669547ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:46.87453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:46.518541Z","time spent":"355.964976ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:46.875998Z","caller":"traceutil/trace.go:171","msg":"trace[51971423] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"356.701576ms","start":"2026-09-09T01:19:46.519264Z","end":"2026-09-09T01:19:46.875966Z","steps":["trace[51971423] 'process raft request' (duration: 356.367087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:46.876191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:46.519248Z","time spent":"356.831519ms","remote":"127.0.0.1:43622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:46.876303Z","caller":"traceutil/trace.go:171","msg":"trace[1416643426] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"356.936262ms","start":"2026-09-09T01:19:46.519341Z","end":"2026-09-09T01:19:46.876278Z","steps":["trace[1416643426] 'process raft request' (duration: 356.500141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:46.876416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:46.519319Z","time spent":"357.036325ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:46.876484Z","caller":"traceutil/trace.go:171","msg":"trace[1222336904] linearizableReadLoop","detail":"{readStateIndex:5814; appliedIndex:5811; }","duration":"292.494935ms","start":"2026-09-09T01:19:46.583943Z","end":"2026-09-09T01:19:46.876438Z","steps":["trace[1222336904] 'read index received' (duration: 291.693624ms)","trace[1222336904] 'applied index is now lower than readState.Index' (duration: 797.671µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:46.876562Z","caller":"traceutil/trace.go:171","msg":"trace[67227594] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"357.159688ms","start":"2026-09-09T01:19:46.519377Z","end":"2026-09-09T01:19:46.876537Z","steps":["trace[67227594] 'process raft request' (duration: 356.800498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:46.876605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.67733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:19:46.876891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:46.519364Z","time spent":"357.460366ms","remote":"127.0.0.1:43622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:46.876961Z","caller":"traceutil/trace.go:171","msg":"trace[1317085498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5602; }","duration":"293.063321ms","start":"2026-09-09T01:19:46.583881Z","end":"2026-09-09T01:19:46.876945Z","steps":["trace[1317085498] 'agreement among raft nodes before linearized reading' (duration: 292.66462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:46.89134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.517155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:19:46.891415Z","caller":"traceutil/trace.go:171","msg":"trace[1927389205] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5603; }","duration":"139.633318ms","start":"2026-09-09T01:19:46.751765Z","end":"2026-09-09T01:19:46.891398Z","steps":["trace[1927389205] 'agreement among raft nodes before linearized reading' (duration: 139.497854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:46.891428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.886313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:19:46.891447Z","caller":"traceutil/trace.go:171","msg":"trace[1241923518] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"100.349897ms","start":"2026-09-09T01:19:46.791063Z","end":"2026-09-09T01:19:46.891413Z","steps":["trace[1241923518] 'process raft request' (duration: 100.08983ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:46.891493Z","caller":"traceutil/trace.go:171","msg":"trace[737174228] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5603; }","duration":"108.992707ms","start":"2026-09-09T01:19:46.782478Z","end":"2026-09-09T01:19:46.891471Z","steps":["trace[737174228] 'agreement among raft nodes before linearized reading' (duration: 108.796881ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:47.189788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2914} {"level":"warn","ts":"2026-09-09T01:19:47.191047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.596516ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-09-09T01:19:47.191323Z","caller":"traceutil/trace.go:171","msg":"trace[1006563454] compact","detail":"{revision:2914; response_revision:5603; }","duration":"298.579596ms","start":"2026-09-09T01:19:46.892719Z","end":"2026-09-09T01:19:47.191299Z","steps":["trace[1006563454] 'process raft request' (duration: 63.120701ms)","trace[1006563454] 'check and update compact revision' (duration: 233.387881ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:47.191844Z","caller":"traceutil/trace.go:171","msg":"trace[1568430986] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"297.94284ms","start":"2026-09-09T01:19:46.893873Z","end":"2026-09-09T01:19:47.191816Z","steps":["trace[1568430986] 'process raft request' (duration: 297.391746ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:47.192198Z","caller":"traceutil/trace.go:171","msg":"trace[1054035212] transaction","detail":"{read_only:false; response_revision:5605; number_of_response:1; }","duration":"296.006448ms","start":"2026-09-09T01:19:46.896159Z","end":"2026-09-09T01:19:47.192166Z","steps":["trace[1054035212] 'process raft request' (duration: 295.607918ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:47.192445Z","caller":"traceutil/trace.go:171","msg":"trace[1142576113] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"288.800678ms","start":"2026-09-09T01:19:46.903618Z","end":"2026-09-09T01:19:47.192418Z","steps":["trace[1142576113] 'process raft request' (duration: 288.48687ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:47.228142Z","caller":"traceutil/trace.go:171","msg":"trace[408967394] linearizableReadLoop","detail":"{readStateIndex:5820; appliedIndex:5815; }","duration":"180.990323ms","start":"2026-09-09T01:19:47.047123Z","end":"2026-09-09T01:19:47.228113Z","steps":["trace[408967394] 'read index received' (duration: 140.734427ms)","trace[408967394] 'applied index is now lower than readState.Index' (duration: 40.254736ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:19:47.228181Z","caller":"traceutil/trace.go:171","msg":"trace[538550955] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"309.240599ms","start":"2026-09-09T01:19:46.9189Z","end":"2026-09-09T01:19:47.22814Z","steps":["trace[538550955] 'process raft request' (duration: 273.40215ms)","trace[538550955] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:532; } (duration: 35.593772ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:47.228296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.137867ms","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-09-09T01:19:47.228311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:19:46.91889Z","time spent":"309.341731ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:19:47.228341Z","caller":"traceutil/trace.go:171","msg":"trace[676356351] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5607; }","duration":"181.210969ms","start":"2026-09-09T01:19:47.047115Z","end":"2026-09-09T01:19:47.228326Z","steps":["trace[676356351] 'agreement among raft nodes before linearized reading' (duration: 181.101976ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:47.276424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2914,"took":"84.371814ms","hash":346363839,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T01:19:47.276493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":346363839,"revision":2914,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:19:54.951973Z","caller":"traceutil/trace.go:171","msg":"trace[522691190] linearizableReadLoop","detail":"{readStateIndex:6002; appliedIndex:6001; }","duration":"160.536531ms","start":"2026-09-09T01:19:54.791373Z","end":"2026-09-09T01:19:54.95191Z","steps":["trace[522691190] 'read index received' (duration: 160.196572ms)","trace[522691190] 'applied index is now lower than readState.Index' (duration: 338.449µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:19:54.952997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.61302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-09T01:19:54.953039Z","caller":"traceutil/trace.go:171","msg":"trace[1190322368] transaction","detail":"{read_only:false; response_revision:5785; number_of_response:1; }","duration":"226.253098ms","start":"2026-09-09T01:19:54.726737Z","end":"2026-09-09T01:19:54.952991Z","steps":["trace[1190322368] 'process raft request' (duration: 224.953333ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:54.953079Z","caller":"traceutil/trace.go:171","msg":"trace[391399284] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5785; }","duration":"161.755193ms","start":"2026-09-09T01:19:54.791304Z","end":"2026-09-09T01:19:54.953059Z","steps":["trace[391399284] 'agreement among raft nodes before linearized reading' (duration: 161.478036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:19:54.952999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.476518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5915ccd3-d1ed-4099-b506-c5c17975dea4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-09T01:19:54.953587Z","caller":"traceutil/trace.go:171","msg":"trace[1424883307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5915ccd3-d1ed-4099-b506-c5c17975dea4; range_end:; response_count:1; response_revision:5785; }","duration":"117.130315ms","start":"2026-09-09T01:19:54.836434Z","end":"2026-09-09T01:19:54.953564Z","steps":["trace[1424883307] 'agreement among raft nodes before linearized reading' (duration: 116.384865ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:47.199107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5603} {"level":"info","ts":"2026-09-09T01:24:47.290507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5603,"took":"89.655219ms","hash":2882854760,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":29290496,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-09T01:24:47.290584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2882854760,"revision":5603,"compact-revision":2914} {"level":"warn","ts":"2026-09-09T01:26:17.633937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.123332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:26:17.634029Z","caller":"traceutil/trace.go:171","msg":"trace[1867797078] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8678; }","duration":"141.274896ms","start":"2026-09-09T01:26:17.492734Z","end":"2026-09-09T01:26:17.634009Z","steps":["trace[1867797078] 'count revisions from in-memory index tree' (duration: 141.010829ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:18.354772Z","caller":"traceutil/trace.go:171","msg":"trace[266208114] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"233.765988ms","start":"2026-09-09T01:26:18.120987Z","end":"2026-09-09T01:26:18.354753Z","steps":["trace[266208114] 'read index received' (duration: 233.603124ms)","trace[266208114] 'applied index is now lower than readState.Index' (duration: 161.944µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:26:18.354873Z","caller":"traceutil/trace.go:171","msg":"trace[2143256343] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"282.360196ms","start":"2026-09-09T01:26:18.072462Z","end":"2026-09-09T01:26:18.354822Z","steps":["trace[2143256343] 'process raft request' (duration: 282.14439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:18.354921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.927692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:26:18.35504Z","caller":"traceutil/trace.go:171","msg":"trace[171265003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8681; }","duration":"234.107507ms","start":"2026-09-09T01:26:18.120924Z","end":"2026-09-09T01:26:18.355031Z","steps":["trace[171265003] 'agreement among raft nodes before linearized reading' (duration: 233.904702ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:19.198794Z","caller":"traceutil/trace.go:171","msg":"trace[1702336458] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"177.081954ms","start":"2026-09-09T01:26:19.021678Z","end":"2026-09-09T01:26:19.19876Z","steps":["trace[1702336458] 'process raft request' (duration: 102.454856ms)","trace[1702336458] 'compare' (duration: 74.469294ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:26:19.29308Z","caller":"traceutil/trace.go:171","msg":"trace[1852785740] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"235.324289ms","start":"2026-09-09T01:26:19.057724Z","end":"2026-09-09T01:26:19.293048Z","steps":["trace[1852785740] 'process raft request' (duration: 235.275708ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:19.293233Z","caller":"traceutil/trace.go:171","msg":"trace[379429117] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"235.570836ms","start":"2026-09-09T01:26:19.057595Z","end":"2026-09-09T01:26:19.293165Z","steps":["trace[379429117] 'process raft request' (duration: 235.223346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:19.587793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.62951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:26:19.587902Z","caller":"traceutil/trace.go:171","msg":"trace[1134437976] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8688; }","duration":"177.798434ms","start":"2026-09-09T01:26:19.410077Z","end":"2026-09-09T01:26:19.587876Z","steps":["trace[1134437976] 'count revisions from in-memory index tree' (duration: 177.525187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:19.970508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.292785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:26:19.970636Z","caller":"traceutil/trace.go:171","msg":"trace[350283519] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8690; }","duration":"147.550772ms","start":"2026-09-09T01:26:19.823057Z","end":"2026-09-09T01:26:19.970607Z","steps":["trace[350283519] 'range keys from in-memory index tree' (duration: 147.231893ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:48.877925Z","caller":"traceutil/trace.go:171","msg":"trace[871331687] linearizableReadLoop","detail":"{readStateIndex:9217; appliedIndex:9216; }","duration":"255.173275ms","start":"2026-09-09T01:26:48.622712Z","end":"2026-09-09T01:26:48.877885Z","steps":["trace[871331687] 'read index received' (duration: 253.724787ms)","trace[871331687] 'applied index is now lower than readState.Index' (duration: 1.446748ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:26:48.878219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.479293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-09T01:26:48.878251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.790252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:26:48.878314Z","caller":"traceutil/trace.go:171","msg":"trace[1439472015] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8897; }","duration":"232.921045ms","start":"2026-09-09T01:26:48.64538Z","end":"2026-09-09T01:26:48.878301Z","steps":["trace[1439472015] 'agreement among raft nodes before linearized reading' (duration: 232.788481ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:48.878024Z","caller":"traceutil/trace.go:171","msg":"trace[53846073] transaction","detail":"{read_only:false; response_revision:8897; number_of_response:1; }","duration":"267.930343ms","start":"2026-09-09T01:26:48.610046Z","end":"2026-09-09T01:26:48.877976Z","steps":["trace[53846073] 'process raft request' (duration: 266.409113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:48.878334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.516304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-09T01:26:48.878328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.911275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:26:48.87846Z","caller":"traceutil/trace.go:171","msg":"trace[1307007025] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8897; }","duration":"233.022908ms","start":"2026-09-09T01:26:48.645372Z","end":"2026-09-09T01:26:48.878395Z","steps":["trace[1307007025] 'agreement among raft nodes before linearized reading' (duration: 232.882175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:48.878341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.122605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjplr\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:26:48.87831Z","caller":"traceutil/trace.go:171","msg":"trace[736111501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8897; }","duration":"255.601817ms","start":"2026-09-09T01:26:48.622688Z","end":"2026-09-09T01:26:48.87829Z","steps":["trace[736111501] 'agreement among raft nodes before linearized reading' (duration: 255.298579ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:48.878406Z","caller":"traceutil/trace.go:171","msg":"trace[1351827030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8897; }","duration":"255.626987ms","start":"2026-09-09T01:26:48.62276Z","end":"2026-09-09T01:26:48.878387Z","steps":["trace[1351827030] 'agreement among raft nodes before linearized reading' (duration: 255.374891ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:48.878539Z","caller":"traceutil/trace.go:171","msg":"trace[1576129438] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjplr; range_end:; response_count:1; response_revision:8897; }","duration":"206.374721ms","start":"2026-09-09T01:26:48.672157Z","end":"2026-09-09T01:26:48.878532Z","steps":["trace[1576129438] 'agreement among raft nodes before linearized reading' (duration: 205.992411ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:50.90538Z","caller":"traceutil/trace.go:171","msg":"trace[1131355174] linearizableReadLoop","detail":"{readStateIndex:9228; appliedIndex:9227; }","duration":"202.409187ms","start":"2026-09-09T01:26:50.702948Z","end":"2026-09-09T01:26:50.905357Z","steps":["trace[1131355174] 'read index received' (duration: 202.221342ms)","trace[1131355174] 'applied index is now lower than readState.Index' (duration: 186.965µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:26:50.905459Z","caller":"traceutil/trace.go:171","msg":"trace[350786504] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"307.075831ms","start":"2026-09-09T01:26:50.598342Z","end":"2026-09-09T01:26:50.905417Z","steps":["trace[350786504] 'process raft request' (duration: 306.874766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:50.90564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:26:50.598301Z","time spent":"307.235596ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:26:50.90565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.660053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-09T01:26:50.905878Z","caller":"traceutil/trace.go:171","msg":"trace[1901271423] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8907; }","duration":"202.958431ms","start":"2026-09-09T01:26:50.702907Z","end":"2026-09-09T01:26:50.905866Z","steps":["trace[1901271423] 'agreement among raft nodes before linearized reading' (duration: 202.574221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:51.151019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.890668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:26:51.151193Z","caller":"traceutil/trace.go:171","msg":"trace[2070401314] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9228; }","duration":"240.367773ms","start":"2026-09-09T01:26:50.910794Z","end":"2026-09-09T01:26:51.151162Z","steps":["trace[2070401314] 'read index received' (duration: 137.195767ms)","trace[2070401314] 'applied index is now lower than readState.Index' (duration: 103.169566ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:26:51.151292Z","caller":"traceutil/trace.go:171","msg":"trace[835190062] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"240.659151ms","start":"2026-09-09T01:26:50.910619Z","end":"2026-09-09T01:26:51.151278Z","steps":["trace[835190062] 'process raft request' (duration: 137.351162ms)","trace[835190062] 'compare' (duration: 102.517418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:26:51.151613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.783684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-09T01:26:51.151719Z","caller":"traceutil/trace.go:171","msg":"trace[46095356] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8908; }","duration":"240.923998ms","start":"2026-09-09T01:26:50.910767Z","end":"2026-09-09T01:26:51.151691Z","steps":["trace[46095356] 'agreement among raft nodes before linearized reading' (duration: 240.578769ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:51.152097Z","caller":"traceutil/trace.go:171","msg":"trace[953787041] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"240.555298ms","start":"2026-09-09T01:26:50.911509Z","end":"2026-09-09T01:26:51.152065Z","steps":["trace[953787041] 'process raft request' (duration: 240.396984ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:51.152505Z","caller":"traceutil/trace.go:171","msg":"trace[1046810175] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"240.856446ms","start":"2026-09-09T01:26:50.911629Z","end":"2026-09-09T01:26:51.152486Z","steps":["trace[1046810175] 'process raft request' (duration: 240.391834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:51.152956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.045867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228543"} {"level":"info","ts":"2026-09-09T01:26:51.153013Z","caller":"traceutil/trace.go:171","msg":"trace[339885209] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8910; }","duration":"242.144239ms","start":"2026-09-09T01:26:50.910856Z","end":"2026-09-09T01:26:51.153Z","steps":["trace[339885209] 'agreement among raft nodes before linearized reading' (duration: 241.39076ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:57.307542Z","caller":"traceutil/trace.go:171","msg":"trace[2124814426] linearizableReadLoop","detail":"{readStateIndex:9263; appliedIndex:9262; }","duration":"112.941435ms","start":"2026-09-09T01:26:57.194564Z","end":"2026-09-09T01:26:57.307506Z","steps":["trace[2124814426] 'read index received' (duration: 112.584055ms)","trace[2124814426] 'applied index is now lower than readState.Index' (duration: 355.909µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:26:57.307812Z","caller":"traceutil/trace.go:171","msg":"trace[889461123] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"113.554591ms","start":"2026-09-09T01:26:57.194217Z","end":"2026-09-09T01:26:57.307771Z","steps":["trace[889461123] 'process raft request' (duration: 113.070558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:57.307973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.370936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-09T01:26:57.308008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.098809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T01:26:57.30807Z","caller":"traceutil/trace.go:171","msg":"trace[884260376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8941; }","duration":"113.498599ms","start":"2026-09-09T01:26:57.194548Z","end":"2026-09-09T01:26:57.308047Z","steps":["trace[884260376] 'agreement among raft nodes before linearized reading' (duration: 113.16947ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:57.308075Z","caller":"traceutil/trace.go:171","msg":"trace[308821820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8941; }","duration":"113.191441ms","start":"2026-09-09T01:26:57.194866Z","end":"2026-09-09T01:26:57.308057Z","steps":["trace[308821820] 'agreement among raft nodes before linearized reading' (duration: 112.949365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:26:57.308012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.434621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjplr\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:26:57.30824Z","caller":"traceutil/trace.go:171","msg":"trace[1086957946] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjplr; range_end:; response_count:1; response_revision:8941; }","duration":"109.729609ms","start":"2026-09-09T01:26:57.1985Z","end":"2026-09-09T01:26:57.308229Z","steps":["trace[1086957946] 'agreement among raft nodes before linearized reading' (duration: 109.331838ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:26:58.169709Z","caller":"traceutil/trace.go:171","msg":"trace[1864663920] transaction","detail":"{read_only:false; response_revision:8948; number_of_response:1; }","duration":"176.464119ms","start":"2026-09-09T01:26:57.993215Z","end":"2026-09-09T01:26:58.169679Z","steps":["trace[1864663920] 'process raft request' (duration: 124.389158ms)","trace[1864663920] 'compare' (duration: 51.528246ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:27:02.948743Z","caller":"traceutil/trace.go:171","msg":"trace[1824438675] linearizableReadLoop","detail":"{readStateIndex:9307; appliedIndex:9306; }","duration":"220.6395ms","start":"2026-09-09T01:27:02.728079Z","end":"2026-09-09T01:27:02.948718Z","steps":["trace[1824438675] 'read index received' (duration: 124.571343ms)","trace[1824438675] 'applied index is now lower than readState.Index' (duration: 96.067007ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:27:02.949022Z","caller":"traceutil/trace.go:171","msg":"trace[84184025] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8984; }","duration":"308.575062ms","start":"2026-09-09T01:27:02.640435Z","end":"2026-09-09T01:27:02.94901Z","steps":["trace[84184025] 'process raft request' (duration: 212.243058ms)","trace[84184025] 'compare' (duration: 95.847831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:27:02.949184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:27:02.640423Z","time spent":"308.636843ms","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-09-09T01:27:02.94946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.374469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2p6hr\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-09-09T01:27:02.949521Z","caller":"traceutil/trace.go:171","msg":"trace[1762219832] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2p6hr; range_end:; response_count:1; response_revision:8984; }","duration":"221.476622ms","start":"2026-09-09T01:27:02.728029Z","end":"2026-09-09T01:27:02.949506Z","steps":["trace[1762219832] 'agreement among raft nodes before linearized reading' (duration: 221.315548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:27:03.233525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.003701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:27:03.233672Z","caller":"traceutil/trace.go:171","msg":"trace[905745522] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"259.88319ms","start":"2026-09-09T01:27:02.973756Z","end":"2026-09-09T01:27:03.233639Z","steps":["trace[905745522] 'process raft request' (duration: 133.679344ms)","trace[905745522] 'compare' (duration: 125.903978ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:27:15.823565Z","caller":"traceutil/trace.go:171","msg":"trace[2086988221] transaction","detail":"{read_only:false; response_revision:9118; number_of_response:1; }","duration":"114.803824ms","start":"2026-09-09T01:27:15.708723Z","end":"2026-09-09T01:27:15.823526Z","steps":["trace[2086988221] 'process raft request' (duration: 114.552927ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:26.404305Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000254b.wal"} {"level":"info","ts":"2026-09-09T01:28:05.716203Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:28:05.72396Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T01:28:05.72406Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-09T01:28:14.500451Z","caller":"traceutil/trace.go:171","msg":"trace[388362177] linearizableReadLoop","detail":"{readStateIndex:10078; appliedIndex:10077; }","duration":"105.27972ms","start":"2026-09-09T01:28:14.395136Z","end":"2026-09-09T01:28:14.500416Z","steps":["trace[388362177] 'read index received' (duration: 105.102825ms)","trace[388362177] 'applied index is now lower than readState.Index' (duration: 175.645µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:28:14.500563Z","caller":"traceutil/trace.go:171","msg":"trace[7829578] transaction","detail":"{read_only:false; response_revision:9733; number_of_response:1; }","duration":"107.781015ms","start":"2026-09-09T01:28:14.39273Z","end":"2026-09-09T01:28:14.500512Z","steps":["trace[7829578] 'process raft request' (duration: 107.511458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:28:14.500749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.587717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:28:14.500837Z","caller":"traceutil/trace.go:171","msg":"trace[2065423045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9733; }","duration":"105.740891ms","start":"2026-09-09T01:28:14.395076Z","end":"2026-09-09T01:28:14.500817Z","steps":["trace[2065423045] 'agreement among raft nodes before linearized reading' (duration: 105.483235ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:29:47.206402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7972} {"level":"info","ts":"2026-09-09T01:29:47.291847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7972,"took":"83.304358ms","hash":825098018,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":24834048,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-09T01:29:47.291919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":825098018,"revision":7972,"compact-revision":5603} {"level":"info","ts":"2026-09-09T01:33:47.513576Z","caller":"traceutil/trace.go:171","msg":"trace[1214535316] transaction","detail":"{read_only:false; response_revision:12128; number_of_response:1; }","duration":"122.047292ms","start":"2026-09-09T01:33:47.391485Z","end":"2026-09-09T01:33:47.513533Z","steps":["trace[1214535316] 'process raft request' (duration: 121.777495ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:34:05.739056Z","caller":"traceutil/trace.go:171","msg":"trace[1950036883] transaction","detail":"{read_only:false; response_revision:12230; number_of_response:1; }","duration":"112.039757ms","start":"2026-09-09T01:34:05.626987Z","end":"2026-09-09T01:34:05.739027Z","steps":["trace[1950036883] 'process raft request' (duration: 70.148668ms)","trace[1950036883] 'compare' (duration: 41.629922ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:34:07.742916Z","caller":"traceutil/trace.go:171","msg":"trace[1424472578] transaction","detail":"{read_only:false; response_revision:12242; number_of_response:1; }","duration":"162.969816ms","start":"2026-09-09T01:34:07.579913Z","end":"2026-09-09T01:34:07.742883Z","steps":["trace[1424472578] 'process raft request' (duration: 162.306698ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:34:07.931656Z","caller":"traceutil/trace.go:171","msg":"trace[1673903143] linearizableReadLoop","detail":"{readStateIndex:12670; appliedIndex:12669; }","duration":"172.703174ms","start":"2026-09-09T01:34:07.758914Z","end":"2026-09-09T01:34:07.931617Z","steps":["trace[1673903143] 'read index received' (duration: 79.245688ms)","trace[1673903143] 'applied index is now lower than readState.Index' (duration: 93.455796ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:34:07.931878Z","caller":"traceutil/trace.go:171","msg":"trace[323709884] transaction","detail":"{read_only:false; response_revision:12243; number_of_response:1; }","duration":"183.341665ms","start":"2026-09-09T01:34:07.74849Z","end":"2026-09-09T01:34:07.931831Z","steps":["trace[323709884] 'process raft request' (duration: 89.704325ms)","trace[323709884] 'compare' (duration: 92.959732ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:34:07.932012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.075803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T01:34:07.932069Z","caller":"traceutil/trace.go:171","msg":"trace[1806782844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12243; }","duration":"173.182666ms","start":"2026-09-09T01:34:07.758867Z","end":"2026-09-09T01:34:07.93205Z","steps":["trace[1806782844] 'agreement among raft nodes before linearized reading' (duration: 172.95896ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:34:47.214352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10356} {"level":"info","ts":"2026-09-09T01:34:47.302307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10356,"took":"84.840077ms","hash":3223934687,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":25681920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T01:34:47.302377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3223934687,"revision":10356,"compact-revision":7972} {"level":"info","ts":"2026-09-09T01:39:47.221603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12532} {"level":"info","ts":"2026-09-09T01:39:47.311135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12532,"took":"87.151432ms","hash":1703974431,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":27258880,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T01:39:47.311199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1703974431,"revision":12532,"compact-revision":10356} {"level":"warn","ts":"2026-09-09T01:40:54.432327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.355942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:40:54.432404Z","caller":"traceutil/trace.go:171","msg":"trace[283291247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14924; }","duration":"175.460264ms","start":"2026-09-09T01:40:54.256926Z","end":"2026-09-09T01:40:54.432387Z","steps":["trace[283291247] 'range keys from in-memory index tree' (duration: 175.261269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.432483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.064644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjplr\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:40:54.432524Z","caller":"traceutil/trace.go:171","msg":"trace[1959518075] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjplr; range_end:; response_count:1; response_revision:14924; }","duration":"192.142146ms","start":"2026-09-09T01:40:54.24037Z","end":"2026-09-09T01:40:54.432513Z","steps":["trace[1959518075] 'range keys from in-memory index tree' (duration: 191.946651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.432916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.690256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:40:54.432956Z","caller":"traceutil/trace.go:171","msg":"trace[1542630101] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:14924; }","duration":"162.772469ms","start":"2026-09-09T01:40:54.270172Z","end":"2026-09-09T01:40:54.432944Z","steps":["trace[1542630101] 'count revisions from in-memory index tree' (duration: 162.619494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.433215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.514752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-09T01:40:54.433191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.866381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T01:40:54.433287Z","caller":"traceutil/trace.go:171","msg":"trace[1676144145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14924; }","duration":"163.179319ms","start":"2026-09-09T01:40:54.270096Z","end":"2026-09-09T01:40:54.433276Z","steps":["trace[1676144145] 'range keys from in-memory index tree' (duration: 162.375668ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:54.433298Z","caller":"traceutil/trace.go:171","msg":"trace[1521845522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14924; }","duration":"181.054702ms","start":"2026-09-09T01:40:54.252215Z","end":"2026-09-09T01:40:54.43327Z","steps":["trace[1521845522] 'range keys from in-memory index tree' (duration: 179.675085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:40:54.680639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.846499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:40:54.680698Z","caller":"traceutil/trace.go:171","msg":"trace[562404064] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:14927; }","duration":"100.958192ms","start":"2026-09-09T01:40:54.579726Z","end":"2026-09-09T01:40:54.680685Z","steps":["trace[562404064] 'count revisions from in-memory index tree' (duration: 100.721586ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:41:11.675347Z","caller":"traceutil/trace.go:171","msg":"trace[1075611456] transaction","detail":"{read_only:false; response_revision:15029; number_of_response:1; }","duration":"230.851761ms","start":"2026-09-09T01:41:11.444465Z","end":"2026-09-09T01:41:11.675317Z","steps":["trace[1075611456] 'process raft request' (duration: 230.571993ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:41:36.170126Z","caller":"traceutil/trace.go:171","msg":"trace[1342450737] linearizableReadLoop","detail":"{readStateIndex:15892; appliedIndex:15891; }","duration":"100.763767ms","start":"2026-09-09T01:41:36.069319Z","end":"2026-09-09T01:41:36.170083Z","steps":["trace[1342450737] 'read index received' (duration: 68.112443ms)","trace[1342450737] 'applied index is now lower than readState.Index' (duration: 32.650044ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:41:36.170147Z","caller":"traceutil/trace.go:171","msg":"trace[1975263454] transaction","detail":"{read_only:false; response_revision:15349; number_of_response:1; }","duration":"394.512883ms","start":"2026-09-09T01:41:35.775597Z","end":"2026-09-09T01:41:36.17011Z","steps":["trace[1975263454] 'process raft request' (duration: 361.773956ms)","trace[1975263454] 'compare' (duration: 32.359807ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:41:36.170342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:41:35.77557Z","time spent":"394.672997ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:41:36.170509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.164118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-09-09T01:41:36.170621Z","caller":"traceutil/trace.go:171","msg":"trace[194643148] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:15349; }","duration":"101.328403ms","start":"2026-09-09T01:41:36.069264Z","end":"2026-09-09T01:41:36.170592Z","steps":["trace[194643148] 'agreement among raft nodes before linearized reading' (duration: 100.956293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:29.306476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.774309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:42:29.30669Z","caller":"traceutil/trace.go:171","msg":"trace[376492600] transaction","detail":"{read_only:false; response_revision:15990; number_of_response:1; }","duration":"129.336099ms","start":"2026-09-09T01:42:29.177337Z","end":"2026-09-09T01:42:29.306673Z","steps":["trace[376492600] 'process raft request' (duration: 129.249757ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:42:29.306892Z","caller":"traceutil/trace.go:171","msg":"trace[1203353622] transaction","detail":"{read_only:false; response_revision:15989; number_of_response:1; }","duration":"143.397501ms","start":"2026-09-09T01:42:29.163469Z","end":"2026-09-09T01:42:29.306867Z","steps":["trace[1203353622] 'process raft request' (duration: 21.100808ms)","trace[1203353622] 'compare' (duration: 121.539273ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:42:29.306917Z","caller":"traceutil/trace.go:171","msg":"trace[406858600] linearizableReadLoop","detail":"{readStateIndex:16547; appliedIndex:16546; }","duration":"142.887997ms","start":"2026-09-09T01:42:29.164017Z","end":"2026-09-09T01:42:29.306905Z","steps":["trace[406858600] 'read index received' (duration: 20.570454ms)","trace[406858600] 'applied index is now lower than readState.Index' (duration: 122.316623ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:42:29.307055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.034821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-09T01:42:29.307094Z","caller":"traceutil/trace.go:171","msg":"trace[1638829808] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15990; }","duration":"143.113693ms","start":"2026-09-09T01:42:29.163972Z","end":"2026-09-09T01:42:29.307085Z","steps":["trace[1638829808] 'agreement among raft nodes before linearized reading' (duration: 142.966949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:29.307359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.471705ms","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-09-09T01:42:29.307412Z","caller":"traceutil/trace.go:171","msg":"trace[856607977] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15990; }","duration":"106.528486ms","start":"2026-09-09T01:42:29.200871Z","end":"2026-09-09T01:42:29.3074Z","steps":["trace[856607977] 'agreement among raft nodes before linearized reading' (duration: 106.391303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:29.307428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.76024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:42:29.307731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.686005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:42:29.307714Z","caller":"traceutil/trace.go:171","msg":"trace[1057167508] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15990; }","duration":"116.098169ms","start":"2026-09-09T01:42:29.19159Z","end":"2026-09-09T01:42:29.307688Z","steps":["trace[1057167508] 'agreement among raft nodes before linearized reading' (duration: 115.808231ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:42:29.307815Z","caller":"traceutil/trace.go:171","msg":"trace[99467687] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15990; }","duration":"116.817228ms","start":"2026-09-09T01:42:29.190988Z","end":"2026-09-09T01:42:29.307805Z","steps":["trace[99467687] 'agreement among raft nodes before linearized reading' (duration: 116.717305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:29.307963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.427738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:42:29.307995Z","caller":"traceutil/trace.go:171","msg":"trace[147467496] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15990; }","duration":"116.460359ms","start":"2026-09-09T01:42:29.191527Z","end":"2026-09-09T01:42:29.307987Z","steps":["trace[147467496] 'agreement among raft nodes before linearized reading' (duration: 116.412598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:29.307336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.043792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:42:29.308078Z","caller":"traceutil/trace.go:171","msg":"trace[1174712312] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15990; }","duration":"143.846133ms","start":"2026-09-09T01:42:29.164224Z","end":"2026-09-09T01:42:29.30807Z","steps":["trace[1174712312] 'agreement among raft nodes before linearized reading' (duration: 143.000241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:42:29.308252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.122541ms","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-09-09T01:42:29.308311Z","caller":"traceutil/trace.go:171","msg":"trace[1972171264] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15990; }","duration":"138.247284ms","start":"2026-09-09T01:42:29.170048Z","end":"2026-09-09T01:42:29.308295Z","steps":["trace[1972171264] 'agreement among raft nodes before linearized reading' (duration: 137.956126ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:43:03.542656Z","caller":"traceutil/trace.go:171","msg":"trace[79150516] transaction","detail":"{read_only:false; response_revision:16166; number_of_response:1; }","duration":"207.321391ms","start":"2026-09-09T01:43:03.335307Z","end":"2026-09-09T01:43:03.542629Z","steps":["trace[79150516] 'process raft request' (duration: 207.161606ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:43:03.811146Z","caller":"traceutil/trace.go:171","msg":"trace[1695216722] transaction","detail":"{read_only:false; response_revision:16167; number_of_response:1; }","duration":"475.009525ms","start":"2026-09-09T01:43:03.336113Z","end":"2026-09-09T01:43:03.811123Z","steps":["trace[1695216722] 'process raft request' (duration: 474.833181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.811368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.336092Z","time spent":"475.121099ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:43:03.811513Z","caller":"traceutil/trace.go:171","msg":"trace[1176641511] linearizableReadLoop","detail":"{readStateIndex:16732; appliedIndex:16731; }","duration":"402.825058ms","start":"2026-09-09T01:43:03.408665Z","end":"2026-09-09T01:43:03.81149Z","steps":["trace[1176641511] 'read index received' (duration: 134.060474ms)","trace[1176641511] 'applied index is now lower than readState.Index' (duration: 268.763394ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:43:03.811612Z","caller":"traceutil/trace.go:171","msg":"trace[1633184665] transaction","detail":"{read_only:false; response_revision:16168; number_of_response:1; }","duration":"369.099897ms","start":"2026-09-09T01:43:03.442483Z","end":"2026-09-09T01:43:03.811583Z","steps":["trace[1633184665] 'process raft request' (duration: 368.595583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.811794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.034253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:43:03.811864Z","caller":"traceutil/trace.go:171","msg":"trace[1059740885] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16169; }","duration":"403.121425ms","start":"2026-09-09T01:43:03.40872Z","end":"2026-09-09T01:43:03.811842Z","steps":["trace[1059740885] 'agreement among raft nodes before linearized reading' (duration: 403.022063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.811847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.442461Z","time spent":"369.25829ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:43:03.811871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.15163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:43:03.811915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.408714Z","time spent":"403.189567ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-09-09T01:43:03.811961Z","caller":"traceutil/trace.go:171","msg":"trace[1618873102] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16169; }","duration":"251.268913ms","start":"2026-09-09T01:43:03.560657Z","end":"2026-09-09T01:43:03.811926Z","steps":["trace[1618873102] 'agreement among raft nodes before linearized reading' (duration: 251.134769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.829242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:43:03.812096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.430074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:43:03.812109Z","caller":"traceutil/trace.go:171","msg":"trace[1740572100] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16169; }","duration":"401.902123ms","start":"2026-09-09T01:43:03.410192Z","end":"2026-09-09T01:43:03.812094Z","steps":["trace[1740572100] 'agreement among raft nodes before linearized reading' (duration: 401.814781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.410183Z","time spent":"401.962305ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-09-09T01:43:03.812174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.404165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-09T01:43:03.812244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.382082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:43:03.812269Z","caller":"traceutil/trace.go:171","msg":"trace[1893075639] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16169; }","duration":"403.476904ms","start":"2026-09-09T01:43:03.408784Z","end":"2026-09-09T01:43:03.812261Z","steps":["trace[1893075639] 'agreement among raft nodes before linearized reading' (duration: 403.443474ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:43:03.812278Z","caller":"traceutil/trace.go:171","msg":"trace[1104773531] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16169; }","duration":"366.540019ms","start":"2026-09-09T01:43:03.445699Z","end":"2026-09-09T01:43:03.812239Z","steps":["trace[1104773531] 'agreement among raft nodes before linearized reading' (duration: 366.226101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.408758Z","time spent":"403.534636ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-09-09T01:43:03.81232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.445685Z","time spent":"366.622611ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-09T01:43:03.812443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.555033ms","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-09-09T01:43:03.812498Z","caller":"traceutil/trace.go:171","msg":"trace[1643485661] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16169; }","duration":"379.607854ms","start":"2026-09-09T01:43:03.432873Z","end":"2026-09-09T01:43:03.812481Z","steps":["trace[1643485661] 'agreement among raft nodes before linearized reading' (duration: 379.45212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.895859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:43:03.812539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.432762Z","time spent":"379.767969ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-09-09T01:43:03.812556Z","caller":"traceutil/trace.go:171","msg":"trace[146199119] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16169; }","duration":"251.94533ms","start":"2026-09-09T01:43:03.560602Z","end":"2026-09-09T01:43:03.812548Z","steps":["trace[146199119] 'agreement among raft nodes before linearized reading' (duration: 251.901059ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:43:03.812121Z","caller":"traceutil/trace.go:171","msg":"trace[2025480701] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16169; }","duration":"403.489766ms","start":"2026-09-09T01:43:03.408623Z","end":"2026-09-09T01:43:03.812113Z","steps":["trace[2025480701] 'agreement among raft nodes before linearized reading' (duration: 403.434394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.408607Z","time spent":"404.03623ms","remote":"127.0.0.1:43700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-09-09T01:43:03.812678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.313792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:43:03.812699Z","caller":"traceutil/trace.go:171","msg":"trace[1633800786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16169; }","duration":"293.335103ms","start":"2026-09-09T01:43:03.519357Z","end":"2026-09-09T01:43:03.812692Z","steps":["trace[1633800786] 'agreement among raft nodes before linearized reading' (duration: 293.267831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.91671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-09T01:43:03.812778Z","caller":"traceutil/trace.go:171","msg":"trace[1281863687] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16169; }","duration":"403.497306ms","start":"2026-09-09T01:43:03.409265Z","end":"2026-09-09T01:43:03.812762Z","steps":["trace[1281863687] 'agreement among raft nodes before linearized reading' (duration: 402.847449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.812861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.469382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:43:03.812882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.409256Z","time spent":"403.608228ms","remote":"127.0.0.1:43622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-09-09T01:43:03.812905Z","caller":"traceutil/trace.go:171","msg":"trace[1060990628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16169; }","duration":"230.553194ms","start":"2026-09-09T01:43:03.582341Z","end":"2026-09-09T01:43:03.812894Z","steps":["trace[1060990628] 'agreement among raft nodes before linearized reading' (duration: 230.494223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.813076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.602838ms","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-09-09T01:43:03.813108Z","caller":"traceutil/trace.go:171","msg":"trace[200623328] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16169; }","duration":"249.65497ms","start":"2026-09-09T01:43:03.563445Z","end":"2026-09-09T01:43:03.8131Z","steps":["trace[200623328] 'agreement among raft nodes before linearized reading' (duration: 249.578288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.813356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.198823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-09T01:43:03.813437Z","caller":"traceutil/trace.go:171","msg":"trace[1903098341] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16169; }","duration":"256.831319ms","start":"2026-09-09T01:43:03.556589Z","end":"2026-09-09T01:43:03.81342Z","steps":["trace[1903098341] 'agreement among raft nodes before linearized reading' (duration: 255.913785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.813744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.173753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:43:03.813771Z","caller":"traceutil/trace.go:171","msg":"trace[448682676] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16169; }","duration":"253.229324ms","start":"2026-09-09T01:43:03.560534Z","end":"2026-09-09T01:43:03.813764Z","steps":["trace[448682676] 'agreement among raft nodes before linearized reading' (duration: 253.166493ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:43:03.811634Z","caller":"traceutil/trace.go:171","msg":"trace[1352257648] transaction","detail":"{read_only:false; response_revision:16169; number_of_response:1; }","duration":"361.132596ms","start":"2026-09-09T01:43:03.45049Z","end":"2026-09-09T01:43:03.811623Z","steps":["trace[1352257648] 'process raft request' (duration: 360.869629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:03.815453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:43:03.450474Z","time spent":"364.919275ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:43:04.048164Z","caller":"traceutil/trace.go:171","msg":"trace[939185285] linearizableReadLoop","detail":"{readStateIndex:16736; appliedIndex:16734; }","duration":"227.292288ms","start":"2026-09-09T01:43:03.820849Z","end":"2026-09-09T01:43:04.048142Z","steps":["trace[939185285] 'read index received' (duration: 189.338665ms)","trace[939185285] 'applied index is now lower than readState.Index' (duration: 37.952843ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:43:04.048375Z","caller":"traceutil/trace.go:171","msg":"trace[605211165] transaction","detail":"{read_only:false; response_revision:16170; number_of_response:1; }","duration":"230.094933ms","start":"2026-09-09T01:43:03.818256Z","end":"2026-09-09T01:43:04.048351Z","steps":["trace[605211165] 'process raft request' (duration: 191.917093ms)","trace[605211165] 'compare' (duration: 37.502542ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:43:04.048404Z","caller":"traceutil/trace.go:171","msg":"trace[244179645] transaction","detail":"{read_only:false; response_revision:16171; number_of_response:1; }","duration":"228.469059ms","start":"2026-09-09T01:43:03.819906Z","end":"2026-09-09T01:43:04.048375Z","steps":["trace[244179645] 'process raft request' (duration: 228.150091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:43:04.049249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.390458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737341"} {"level":"info","ts":"2026-09-09T01:43:04.0493Z","caller":"traceutil/trace.go:171","msg":"trace[308138502] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16171; }","duration":"228.454289ms","start":"2026-09-09T01:43:03.820834Z","end":"2026-09-09T01:43:04.049289Z","steps":["trace[308138502] 'agreement among raft nodes before linearized reading' (duration: 227.391001ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:47.228911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14534} {"level":"info","ts":"2026-09-09T01:44:47.313077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14534,"took":"81.499063ms","hash":1034617031,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":31035392,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T01:44:47.313144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1034617031,"revision":14534,"compact-revision":12532} {"level":"warn","ts":"2026-09-09T01:48:51.580502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.244882ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:48:51.58064Z","caller":"traceutil/trace.go:171","msg":"trace[1970772358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18724; }","duration":"113.395966ms","start":"2026-09-09T01:48:51.467224Z","end":"2026-09-09T01:48:51.58062Z","steps":["trace[1970772358] 'range keys from in-memory index tree' (duration: 113.226401ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:51.583154Z","caller":"traceutil/trace.go:171","msg":"trace[955722897] linearizableReadLoop","detail":"{readStateIndex:19374; appliedIndex:19373; }","duration":"236.460607ms","start":"2026-09-09T01:48:51.346678Z","end":"2026-09-09T01:48:51.583138Z","steps":["trace[955722897] 'read index received' (duration: 236.298683ms)","trace[955722897] 'applied index is now lower than readState.Index' (duration: 161.224µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:48:51.583246Z","caller":"traceutil/trace.go:171","msg":"trace[381129695] transaction","detail":"{read_only:false; response_revision:18725; number_of_response:1; }","duration":"261.393215ms","start":"2026-09-09T01:48:51.321826Z","end":"2026-09-09T01:48:51.583219Z","steps":["trace[381129695] 'process raft request' (duration: 261.172469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:48:51.583289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.604071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:48:51.583452Z","caller":"traceutil/trace.go:171","msg":"trace[1740455449] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:18725; }","duration":"236.787006ms","start":"2026-09-09T01:48:51.346634Z","end":"2026-09-09T01:48:51.583421Z","steps":["trace[1740455449] 'agreement among raft nodes before linearized reading' (duration: 236.601901ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:58.572985Z","caller":"traceutil/trace.go:171","msg":"trace[229300425] linearizableReadLoop","detail":"{readStateIndex:19434; appliedIndex:19433; }","duration":"142.987048ms","start":"2026-09-09T01:48:58.429974Z","end":"2026-09-09T01:48:58.572961Z","steps":["trace[229300425] 'read index received' (duration: 142.714821ms)","trace[229300425] 'applied index is now lower than readState.Index' (duration: 271.337µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:48:58.573274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.280216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-09T01:48:58.573373Z","caller":"traceutil/trace.go:171","msg":"trace[535397160] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18784; }","duration":"143.418119ms","start":"2026-09-09T01:48:58.429934Z","end":"2026-09-09T01:48:58.573353Z","steps":["trace[535397160] 'agreement among raft nodes before linearized reading' (duration: 143.121332ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:58.573432Z","caller":"traceutil/trace.go:171","msg":"trace[912207940] transaction","detail":"{read_only:false; response_revision:18784; number_of_response:1; }","duration":"230.216063ms","start":"2026-09-09T01:48:58.343171Z","end":"2026-09-09T01:48:58.573387Z","steps":["trace[912207940] 'process raft request' (duration: 229.594867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:48:59.005959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.157627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:48:59.006044Z","caller":"traceutil/trace.go:171","msg":"trace[1753365462] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:18785; }","duration":"139.938437ms","start":"2026-09-09T01:48:58.866085Z","end":"2026-09-09T01:48:59.006024Z","steps":["trace[1753365462] 'count revisions from in-memory index tree' (duration: 139.017093ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:59.412294Z","caller":"traceutil/trace.go:171","msg":"trace[64745632] linearizableReadLoop","detail":"{readStateIndex:19436; appliedIndex:19435; }","duration":"190.181656ms","start":"2026-09-09T01:48:59.222087Z","end":"2026-09-09T01:48:59.412268Z","steps":["trace[64745632] 'read index received' (duration: 189.898608ms)","trace[64745632] 'applied index is now lower than readState.Index' (duration: 281.918µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:48:59.412444Z","caller":"traceutil/trace.go:171","msg":"trace[660920321] transaction","detail":"{read_only:false; response_revision:18786; number_of_response:1; }","duration":"281.492578ms","start":"2026-09-09T01:48:59.130915Z","end":"2026-09-09T01:48:59.412408Z","steps":["trace[660920321] 'process raft request' (duration: 281.084737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:48:59.412676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.559625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-09T01:48:59.412903Z","caller":"traceutil/trace.go:171","msg":"trace[1486296814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18786; }","duration":"190.75293ms","start":"2026-09-09T01:48:59.222045Z","end":"2026-09-09T01:48:59.412798Z","steps":["trace[1486296814] 'agreement among raft nodes before linearized reading' (duration: 190.411982ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:59.659099Z","caller":"traceutil/trace.go:171","msg":"trace[1552447620] linearizableReadLoop","detail":"{readStateIndex:19437; appliedIndex:19436; }","duration":"246.667658ms","start":"2026-09-09T01:48:59.412402Z","end":"2026-09-09T01:48:59.65907Z","steps":["trace[1552447620] 'read index received' (duration: 245.729313ms)","trace[1552447620] 'applied index is now lower than readState.Index' (duration: 937.475µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:48:59.659182Z","caller":"traceutil/trace.go:171","msg":"trace[1403429709] transaction","detail":"{read_only:false; response_revision:18787; number_of_response:1; }","duration":"407.19621ms","start":"2026-09-09T01:48:59.251956Z","end":"2026-09-09T01:48:59.659152Z","steps":["trace[1403429709] 'process raft request' (duration: 406.189194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:48:59.659378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.893724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-z4vcz\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-09-09T01:48:59.659436Z","caller":"traceutil/trace.go:171","msg":"trace[300395440] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-z4vcz; range_end:; response_count:1; response_revision:18787; }","duration":"286.007197ms","start":"2026-09-09T01:48:59.373413Z","end":"2026-09-09T01:48:59.659421Z","steps":["trace[300395440] 'agreement among raft nodes before linearized reading' (duration: 285.790101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:48:59.659643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:48:59.251933Z","time spent":"407.458066ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:48:59.682509Z","caller":"traceutil/trace.go:171","msg":"trace[617502590] transaction","detail":"{read_only:false; response_revision:18788; number_of_response:1; }","duration":"264.316155ms","start":"2026-09-09T01:48:59.41817Z","end":"2026-09-09T01:48:59.682486Z","steps":["trace[617502590] 'process raft request' (duration: 264.106809ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:49:41.173447Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:49:41.177699Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T01:49:41.177797Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T01:49:47.239844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16830} {"level":"info","ts":"2026-09-09T01:49:47.342233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16830,"took":"99.435867ms","hash":1518777913,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":33136640,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-09T01:49:47.34231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1518777913,"revision":16830,"compact-revision":14534} {"level":"info","ts":"2026-09-09T01:54:43.717348Z","caller":"traceutil/trace.go:171","msg":"trace[298277876] linearizableReadLoop","detail":"{readStateIndex:22198; appliedIndex:22197; }","duration":"256.455284ms","start":"2026-09-09T01:54:43.460869Z","end":"2026-09-09T01:54:43.717324Z","steps":["trace[298277876] 'read index received' (duration: 256.238448ms)","trace[298277876] 'applied index is now lower than readState.Index' (duration: 215.996µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:54:43.717422Z","caller":"traceutil/trace.go:171","msg":"trace[1669264491] transaction","detail":"{read_only:false; response_revision:21462; number_of_response:1; }","duration":"336.888088ms","start":"2026-09-09T01:54:43.380496Z","end":"2026-09-09T01:54:43.717384Z","steps":["trace[1669264491] 'process raft request' (duration: 336.679213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:43.717501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.618059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:54:43.717542Z","caller":"traceutil/trace.go:171","msg":"trace[486653322] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:21462; }","duration":"256.770012ms","start":"2026-09-09T01:54:43.46076Z","end":"2026-09-09T01:54:43.71753Z","steps":["trace[486653322] 'agreement among raft nodes before linearized reading' (duration: 256.68462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:43.717572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:54:43.380475Z","time spent":"337.019742ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:54:43.981354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.007586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:54:43.981581Z","caller":"traceutil/trace.go:171","msg":"trace[752875690] transaction","detail":"{read_only:false; response_revision:21463; number_of_response:1; }","duration":"259.171796ms","start":"2026-09-09T01:54:43.72238Z","end":"2026-09-09T01:54:43.981552Z","steps":["trace[752875690] 'process raft request' (duration: 84.880872ms)","trace[752875690] 'compare' (duration: 173.896053ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:54:43.981755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.202298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:54:43.981612Z","caller":"traceutil/trace.go:171","msg":"trace[2117783425] linearizableReadLoop","detail":"{readStateIndex:22202; appliedIndex:22198; }","duration":"136.042633ms","start":"2026-09-09T01:54:43.845558Z","end":"2026-09-09T01:54:43.9816Z","steps":["trace[2117783425] 'read index received' (duration: 77.333732ms)","trace[2117783425] 'applied index is now lower than readState.Index' (duration: 58.707931ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:54:43.981805Z","caller":"traceutil/trace.go:171","msg":"trace[189434130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21466; }","duration":"136.28155ms","start":"2026-09-09T01:54:43.845506Z","end":"2026-09-09T01:54:43.981788Z","steps":["trace[189434130] 'agreement among raft nodes before linearized reading' (duration: 136.127426ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:43.98182Z","caller":"traceutil/trace.go:171","msg":"trace[538532909] transaction","detail":"{read_only:false; response_revision:21464; number_of_response:1; }","duration":"259.315119ms","start":"2026-09-09T01:54:43.722442Z","end":"2026-09-09T01:54:43.981757Z","steps":["trace[538532909] 'process raft request' (duration: 259.013441ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:43.98194Z","caller":"traceutil/trace.go:171","msg":"trace[1017895817] transaction","detail":"{read_only:false; response_revision:21465; number_of_response:1; }","duration":"258.768265ms","start":"2026-09-09T01:54:43.723132Z","end":"2026-09-09T01:54:43.9819Z","steps":["trace[1017895817] 'process raft request' (duration: 258.402435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:43.981881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.752743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:54:43.982097Z","caller":"traceutil/trace.go:171","msg":"trace[1698723589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:21466; }","duration":"120.01392ms","start":"2026-09-09T01:54:43.862073Z","end":"2026-09-09T01:54:43.982087Z","steps":["trace[1698723589] 'agreement among raft nodes before linearized reading' (duration: 119.769074ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:43.981946Z","caller":"traceutil/trace.go:171","msg":"trace[622679908] transaction","detail":"{read_only:false; response_revision:21466; number_of_response:1; }","duration":"258.513318ms","start":"2026-09-09T01:54:43.723389Z","end":"2026-09-09T01:54:43.981902Z","steps":["trace[622679908] 'process raft request' (duration: 258.179439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:44.305178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.774634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:54:44.305263Z","caller":"traceutil/trace.go:171","msg":"trace[130366841] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:21467; }","duration":"208.947609ms","start":"2026-09-09T01:54:44.096297Z","end":"2026-09-09T01:54:44.305244Z","steps":["trace[130366841] 'count revisions from in-memory index tree' (duration: 208.680863ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:44.813206Z","caller":"traceutil/trace.go:171","msg":"trace[1854922809] transaction","detail":"{read_only:false; response_revision:21468; number_of_response:1; }","duration":"214.400753ms","start":"2026-09-09T01:54:44.598779Z","end":"2026-09-09T01:54:44.81318Z","steps":["trace[1854922809] 'process raft request' (duration: 214.25712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:45.156092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.963468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:54:45.156221Z","caller":"traceutil/trace.go:171","msg":"trace[1730678021] transaction","detail":"{read_only:false; response_revision:21469; number_of_response:1; }","duration":"143.368076ms","start":"2026-09-09T01:54:45.012831Z","end":"2026-09-09T01:54:45.156199Z","steps":["trace[1730678021] 'process raft request' (duration: 37.202962ms)","trace[1730678021] 'compare' (duration: 105.755383ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:54:45.276642Z","caller":"traceutil/trace.go:171","msg":"trace[885632180] transaction","detail":"{read_only:false; response_revision:21470; number_of_response:1; }","duration":"192.026262ms","start":"2026-09-09T01:54:45.084592Z","end":"2026-09-09T01:54:45.276619Z","steps":["trace[885632180] 'process raft request' (duration: 189.962677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:54:45.652242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.109644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:54:45.652405Z","caller":"traceutil/trace.go:171","msg":"trace[1875060621] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:21470; }","duration":"245.32604ms","start":"2026-09-09T01:54:45.407058Z","end":"2026-09-09T01:54:45.652384Z","steps":["trace[1875060621] 'count revisions from in-memory index tree' (duration: 245.023312ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:45.706043Z","caller":"traceutil/trace.go:171","msg":"trace[195606343] linearizableReadLoop","detail":"{readStateIndex:22207; appliedIndex:22206; }","duration":"123.872242ms","start":"2026-09-09T01:54:45.582145Z","end":"2026-09-09T01:54:45.706017Z","steps":["trace[195606343] 'read index received' (duration: 72.128736ms)","trace[195606343] 'applied index is now lower than readState.Index' (duration: 51.742136ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:54:45.706257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.100198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:54:45.706324Z","caller":"traceutil/trace.go:171","msg":"trace[257661290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21470; }","duration":"124.204441ms","start":"2026-09-09T01:54:45.582106Z","end":"2026-09-09T01:54:45.70631Z","steps":["trace[257661290] 'agreement among raft nodes before linearized reading' (duration: 124.084958ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:47.249897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19376} {"level":"info","ts":"2026-09-09T01:54:47.381278Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19376,"took":"126.724457ms","hash":1183633671,"current-db-size-bytes":55422976,"current-db-size":"55 MB","current-db-size-in-use-bytes":29347840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-09T01:54:47.381355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1183633671,"revision":19376,"compact-revision":16830} {"level":"info","ts":"2026-09-09T01:58:41.148917Z","caller":"traceutil/trace.go:171","msg":"trace[897954563] linearizableReadLoop","detail":"{readStateIndex:24746; appliedIndex:24745; }","duration":"155.452887ms","start":"2026-09-09T01:58:40.99344Z","end":"2026-09-09T01:58:41.148893Z","steps":["trace[897954563] 'read index received' (duration: 155.118348ms)","trace[897954563] 'applied index is now lower than readState.Index' (duration: 333.229µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:58:41.149037Z","caller":"traceutil/trace.go:171","msg":"trace[357507979] transaction","detail":"{read_only:false; response_revision:23936; number_of_response:1; }","duration":"208.362485ms","start":"2026-09-09T01:58:40.940634Z","end":"2026-09-09T01:58:41.148996Z","steps":["trace[357507979] 'process raft request' (duration: 208.046437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:58:41.149226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.027659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-09-09T01:58:41.149281Z","caller":"traceutil/trace.go:171","msg":"trace[833194874] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:23936; }","duration":"115.135562ms","start":"2026-09-09T01:58:41.03413Z","end":"2026-09-09T01:58:41.149265Z","steps":["trace[833194874] 'agreement among raft nodes before linearized reading' (duration: 114.951477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:58:41.149394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.955641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-09-09T01:58:41.149452Z","caller":"traceutil/trace.go:171","msg":"trace[2017616478] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:23936; }","duration":"156.050434ms","start":"2026-09-09T01:58:40.993386Z","end":"2026-09-09T01:58:41.149437Z","steps":["trace[2017616478] 'agreement among raft nodes before linearized reading' (duration: 155.647853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:58:41.149454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.602694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-09-09T01:58:41.149535Z","caller":"traceutil/trace.go:171","msg":"trace[364291842] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:23936; }","duration":"107.775848ms","start":"2026-09-09T01:58:41.04174Z","end":"2026-09-09T01:58:41.149516Z","steps":["trace[364291842] 'agreement among raft nodes before linearized reading' (duration: 107.46915ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:59:47.256084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21481} {"level":"info","ts":"2026-09-09T01:59:47.369952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21481,"took":"110.264154ms","hash":2804367665,"current-db-size-bytes":55844864,"current-db-size":"56 MB","current-db-size-in-use-bytes":41947136,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T01:59:47.370021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2804367665,"revision":21481,"compact-revision":19376} {"level":"info","ts":"2026-09-09T02:04:47.263874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24575} {"level":"info","ts":"2026-09-09T02:04:47.42684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24575,"took":"158.232814ms","hash":1446997333,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T02:04:47.426918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1446997333,"revision":24575,"compact-revision":21481} {"level":"info","ts":"2026-09-09T02:07:32.766325Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000007234.wal"}