{"level":"warn","ts":"2026-10-16T00:09:29.391771Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:09:29.391876Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.14: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.14:2380","--initial-cluster=instance=https://162.253.55.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.14:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-16T00:09:29.391978Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:09:29.391991Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-10-16T00:09:29.392017Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T00:09:29.39269Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"]} {"level":"info","ts":"2026-10-16T00:09:29.392776Z","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.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.14:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-16T00:09:29.398018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.744675ms"} {"level":"info","ts":"2026-10-16T00:09:29.403649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5db0a1b394e67b72","cluster-id":"cfda6ccfe21bda40"} {"level":"info","ts":"2026-10-16T00:09:29.403721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:09:29.403743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 0"} {"level":"info","ts":"2026-10-16T00:09:29.403755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5db0a1b394e67b72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:09:29.403766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 1"} {"level":"info","ts":"2026-10-16T00:09:29.403808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"warn","ts":"2026-10-16T00:09:29.409981Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:09:29.41225Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:09:29.413704Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-16T00:09:29.416337Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5db0a1b394e67b72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:09:29.416562Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5db0a1b394e67b72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:09:29.41668Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T00:09:29.416922Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T00:09:29.416943Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T00:09:29.418924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"info","ts":"2026-10-16T00:09:29.419104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","added-peer-id":"5db0a1b394e67b72","added-peer-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-10-16T00:09:29.421714Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T00:09:29.421833Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-10-16T00:09:29.421863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-10-16T00:09:29.422102Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5db0a1b394e67b72","initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:09:29.422155Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:09:29.704993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:09:29.705075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:09:29.70511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgPreVoteResp from 5db0a1b394e67b72 at term 1"} {"level":"info","ts":"2026-10-16T00:09:29.70513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:09:29.70514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgVoteResp from 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-10-16T00:09:29.705155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became leader at term 2"} {"level":"info","ts":"2026-10-16T00:09:29.705172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5db0a1b394e67b72 elected leader 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-10-16T00:09:29.713148Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:29.71829Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5db0a1b394e67b72","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.14:2379]}","request-path":"/0/members/5db0a1b394e67b72/attributes","cluster-id":"cfda6ccfe21bda40","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:09:29.718446Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:09:29.718531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:09:29.721627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.14:2379"} {"level":"info","ts":"2026-10-16T00:09:29.721822Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:09:29.721925Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:09:29.722676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:09:29.725993Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:29.726125Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:09:29.72618Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:10:18.501691Z","caller":"traceutil/trace.go:171","msg":"trace[688041041] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"111.008082ms","start":"2026-10-16T00:10:18.390645Z","end":"2026-10-16T00:10:18.501653Z","steps":["trace[688041041] 'process raft request' (duration: 110.715966ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:18.815822Z","caller":"traceutil/trace.go:171","msg":"trace[817611296] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"354.551542ms","start":"2026-10-16T00:10:18.461238Z","end":"2026-10-16T00:10:18.81579Z","steps":["trace[817611296] 'process raft request' (duration: 353.956398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:18.816437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:18.461208Z","time spent":"354.696694ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:10:19.225116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.207859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T00:10:19.225409Z","caller":"traceutil/trace.go:171","msg":"trace[1020451356] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"332.984646ms","start":"2026-10-16T00:10:18.892404Z","end":"2026-10-16T00:10:19.225388Z","steps":["trace[1020451356] 'process raft request' (duration: 332.851383ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:19.225425Z","caller":"traceutil/trace.go:171","msg":"trace[1634030972] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"648.003804ms","start":"2026-10-16T00:10:18.577398Z","end":"2026-10-16T00:10:19.225402Z","steps":["trace[1634030972] 'read index received' (duration: 237.462235ms)","trace[1634030972] 'applied index is now lower than readState.Index' (duration: 410.539399ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:10:19.22554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:18.892376Z","time spent":"333.083657ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:10:19.225642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.238619ms","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-10-16T00:10:19.225696Z","caller":"traceutil/trace.go:171","msg":"trace[1213281201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"648.331201ms","start":"2026-10-16T00:10:18.577349Z","end":"2026-10-16T00:10:19.22568Z","steps":["trace[1213281201] 'agreement among raft nodes before linearized reading' (duration: 648.121036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:19.225742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:18.57733Z","time spent":"648.399783ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T00:10:19.466315Z","caller":"traceutil/trace.go:171","msg":"trace[1380759088] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"223.857355ms","start":"2026-10-16T00:10:19.242437Z","end":"2026-10-16T00:10:19.466294Z","steps":["trace[1380759088] 'read index received' (duration: 144.362689ms)","trace[1380759088] 'applied index is now lower than readState.Index' (duration: 79.493746ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:10:19.466541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.09322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:10:19.46664Z","caller":"traceutil/trace.go:171","msg":"trace[148075802] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:649; }","duration":"224.166911ms","start":"2026-10-16T00:10:19.242418Z","end":"2026-10-16T00:10:19.466585Z","steps":["trace[148075802] 'agreement among raft nodes before linearized reading' (duration: 224.008278ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:19.466737Z","caller":"traceutil/trace.go:171","msg":"trace[657418027] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"234.682324ms","start":"2026-10-16T00:10:19.232017Z","end":"2026-10-16T00:10:19.466699Z","steps":["trace[657418027] 'process raft request' (duration: 154.792459ms)","trace[657418027] 'compare' (duration: 79.375863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:10:20.026546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.893823ms","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-10-16T00:10:20.026625Z","caller":"traceutil/trace.go:171","msg":"trace[1263423916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"395.016466ms","start":"2026-10-16T00:10:19.63159Z","end":"2026-10-16T00:10:20.026607Z","steps":["trace[1263423916] 'range keys from in-memory index tree' (duration: 394.688258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:20.02666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:19.631571Z","time spent":"395.081467ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-16T00:10:20.160871Z","caller":"traceutil/trace.go:171","msg":"trace[1452088011] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"305.856386ms","start":"2026-10-16T00:10:19.85499Z","end":"2026-10-16T00:10:20.160846Z","steps":["trace[1452088011] 'process raft request' (duration: 305.000697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:20.161104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:10:19.854937Z","time spent":"306.070961ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:10:20.170269Z","caller":"traceutil/trace.go:171","msg":"trace[1864111703] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"133.876407ms","start":"2026-10-16T00:10:20.036368Z","end":"2026-10-16T00:10:20.170245Z","steps":["trace[1864111703] 'read index received' (duration: 123.425736ms)","trace[1864111703] 'applied index is now lower than readState.Index' (duration: 10.449291ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:10:20.17039Z","caller":"traceutil/trace.go:171","msg":"trace[740796762] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"139.543423ms","start":"2026-10-16T00:10:20.030829Z","end":"2026-10-16T00:10:20.170373Z","steps":["trace[740796762] 'process raft request' (duration: 139.276347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:20.170493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.104982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-16T00:10:20.170542Z","caller":"traceutil/trace.go:171","msg":"trace[1312054634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"134.200864ms","start":"2026-10-16T00:10:20.036325Z","end":"2026-10-16T00:10:20.170526Z","steps":["trace[1312054634] 'agreement among raft nodes before linearized reading' (duration: 134.038801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:20.170755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.586029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-16T00:10:20.170872Z","caller":"traceutil/trace.go:171","msg":"trace[566654859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"113.740022ms","start":"2026-10-16T00:10:20.057101Z","end":"2026-10-16T00:10:20.170841Z","steps":["trace[566654859] 'agreement among raft nodes before linearized reading' (duration: 113.364624ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:21.686642Z","caller":"traceutil/trace.go:171","msg":"trace[1760616459] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"149.40107ms","start":"2026-10-16T00:10:21.537213Z","end":"2026-10-16T00:10:21.686614Z","steps":["trace[1760616459] 'process raft request' (duration: 149.231156ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:21.689875Z","caller":"traceutil/trace.go:171","msg":"trace[267451414] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"137.782394ms","start":"2026-10-16T00:10:21.552069Z","end":"2026-10-16T00:10:21.689851Z","steps":["trace[267451414] 'process raft request' (duration: 137.593959ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:03.789511Z","caller":"traceutil/trace.go:171","msg":"trace[498839427] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"100.96628ms","start":"2026-10-16T00:11:03.688516Z","end":"2026-10-16T00:11:03.789482Z","steps":["trace[498839427] 'process raft request' (duration: 100.779296ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:05.311336Z","caller":"traceutil/trace.go:171","msg":"trace[439770636] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"219.723444ms","start":"2026-10-16T00:11:05.09159Z","end":"2026-10-16T00:11:05.311314Z","steps":["trace[439770636] 'process raft request' (duration: 219.573701ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:21.894657Z","caller":"traceutil/trace.go:171","msg":"trace[915756436] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"162.586834ms","start":"2026-10-16T00:11:21.732033Z","end":"2026-10-16T00:11:21.894619Z","steps":["trace[915756436] 'process raft request' (duration: 162.381109ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:27.54702Z","caller":"traceutil/trace.go:171","msg":"trace[1632593648] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"116.860195ms","start":"2026-10-16T00:11:27.430065Z","end":"2026-10-16T00:11:27.546926Z","steps":["trace[1632593648] 'process raft request' (duration: 116.694442ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:30.078206Z","caller":"traceutil/trace.go:171","msg":"trace[1809073472] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"121.336154ms","start":"2026-10-16T00:11:29.95684Z","end":"2026-10-16T00:11:30.078176Z","steps":["trace[1809073472] 'process raft request' (duration: 121.15271ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:30.12344Z","caller":"traceutil/trace.go:171","msg":"trace[1324344648] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"165.347484ms","start":"2026-10-16T00:11:29.958067Z","end":"2026-10-16T00:11:30.123415Z","steps":["trace[1324344648] 'process raft request' (duration: 165.218671ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:32.175059Z","caller":"traceutil/trace.go:171","msg":"trace[1896327078] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"104.877501ms","start":"2026-10-16T00:11:32.070154Z","end":"2026-10-16T00:11:32.175031Z","steps":["trace[1896327078] 'process raft request' (duration: 104.476352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:58.092176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.201614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:11:58.092325Z","caller":"traceutil/trace.go:171","msg":"trace[764663328] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"193.891934ms","start":"2026-10-16T00:11:57.898411Z","end":"2026-10-16T00:11:58.092302Z","steps":["trace[764663328] 'read index received' (duration: 2.399683ms)","trace[764663328] 'applied index is now lower than readState.Index' (duration: 191.490581ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:58.092454Z","caller":"traceutil/trace.go:171","msg":"trace[908227568] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"251.569395ms","start":"2026-10-16T00:11:57.840862Z","end":"2026-10-16T00:11:58.092432Z","steps":["trace[908227568] 'process raft request' (duration: 59.987152ms)","trace[908227568] 'compare' (duration: 190.905558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:58.092562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.148679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9e255457-cbd8-40ed-b581-85c6e9f1dea1\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-16T00:11:58.092617Z","caller":"traceutil/trace.go:171","msg":"trace[385721630] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9e255457-cbd8-40ed-b581-85c6e9f1dea1; range_end:; response_count:1; response_revision:1918; }","duration":"194.242692ms","start":"2026-10-16T00:11:57.898362Z","end":"2026-10-16T00:11:58.092605Z","steps":["trace[385721630] 'agreement among raft nodes before linearized reading' (duration: 194.000596ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:58.142575Z","caller":"traceutil/trace.go:171","msg":"trace[881275797] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"132.870118ms","start":"2026-10-16T00:11:58.009674Z","end":"2026-10-16T00:11:58.142544Z","steps":["trace[881275797] 'process raft request' (duration: 132.712065ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:05.920822Z","caller":"traceutil/trace.go:171","msg":"trace[687255031] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"121.984609ms","start":"2026-10-16T00:12:05.798782Z","end":"2026-10-16T00:12:05.920767Z","steps":["trace[687255031] 'process raft request' (duration: 121.688022ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:12.852Z","caller":"traceutil/trace.go:171","msg":"trace[2086441100] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"314.42263ms","start":"2026-10-16T00:12:12.537529Z","end":"2026-10-16T00:12:12.851952Z","steps":["trace[2086441100] 'process raft request' (duration: 314.139264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:12:12.852181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:12:12.537477Z","time spent":"314.619885ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:12:12.851951Z","caller":"traceutil/trace.go:171","msg":"trace[199082525] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"311.408934ms","start":"2026-10-16T00:12:12.540503Z","end":"2026-10-16T00:12:12.851912Z","steps":["trace[199082525] 'read index received' (duration: 311.20592ms)","trace[199082525] 'applied index is now lower than readState.Index' (duration: 201.504µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:12:12.852294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.757102ms","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-10-16T00:12:12.852361Z","caller":"traceutil/trace.go:171","msg":"trace[2057397495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2041; }","duration":"311.918086ms","start":"2026-10-16T00:12:12.540428Z","end":"2026-10-16T00:12:12.852346Z","steps":["trace[2057397495] 'agreement among raft nodes before linearized reading' (duration: 311.641809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:12:12.852406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:12:12.540399Z","time spent":"311.997137ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-16T00:12:13.004042Z","caller":"traceutil/trace.go:171","msg":"trace[616839581] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"318.932219ms","start":"2026-10-16T00:12:12.685075Z","end":"2026-10-16T00:12:13.004008Z","steps":["trace[616839581] 'process raft request' (duration: 296.100136ms)","trace[616839581] 'compare' (duration: 22.68429ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:12:13.00422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:12:12.685046Z","time spent":"319.083223ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:12:13.005528Z","caller":"traceutil/trace.go:171","msg":"trace[1214552567] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"147.787367ms","start":"2026-10-16T00:12:12.857715Z","end":"2026-10-16T00:12:13.005503Z","steps":["trace[1214552567] 'process raft request' (duration: 147.583063ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:38.266455Z","caller":"traceutil/trace.go:171","msg":"trace[67107851] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"136.809404ms","start":"2026-10-16T00:12:38.129621Z","end":"2026-10-16T00:12:38.26643Z","steps":["trace[67107851] 'process raft request' (duration: 136.332784ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:50.40464Z","caller":"traceutil/trace.go:171","msg":"trace[1193626] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"101.177347ms","start":"2026-10-16T00:12:50.303434Z","end":"2026-10-16T00:12:50.404611Z","steps":["trace[1193626] 'process raft request' (duration: 100.951692ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:50.921754Z","caller":"traceutil/trace.go:171","msg":"trace[2037201058] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"557.487028ms","start":"2026-10-16T00:12:50.364244Z","end":"2026-10-16T00:12:50.921731Z","steps":["trace[2037201058] 'process raft request' (duration: 557.262683ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:50.921841Z","caller":"traceutil/trace.go:171","msg":"trace[1907329100] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"543.613704ms","start":"2026-10-16T00:12:50.37816Z","end":"2026-10-16T00:12:50.921774Z","steps":["trace[1907329100] 'process raft request' (duration: 543.519582ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:50.921849Z","caller":"traceutil/trace.go:171","msg":"trace[57520952] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2341; }","duration":"474.353814ms","start":"2026-10-16T00:12:50.447473Z","end":"2026-10-16T00:12:50.921826Z","steps":["trace[57520952] 'read index received' (duration: 473.962535ms)","trace[57520952] 'applied index is now lower than readState.Index' (duration: 388.299µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:12:50.921938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:12:50.364215Z","time spent":"557.62821ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:12:50.92195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.481527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:12:50.922078Z","caller":"traceutil/trace.go:171","msg":"trace[2036348069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2256; }","duration":"474.6153ms","start":"2026-10-16T00:12:50.447405Z","end":"2026-10-16T00:12:50.92202Z","steps":["trace[2036348069] 'agreement among raft nodes before linearized reading' (duration: 474.494067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:12:50.922134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:12:50.447382Z","time spent":"474.738562ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:12:50.922278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:12:50.378136Z","time spent":"544.039774ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:14:13.329995Z","caller":"traceutil/trace.go:171","msg":"trace[1609523982] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"147.880556ms","start":"2026-10-16T00:14:13.182091Z","end":"2026-10-16T00:14:13.329972Z","steps":["trace[1609523982] 'process raft request' (duration: 147.722762ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:20.825707Z","caller":"traceutil/trace.go:171","msg":"trace[1144945593] transaction","detail":"{read_only:false; response_revision:4084; number_of_response:1; }","duration":"114.947166ms","start":"2026-10-16T00:17:20.710725Z","end":"2026-10-16T00:17:20.825672Z","steps":["trace[1144945593] 'process raft request' (duration: 71.161904ms)","trace[1144945593] 'compare' (duration: 43.490266ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:26.014316Z","caller":"traceutil/trace.go:171","msg":"trace[506293113] transaction","detail":"{read_only:false; response_revision:4107; number_of_response:1; }","duration":"163.621196ms","start":"2026-10-16T00:17:25.850674Z","end":"2026-10-16T00:17:26.014296Z","steps":["trace[506293113] 'process raft request' (duration: 163.484043ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:26.17368Z","caller":"traceutil/trace.go:171","msg":"trace[1514980331] transaction","detail":"{read_only:false; response_revision:4108; number_of_response:1; }","duration":"121.824397ms","start":"2026-10-16T00:17:26.051824Z","end":"2026-10-16T00:17:26.173649Z","steps":["trace[1514980331] 'process raft request' (duration: 121.672754ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:26.174535Z","caller":"traceutil/trace.go:171","msg":"trace[140157115] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"117.285107ms","start":"2026-10-16T00:17:26.057222Z","end":"2026-10-16T00:17:26.174507Z","steps":["trace[140157115] 'process raft request' (duration: 117.160504ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:26.174756Z","caller":"traceutil/trace.go:171","msg":"trace[1139935509] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"112.553624ms","start":"2026-10-16T00:17:26.062156Z","end":"2026-10-16T00:17:26.17471Z","steps":["trace[1139935509] 'process raft request' (duration: 112.315658ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:53.709534Z","caller":"traceutil/trace.go:171","msg":"trace[835076257] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"200.114703ms","start":"2026-10-16T00:18:53.509394Z","end":"2026-10-16T00:18:53.709509Z","steps":["trace[835076257] 'process raft request' (duration: 200.042891ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:53.709574Z","caller":"traceutil/trace.go:171","msg":"trace[1088803334] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"200.178294ms","start":"2026-10-16T00:18:53.509361Z","end":"2026-10-16T00:18:53.709539Z","steps":["trace[1088803334] 'process raft request' (duration: 135.026054ms)","trace[1088803334] 'compare' (duration: 64.882664ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:18:55.2527Z","caller":"traceutil/trace.go:171","msg":"trace[996476164] linearizableReadLoop","detail":"{readStateIndex:5486; appliedIndex:5485; }","duration":"104.726664ms","start":"2026-10-16T00:18:55.147951Z","end":"2026-10-16T00:18:55.252677Z","steps":["trace[996476164] 'read index received' (duration: 104.498379ms)","trace[996476164] 'applied index is now lower than readState.Index' (duration: 227.245µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:18:55.253059Z","caller":"traceutil/trace.go:171","msg":"trace[1513578523] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"162.90096ms","start":"2026-10-16T00:18:55.090131Z","end":"2026-10-16T00:18:55.253032Z","steps":["trace[1513578523] 'process raft request' (duration: 162.390999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:18:55.253154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.174704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84d09059-8465-4f17-be56-32a3433e5735\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-16T00:18:55.253241Z","caller":"traceutil/trace.go:171","msg":"trace[1264457256] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84d09059-8465-4f17-be56-32a3433e5735; range_end:; response_count:1; response_revision:5284; }","duration":"105.310767ms","start":"2026-10-16T00:18:55.14791Z","end":"2026-10-16T00:18:55.253221Z","steps":["trace[1264457256] 'agreement among raft nodes before linearized reading' (duration: 105.054172ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:55.391108Z","caller":"traceutil/trace.go:171","msg":"trace[1887395273] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"133.571512ms","start":"2026-10-16T00:18:55.257511Z","end":"2026-10-16T00:18:55.391083Z","steps":["trace[1887395273] 'process raft request' (duration: 63.531904ms)","trace[1887395273] 'compare' (duration: 69.849364ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:18:55.410135Z","caller":"traceutil/trace.go:171","msg":"trace[765478095] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"151.050179ms","start":"2026-10-16T00:18:55.259061Z","end":"2026-10-16T00:18:55.410111Z","steps":["trace[765478095] 'process raft request' (duration: 150.897735ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:55.410268Z","caller":"traceutil/trace.go:171","msg":"trace[1472394256] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"140.795542ms","start":"2026-10-16T00:18:55.269454Z","end":"2026-10-16T00:18:55.41025Z","steps":["trace[1472394256] 'process raft request' (duration: 140.603187ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:18:55.410255Z","caller":"traceutil/trace.go:171","msg":"trace[2088988739] linearizableReadLoop","detail":"{readStateIndex:5489; appliedIndex:5486; }","duration":"106.648437ms","start":"2026-10-16T00:18:55.303572Z","end":"2026-10-16T00:18:55.41022Z","steps":["trace[2088988739] 'read index received' (duration: 17.460666ms)","trace[2088988739] 'applied index is now lower than readState.Index' (duration: 89.185361ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:18:55.410483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.892302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T00:18:55.410536Z","caller":"traceutil/trace.go:171","msg":"trace[510136031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5287; }","duration":"106.982135ms","start":"2026-10-16T00:18:55.30354Z","end":"2026-10-16T00:18:55.410522Z","steps":["trace[510136031] 'agreement among raft nodes before linearized reading' (duration: 106.813161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:18:57.830371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.661531ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:18:57.830499Z","caller":"traceutil/trace.go:171","msg":"trace[1689424825] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5322; }","duration":"203.809214ms","start":"2026-10-16T00:18:57.626671Z","end":"2026-10-16T00:18:57.83048Z","steps":["trace[1689424825] 'range keys from in-memory index tree' (duration: 203.641351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:18:57.830502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.980403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-16T00:18:57.830571Z","caller":"traceutil/trace.go:171","msg":"trace[503774158] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5322; }","duration":"235.096806ms","start":"2026-10-16T00:18:57.595456Z","end":"2026-10-16T00:18:57.830553Z","steps":["trace[503774158] 'range keys from in-memory index tree' (duration: 234.81913ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:10.458949Z","caller":"traceutil/trace.go:171","msg":"trace[172191418] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"128.628573ms","start":"2026-10-16T00:19:10.330299Z","end":"2026-10-16T00:19:10.458927Z","steps":["trace[172191418] 'process raft request' (duration: 128.195684ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:10.613997Z","caller":"traceutil/trace.go:171","msg":"trace[2118967772] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"150.515157ms","start":"2026-10-16T00:19:10.463452Z","end":"2026-10-16T00:19:10.613967Z","steps":["trace[2118967772] 'process raft request' (duration: 101.962773ms)","trace[2118967772] 'compare' (duration: 48.242367ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:19:10.614064Z","caller":"traceutil/trace.go:171","msg":"trace[259677559] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"150.481426ms","start":"2026-10-16T00:19:10.46355Z","end":"2026-10-16T00:19:10.614032Z","steps":["trace[259677559] 'process raft request' (duration: 150.330453ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:10.61422Z","caller":"traceutil/trace.go:171","msg":"trace[292206345] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"150.014365ms","start":"2026-10-16T00:19:10.464151Z","end":"2026-10-16T00:19:10.614165Z","steps":["trace[292206345] 'process raft request' (duration: 149.833551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:11.011139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.414537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:19:11.011216Z","caller":"traceutil/trace.go:171","msg":"trace[1672366279] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5566; }","duration":"142.506369ms","start":"2026-10-16T00:19:10.868692Z","end":"2026-10-16T00:19:11.011199Z","steps":["trace[1672366279] 'count revisions from in-memory index tree' (duration: 142.337266ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:12.791829Z","caller":"traceutil/trace.go:171","msg":"trace[1420366001] transaction","detail":"{read_only:false; response_revision:5583; number_of_response:1; }","duration":"172.765168ms","start":"2026-10-16T00:19:12.619038Z","end":"2026-10-16T00:19:12.791803Z","steps":["trace[1420366001] 'process raft request' (duration: 172.612104ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:12.792762Z","caller":"traceutil/trace.go:171","msg":"trace[193223800] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"162.825748ms","start":"2026-10-16T00:19:12.629908Z","end":"2026-10-16T00:19:12.792734Z","steps":["trace[193223800] 'process raft request' (duration: 162.655025ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:12.793101Z","caller":"traceutil/trace.go:171","msg":"trace[1309992746] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"162.591383ms","start":"2026-10-16T00:19:12.630497Z","end":"2026-10-16T00:19:12.793089Z","steps":["trace[1309992746] 'process raft request' (duration: 162.198914ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:12.792991Z","caller":"traceutil/trace.go:171","msg":"trace[1847735980] linearizableReadLoop","detail":"{readStateIndex:5796; appliedIndex:5793; }","duration":"149.706228ms","start":"2026-10-16T00:19:12.643261Z","end":"2026-10-16T00:19:12.792968Z","steps":["trace[1847735980] 'read index received' (duration: 148.43721ms)","trace[1847735980] 'applied index is now lower than readState.Index' (duration: 1.267608ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:19:12.793502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.21547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:19:12.793612Z","caller":"traceutil/trace.go:171","msg":"trace[1300160508] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5585; }","duration":"150.378143ms","start":"2026-10-16T00:19:12.643219Z","end":"2026-10-16T00:19:12.793597Z","steps":["trace[1300160508] 'agreement among raft nodes before linearized reading' (duration: 150.22839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:12.793862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.294677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-568785d674-zzs8k\" ","response":"range_response_count:1 size:11844"} {"level":"info","ts":"2026-10-16T00:19:12.7939Z","caller":"traceutil/trace.go:171","msg":"trace[743134286] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-568785d674-zzs8k; range_end:; response_count:1; response_revision:5585; }","duration":"129.369029ms","start":"2026-10-16T00:19:12.664521Z","end":"2026-10-16T00:19:12.79389Z","steps":["trace[743134286] 'agreement among raft nodes before linearized reading' (duration: 129.241766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:12.79401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.116662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:19:12.794052Z","caller":"traceutil/trace.go:171","msg":"trace[1929076146] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5585; }","duration":"119.241225ms","start":"2026-10-16T00:19:12.674801Z","end":"2026-10-16T00:19:12.794042Z","steps":["trace[1929076146] 'agreement among raft nodes before linearized reading' (duration: 119.148393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:19.812308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.132525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:19:19.812396Z","caller":"traceutil/trace.go:171","msg":"trace[538547178] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5738; }","duration":"206.264039ms","start":"2026-10-16T00:19:19.606113Z","end":"2026-10-16T00:19:19.812377Z","steps":["trace[538547178] 'count revisions from in-memory index tree' (duration: 206.031393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:19.812492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.180446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54c25d36-d916-4457-bad1-2850653828e0\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-16T00:19:19.812527Z","caller":"traceutil/trace.go:171","msg":"trace[444779449] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54c25d36-d916-4457-bad1-2850653828e0; range_end:; response_count:1; response_revision:5738; }","duration":"220.237667ms","start":"2026-10-16T00:19:19.592281Z","end":"2026-10-16T00:19:19.812519Z","steps":["trace[444779449] 'range keys from in-memory index tree' (duration: 220.018072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:19.812529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.452213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-16T00:19:19.812543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.336092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-16T00:19:19.812563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.633047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-16T00:19:19.812624Z","caller":"traceutil/trace.go:171","msg":"trace[655363808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5738; }","duration":"231.467006ms","start":"2026-10-16T00:19:19.581137Z","end":"2026-10-16T00:19:19.812604Z","steps":["trace[655363808] 'range keys from in-memory index tree' (duration: 231.144979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:19:19.812667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.91995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T00:19:19.812612Z","caller":"traceutil/trace.go:171","msg":"trace[92275874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5738; }","duration":"254.601596ms","start":"2026-10-16T00:19:19.557991Z","end":"2026-10-16T00:19:19.812592Z","steps":["trace[92275874] 'range keys from in-memory index tree' (duration: 254.157286ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:19.812682Z","caller":"traceutil/trace.go:171","msg":"trace[72504741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5738; }","duration":"235.816352ms","start":"2026-10-16T00:19:19.576839Z","end":"2026-10-16T00:19:19.812656Z","steps":["trace[72504741] 'range keys from in-memory index tree' (duration: 235.355481ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:19.812705Z","caller":"traceutil/trace.go:171","msg":"trace[1133677212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5738; }","duration":"239.009522ms","start":"2026-10-16T00:19:19.573686Z","end":"2026-10-16T00:19:19.812695Z","steps":["trace[1133677212] 'range keys from in-memory index tree' (duration: 238.779477ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:20.061937Z","caller":"traceutil/trace.go:171","msg":"trace[1070480041] transaction","detail":"{read_only:false; response_revision:5740; number_of_response:1; }","duration":"244.028223ms","start":"2026-10-16T00:19:19.817864Z","end":"2026-10-16T00:19:20.061893Z","steps":["trace[1070480041] 'process raft request' (duration: 205.007981ms)","trace[1070480041] 'compare' (duration: 38.754706ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:19:20.061983Z","caller":"traceutil/trace.go:171","msg":"trace[1952520029] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"244.007082ms","start":"2026-10-16T00:19:19.817943Z","end":"2026-10-16T00:19:20.061951Z","steps":["trace[1952520029] 'process raft request' (duration: 243.91253ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:20.062219Z","caller":"traceutil/trace.go:171","msg":"trace[834663713] transaction","detail":"{read_only:false; response_revision:5741; number_of_response:1; }","duration":"244.245538ms","start":"2026-10-16T00:19:19.81796Z","end":"2026-10-16T00:19:20.062206Z","steps":["trace[834663713] 'process raft request' (duration: 243.835479ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:19:29.968648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-10-16T00:19:30.049692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"79.320163ms","hash":1085128682,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":32194560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:19:30.049776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1085128682,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:24:29.976685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5868} {"level":"info","ts":"2026-10-16T00:24:30.070134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5868,"took":"91.260495ms","hash":565356746,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":26898432,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:24:30.07024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":565356746,"revision":5868,"compact-revision":2988} {"level":"info","ts":"2026-10-16T00:25:35.896176Z","caller":"traceutil/trace.go:171","msg":"trace[828714911] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"510.140314ms","start":"2026-10-16T00:25:35.386013Z","end":"2026-10-16T00:25:35.896154Z","steps":["trace[828714911] 'process raft request' (duration: 509.840578ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:35.896135Z","caller":"traceutil/trace.go:171","msg":"trace[1153044125] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8924; }","duration":"499.418228ms","start":"2026-10-16T00:25:35.396684Z","end":"2026-10-16T00:25:35.896102Z","steps":["trace[1153044125] 'read index received' (duration: 499.139982ms)","trace[1153044125] 'applied index is now lower than readState.Index' (duration: 276.886µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:25:35.896341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:35.385961Z","time spent":"510.280137ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:25:35.896387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.707124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T00:25:35.896435Z","caller":"traceutil/trace.go:171","msg":"trace[1042566495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8617; }","duration":"499.808786ms","start":"2026-10-16T00:25:35.396614Z","end":"2026-10-16T00:25:35.896423Z","steps":["trace[1042566495] 'agreement among raft nodes before linearized reading' (duration: 499.604142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:25:35.896469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:35.396588Z","time spent":"499.873308ms","remote":"127.0.0.1:59550","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-10-16T00:25:35.902252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.668107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:25:35.902356Z","caller":"traceutil/trace.go:171","msg":"trace[1122706277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8619; }","duration":"453.816341ms","start":"2026-10-16T00:25:35.448512Z","end":"2026-10-16T00:25:35.902329Z","steps":["trace[1122706277] 'agreement among raft nodes before linearized reading' (duration: 453.663527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:25:35.902414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:35.448495Z","time spent":"453.906543ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T00:25:35.902682Z","caller":"traceutil/trace.go:171","msg":"trace[571421787] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"401.284251ms","start":"2026-10-16T00:25:35.501373Z","end":"2026-10-16T00:25:35.902657Z","steps":["trace[571421787] 'process raft request' (duration: 400.585486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:25:35.902792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:35.501358Z","time spent":"401.370033ms","remote":"127.0.0.1:59550","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-10-16T00:25:35.904092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.805162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:25:35.904395Z","caller":"traceutil/trace.go:171","msg":"trace[756687059] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8619; }","duration":"430.970966ms","start":"2026-10-16T00:25:35.473236Z","end":"2026-10-16T00:25:35.904207Z","steps":["trace[756687059] 'agreement among raft nodes before linearized reading' (duration: 429.224958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:25:35.904556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:35.473225Z","time spent":"431.294583ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":876,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:25:48.522535Z","caller":"traceutil/trace.go:171","msg":"trace[1093887899] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"424.406312ms","start":"2026-10-16T00:25:48.098094Z","end":"2026-10-16T00:25:48.522501Z","steps":["trace[1093887899] 'process raft request' (duration: 399.810799ms)","trace[1093887899] 'compare' (duration: 24.46984ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:25:48.522714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:48.098067Z","time spent":"424.548634ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:25:48.538688Z","caller":"traceutil/trace.go:171","msg":"trace[296125165] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"119.024178ms","start":"2026-10-16T00:25:48.419613Z","end":"2026-10-16T00:25:48.538637Z","steps":["trace[296125165] 'process raft request' (duration: 118.860535ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:48.538739Z","caller":"traceutil/trace.go:171","msg":"trace[412810778] linearizableReadLoop","detail":"{readStateIndex:8996; appliedIndex:8994; }","duration":"108.318952ms","start":"2026-10-16T00:25:48.430389Z","end":"2026-10-16T00:25:48.538708Z","steps":["trace[412810778] 'read index received' (duration: 67.51034ms)","trace[412810778] 'applied index is now lower than readState.Index' (duration: 40.807352ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:25:48.538949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.540216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ms79r\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-16T00:25:48.539012Z","caller":"traceutil/trace.go:171","msg":"trace[267897554] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ms79r; range_end:; response_count:1; response_revision:8688; }","duration":"108.626019ms","start":"2026-10-16T00:25:48.430355Z","end":"2026-10-16T00:25:48.538981Z","steps":["trace[267897554] 'agreement among raft nodes before linearized reading' (duration: 108.440895ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:49.353546Z","caller":"traceutil/trace.go:171","msg":"trace[1796845313] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"173.530392ms","start":"2026-10-16T00:25:49.179987Z","end":"2026-10-16T00:25:49.353517Z","steps":["trace[1796845313] 'process raft request' (duration: 173.342138ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:13.834314Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023bb.wal"} {"level":"info","ts":"2026-10-16T00:26:18.908099Z","caller":"traceutil/trace.go:171","msg":"trace[1410597386] transaction","detail":"{read_only:false; response_revision:8901; number_of_response:1; }","duration":"250.151364ms","start":"2026-10-16T00:26:18.657923Z","end":"2026-10-16T00:26:18.908074Z","steps":["trace[1410597386] 'process raft request' (duration: 249.273634ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:19.127099Z","caller":"traceutil/trace.go:171","msg":"trace[548873393] linearizableReadLoop","detail":"{readStateIndex:9223; appliedIndex:9220; }","duration":"445.470656ms","start":"2026-10-16T00:26:18.681602Z","end":"2026-10-16T00:26:19.127072Z","steps":["trace[548873393] 'read index received' (duration: 224.16042ms)","trace[548873393] 'applied index is now lower than readState.Index' (duration: 221.309076ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:26:19.127363Z","caller":"traceutil/trace.go:171","msg":"trace[1223389144] transaction","detail":"{read_only:false; response_revision:8902; number_of_response:1; }","duration":"468.317241ms","start":"2026-10-16T00:26:18.659034Z","end":"2026-10-16T00:26:19.127352Z","steps":["trace[1223389144] 'process raft request' (duration: 467.83367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:26:19.127468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:26:18.65901Z","time spent":"468.392243ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:26:19.127579Z","caller":"traceutil/trace.go:171","msg":"trace[1784378271] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"465.371946ms","start":"2026-10-16T00:26:18.662175Z","end":"2026-10-16T00:26:19.127547Z","steps":["trace[1784378271] 'process raft request' (duration: 464.856155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:26:19.127748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:26:18.662158Z","time spent":"465.50199ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:26:19.127836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.245374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-16T00:26:19.127835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.028524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ms79r\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-16T00:26:19.127943Z","caller":"traceutil/trace.go:171","msg":"trace[1448050171] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ms79r; range_end:; response_count:1; response_revision:8903; }","duration":"386.199928ms","start":"2026-10-16T00:26:18.741718Z","end":"2026-10-16T00:26:19.127918Z","steps":["trace[1448050171] 'agreement among raft nodes before linearized reading' (duration: 385.83422ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:19.12788Z","caller":"traceutil/trace.go:171","msg":"trace[548956694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8903; }","duration":"446.374587ms","start":"2026-10-16T00:26:18.681495Z","end":"2026-10-16T00:26:19.127869Z","steps":["trace[548956694] 'agreement among raft nodes before linearized reading' (duration: 446.230064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:26:19.128023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:26:18.741702Z","time spent":"386.28872ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-ms79r\" "} {"level":"warn","ts":"2026-10-16T00:26:19.128067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:26:18.681461Z","time spent":"446.598011ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-16T00:26:21.23616Z","caller":"traceutil/trace.go:171","msg":"trace[835246809] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"102.34555ms","start":"2026-10-16T00:26:21.13378Z","end":"2026-10-16T00:26:21.236125Z","steps":["trace[835246809] 'process raft request' (duration: 102.090444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:26:28.494487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.94927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:26:28.494691Z","caller":"traceutil/trace.go:171","msg":"trace[1180047519] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"176.763404ms","start":"2026-10-16T00:26:28.317897Z","end":"2026-10-16T00:26:28.49466Z","steps":["trace[1180047519] 'process raft request' (duration: 27.562168ms)","trace[1180047519] 'compare' (duration: 148.827267ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:26:29.245689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.959087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:26:29.245814Z","caller":"traceutil/trace.go:171","msg":"trace[670655816] linearizableReadLoop","detail":"{readStateIndex:9277; appliedIndex:9276; }","duration":"249.734595ms","start":"2026-10-16T00:26:28.996061Z","end":"2026-10-16T00:26:29.245796Z","steps":["trace[670655816] 'read index received' (duration: 140.556714ms)","trace[670655816] 'applied index is now lower than readState.Index' (duration: 109.176281ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:26:29.245895Z","caller":"traceutil/trace.go:171","msg":"trace[819426386] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"277.212052ms","start":"2026-10-16T00:26:28.968631Z","end":"2026-10-16T00:26:29.245843Z","steps":["trace[819426386] 'process raft request' (duration: 167.999821ms)","trace[819426386] 'compare' (duration: 108.732641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:26:29.24596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.893349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:26:29.245987Z","caller":"traceutil/trace.go:171","msg":"trace[1685772398] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8955; }","duration":"249.923349ms","start":"2026-10-16T00:26:28.996055Z","end":"2026-10-16T00:26:29.245979Z","steps":["trace[1685772398] 'agreement among raft nodes before linearized reading' (duration: 249.787736ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:29.430295Z","caller":"traceutil/trace.go:171","msg":"trace[194078085] linearizableReadLoop","detail":"{readStateIndex:9279; appliedIndex:9278; }","duration":"122.036395ms","start":"2026-10-16T00:26:29.308239Z","end":"2026-10-16T00:26:29.430275Z","steps":["trace[194078085] 'read index received' (duration: 104.523718ms)","trace[194078085] 'applied index is now lower than readState.Index' (duration: 17.511827ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:26:29.430377Z","caller":"traceutil/trace.go:171","msg":"trace[1946820216] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"167.482769ms","start":"2026-10-16T00:26:29.262886Z","end":"2026-10-16T00:26:29.430369Z","steps":["trace[1946820216] 'process raft request' (duration: 149.88557ms)","trace[1946820216] 'compare' (duration: 17.352344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:26:29.430526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.261531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:26:29.430559Z","caller":"traceutil/trace.go:171","msg":"trace[708499527] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8957; }","duration":"122.359482ms","start":"2026-10-16T00:26:29.308189Z","end":"2026-10-16T00:26:29.430549Z","steps":["trace[708499527] 'agreement among raft nodes before linearized reading' (duration: 122.27632ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:32.605605Z","caller":"traceutil/trace.go:171","msg":"trace[1743899748] linearizableReadLoop","detail":"{readStateIndex:9309; appliedIndex:9306; }","duration":"157.684943ms","start":"2026-10-16T00:26:32.447891Z","end":"2026-10-16T00:26:32.605576Z","steps":["trace[1743899748] 'read index received' (duration: 11.678718ms)","trace[1743899748] 'applied index is now lower than readState.Index' (duration: 146.005215ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:26:32.605632Z","caller":"traceutil/trace.go:171","msg":"trace[1294809868] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8985; }","duration":"181.449167ms","start":"2026-10-16T00:26:32.424149Z","end":"2026-10-16T00:26:32.605598Z","steps":["trace[1294809868] 'process raft request' (duration: 181.174311ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:32.60598Z","caller":"traceutil/trace.go:171","msg":"trace[1542961303] transaction","detail":"{read_only:false; response_revision:8986; number_of_response:1; }","duration":"177.823617ms","start":"2026-10-16T00:26:32.428142Z","end":"2026-10-16T00:26:32.605966Z","steps":["trace[1542961303] 'process raft request' (duration: 177.366287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:26:32.606249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.340687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:26:32.60629Z","caller":"traceutil/trace.go:171","msg":"trace[1208775577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8986; }","duration":"158.421999ms","start":"2026-10-16T00:26:32.447858Z","end":"2026-10-16T00:26:32.60628Z","steps":["trace[1208775577] 'agreement among raft nodes before linearized reading' (duration: 158.339728ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:32.72837Z","caller":"traceutil/trace.go:171","msg":"trace[1601060353] transaction","detail":"{read_only:false; response_revision:8987; number_of_response:1; }","duration":"116.168185ms","start":"2026-10-16T00:26:32.612169Z","end":"2026-10-16T00:26:32.728337Z","steps":["trace[1601060353] 'process raft request' (duration: 92.170565ms)","trace[1601060353] 'compare' (duration: 23.54167ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:26:32.728475Z","caller":"traceutil/trace.go:171","msg":"trace[312292731] linearizableReadLoop","detail":"{readStateIndex:9310; appliedIndex:9309; }","duration":"115.871199ms","start":"2026-10-16T00:26:32.612576Z","end":"2026-10-16T00:26:32.728447Z","steps":["trace[312292731] 'read index received' (duration: 91.645814ms)","trace[312292731] 'applied index is now lower than readState.Index' (duration: 24.223025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:26:32.728689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.091644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m4xk5\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-10-16T00:26:32.72872Z","caller":"traceutil/trace.go:171","msg":"trace[685988774] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m4xk5; range_end:; response_count:1; response_revision:8987; }","duration":"116.178116ms","start":"2026-10-16T00:26:32.612532Z","end":"2026-10-16T00:26:32.72871Z","steps":["trace[685988774] 'agreement among raft nodes before linearized reading' (duration: 116.000082ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.63795Z","caller":"traceutil/trace.go:171","msg":"trace[1075833830] transaction","detail":"{read_only:false; response_revision:9515; number_of_response:1; }","duration":"133.491478ms","start":"2026-10-16T00:27:31.504424Z","end":"2026-10-16T00:27:31.637916Z","steps":["trace[1075833830] 'process raft request' (duration: 133.316145ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.637944Z","caller":"traceutil/trace.go:171","msg":"trace[1377980941] transaction","detail":"{read_only:false; response_revision:9514; number_of_response:1; }","duration":"134.811808ms","start":"2026-10-16T00:27:31.503079Z","end":"2026-10-16T00:27:31.63789Z","steps":["trace[1377980941] 'process raft request' (duration: 57.666334ms)","trace[1377980941] 'compare' (duration: 76.850147ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:31.638295Z","caller":"traceutil/trace.go:171","msg":"trace[1976275854] transaction","detail":"{read_only:false; response_revision:9517; number_of_response:1; }","duration":"124.628713ms","start":"2026-10-16T00:27:31.513656Z","end":"2026-10-16T00:27:31.638285Z","steps":["trace[1976275854] 'process raft request' (duration: 124.53458ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.638457Z","caller":"traceutil/trace.go:171","msg":"trace[1825471851] transaction","detail":"{read_only:false; response_revision:9516; number_of_response:1; }","duration":"133.253703ms","start":"2026-10-16T00:27:31.505194Z","end":"2026-10-16T00:27:31.638447Z","steps":["trace[1825471851] 'process raft request' (duration: 132.629249ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.638551Z","caller":"traceutil/trace.go:171","msg":"trace[1363128405] transaction","detail":"{read_only:false; response_revision:9518; number_of_response:1; }","duration":"120.925261ms","start":"2026-10-16T00:27:31.517614Z","end":"2026-10-16T00:27:31.638539Z","steps":["trace[1363128405] 'process raft request' (duration: 120.659105ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:31.638589Z","caller":"traceutil/trace.go:171","msg":"trace[656282422] linearizableReadLoop","detail":"{readStateIndex:9858; appliedIndex:9855; }","duration":"134.091892ms","start":"2026-10-16T00:27:31.504487Z","end":"2026-10-16T00:27:31.638579Z","steps":["trace[656282422] 'read index received' (duration: 9.174013ms)","trace[656282422] 'applied index is now lower than readState.Index' (duration: 124.916609ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:27:31.638905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.405519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128865"} {"level":"info","ts":"2026-10-16T00:27:31.638946Z","caller":"traceutil/trace.go:171","msg":"trace[531008938] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:9518; }","duration":"134.506741ms","start":"2026-10-16T00:27:31.50443Z","end":"2026-10-16T00:27:31.638937Z","steps":["trace[531008938] 'agreement among raft nodes before linearized reading' (duration: 134.314687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:31.638995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.566593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rook-ceph-crashcollector-instance\" ","response":"range_response_count:1 size:9258"} {"level":"info","ts":"2026-10-16T00:27:31.639121Z","caller":"traceutil/trace.go:171","msg":"trace[691102739] range","detail":"{range_begin:/registry/deployments/openstack/rook-ceph-crashcollector-instance; range_end:; response_count:1; response_revision:9518; }","duration":"130.731427ms","start":"2026-10-16T00:27:31.508366Z","end":"2026-10-16T00:27:31.639097Z","steps":["trace[691102739] 'agreement among raft nodes before linearized reading' (duration: 130.557964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:27:31.639431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.60205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/rook-ceph-rgw-ceph-a-699b8bdb59\" ","response":"range_response_count:1 size:24146"} {"level":"info","ts":"2026-10-16T00:27:31.639493Z","caller":"traceutil/trace.go:171","msg":"trace[468093106] range","detail":"{range_begin:/registry/replicasets/openstack/rook-ceph-rgw-ceph-a-699b8bdb59; range_end:; response_count:1; response_revision:9518; }","duration":"128.712933ms","start":"2026-10-16T00:27:31.51076Z","end":"2026-10-16T00:27:31.639473Z","steps":["trace[468093106] 'agreement among raft nodes before linearized reading' (duration: 128.499138ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:27:34.947473Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:27:34.953681Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:27:34.953783Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:28:01.42642Z","caller":"traceutil/trace.go:171","msg":"trace[164742280] linearizableReadLoop","detail":"{readStateIndex:10181; appliedIndex:10180; }","duration":"100.187912ms","start":"2026-10-16T00:28:01.326213Z","end":"2026-10-16T00:28:01.426401Z","steps":["trace[164742280] 'read index received' (duration: 100.048029ms)","trace[164742280] 'applied index is now lower than readState.Index' (duration: 138.953µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:28:01.426482Z","caller":"traceutil/trace.go:171","msg":"trace[1312538740] transaction","detail":"{read_only:false; response_revision:9831; number_of_response:1; }","duration":"206.841788ms","start":"2026-10-16T00:28:01.219607Z","end":"2026-10-16T00:28:01.426448Z","steps":["trace[1312538740] 'process raft request' (duration: 206.657664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:28:01.426531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.305646ms","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-10-16T00:28:01.426558Z","caller":"traceutil/trace.go:171","msg":"trace[2047221720] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:9831; }","duration":"100.405018ms","start":"2026-10-16T00:28:01.326144Z","end":"2026-10-16T00:28:01.426549Z","steps":["trace[2047221720] 'agreement among raft nodes before linearized reading' (duration: 100.332996ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:29:29.987075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8057} {"level":"info","ts":"2026-10-16T00:29:30.062015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8057,"took":"71.42659ms","hash":996602475,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":28282880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:29:30.062105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":996602475,"revision":8057,"compact-revision":5868} {"level":"warn","ts":"2026-10-16T00:33:22.663162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.333029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:33:22.663156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.182443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-10-16T00:33:22.663193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.023503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:33:22.663261Z","caller":"traceutil/trace.go:171","msg":"trace[506327412] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12163; }","duration":"102.474323ms","start":"2026-10-16T00:33:22.560764Z","end":"2026-10-16T00:33:22.663239Z","steps":["trace[506327412] 'range keys from in-memory index tree' (duration: 102.257448ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:33:22.663278Z","caller":"traceutil/trace.go:171","msg":"trace[1806065720] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12163; }","duration":"100.324615ms","start":"2026-10-16T00:33:22.56293Z","end":"2026-10-16T00:33:22.663255Z","steps":["trace[1806065720] 'range keys from in-memory index tree' (duration: 100.000228ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:33:22.663289Z","caller":"traceutil/trace.go:171","msg":"trace[1241751246] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12163; }","duration":"102.125105ms","start":"2026-10-16T00:33:22.561141Z","end":"2026-10-16T00:33:22.663266Z","steps":["trace[1241751246] 'range keys from in-memory index tree' (duration: 101.950572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:33:22.663154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.035323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T00:33:22.663412Z","caller":"traceutil/trace.go:171","msg":"trace[136984629] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12163; }","duration":"102.35823ms","start":"2026-10-16T00:33:22.561035Z","end":"2026-10-16T00:33:22.663393Z","steps":["trace[136984629] 'range keys from in-memory index tree' (duration: 101.91497ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:33:45.105217Z","caller":"traceutil/trace.go:171","msg":"trace[5805363] transaction","detail":"{read_only:false; response_revision:12288; number_of_response:1; }","duration":"112.367281ms","start":"2026-10-16T00:33:44.992819Z","end":"2026-10-16T00:33:45.105187Z","steps":["trace[5805363] 'process raft request' (duration: 112.131396ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:33:45.115351Z","caller":"traceutil/trace.go:171","msg":"trace[1591926766] transaction","detail":"{read_only:false; response_revision:12289; number_of_response:1; }","duration":"119.077689ms","start":"2026-10-16T00:33:44.996244Z","end":"2026-10-16T00:33:45.115322Z","steps":["trace[1591926766] 'process raft request' (duration: 118.921526ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:33:45.404543Z","caller":"traceutil/trace.go:171","msg":"trace[897879856] transaction","detail":"{read_only:false; response_revision:12290; number_of_response:1; }","duration":"147.967537ms","start":"2026-10-16T00:33:45.256554Z","end":"2026-10-16T00:33:45.404522Z","steps":["trace[897879856] 'process raft request' (duration: 56.63918ms)","trace[897879856] 'compare' (duration: 91.235365ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:33:45.433834Z","caller":"traceutil/trace.go:171","msg":"trace[907640720] transaction","detail":"{read_only:false; response_revision:12291; number_of_response:1; }","duration":"174.761839ms","start":"2026-10-16T00:33:45.259039Z","end":"2026-10-16T00:33:45.4338Z","steps":["trace[907640720] 'process raft request' (duration: 174.564885ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:33:45.434077Z","caller":"traceutil/trace.go:171","msg":"trace[1807538970] linearizableReadLoop","detail":"{readStateIndex:12723; appliedIndex:12720; }","duration":"167.440607ms","start":"2026-10-16T00:33:45.26657Z","end":"2026-10-16T00:33:45.434011Z","steps":["trace[1807538970] 'read index received' (duration: 46.753332ms)","trace[1807538970] 'applied index is now lower than readState.Index' (duration: 120.683375ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:33:45.434137Z","caller":"traceutil/trace.go:171","msg":"trace[2050505452] transaction","detail":"{read_only:false; response_revision:12292; number_of_response:1; }","duration":"174.694787ms","start":"2026-10-16T00:33:45.259385Z","end":"2026-10-16T00:33:45.434079Z","steps":["trace[2050505452] 'process raft request' (duration: 174.37836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:33:45.4343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.697032ms","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-10-16T00:33:45.434375Z","caller":"traceutil/trace.go:171","msg":"trace[1874131247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12292; }","duration":"167.833846ms","start":"2026-10-16T00:33:45.266522Z","end":"2026-10-16T00:33:45.434356Z","steps":["trace[1874131247] 'agreement among raft nodes before linearized reading' (duration: 167.621841ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:29.99657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10438} {"level":"info","ts":"2026-10-16T00:34:30.092442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10438,"took":"93.441434ms","hash":2896993032,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":25808896,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T00:34:30.09251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2896993032,"revision":10438,"compact-revision":8057} {"level":"info","ts":"2026-10-16T00:39:30.005272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12608} {"level":"info","ts":"2026-10-16T00:39:30.083494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12608,"took":"76.231194ms","hash":2309638856,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":27787264,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:39:30.08357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2309638856,"revision":12608,"compact-revision":10438} {"level":"info","ts":"2026-10-16T00:40:04.797417Z","caller":"traceutil/trace.go:171","msg":"trace[1374834282] transaction","detail":"{read_only:false; response_revision:14838; number_of_response:1; }","duration":"178.563014ms","start":"2026-10-16T00:40:04.618814Z","end":"2026-10-16T00:40:04.797377Z","steps":["trace[1374834282] 'process raft request' (duration: 178.259868ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:04.913307Z","caller":"traceutil/trace.go:171","msg":"trace[1181083675] linearizableReadLoop","detail":"{readStateIndex:15359; appliedIndex:15357; }","duration":"206.734467ms","start":"2026-10-16T00:40:04.7065Z","end":"2026-10-16T00:40:04.913234Z","steps":["trace[1181083675] 'read index received' (duration: 90.771405ms)","trace[1181083675] 'applied index is now lower than readState.Index' (duration: 115.961592ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:40:04.913346Z","caller":"traceutil/trace.go:171","msg":"trace[970050818] transaction","detail":"{read_only:false; response_revision:14839; number_of_response:1; }","duration":"294.377943ms","start":"2026-10-16T00:40:04.618906Z","end":"2026-10-16T00:40:04.913284Z","steps":["trace[970050818] 'process raft request' (duration: 293.580385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:04.913477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.949811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:40:04.913574Z","caller":"traceutil/trace.go:171","msg":"trace[609239632] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:14839; }","duration":"207.105945ms","start":"2026-10-16T00:40:04.706444Z","end":"2026-10-16T00:40:04.91355Z","steps":["trace[609239632] 'agreement among raft nodes before linearized reading' (duration: 206.90272ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:23.308081Z","caller":"traceutil/trace.go:171","msg":"trace[747363811] transaction","detail":"{read_only:false; response_revision:14946; number_of_response:1; }","duration":"320.046679ms","start":"2026-10-16T00:40:22.988001Z","end":"2026-10-16T00:40:23.308048Z","steps":["trace[747363811] 'process raft request' (duration: 319.888456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:23.308293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:40:22.987976Z","time spent":"320.221833ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:40:23.594197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.728352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:40:23.594543Z","caller":"traceutil/trace.go:171","msg":"trace[1661834621] transaction","detail":"{read_only:false; response_revision:14947; number_of_response:1; }","duration":"606.333053ms","start":"2026-10-16T00:40:22.988152Z","end":"2026-10-16T00:40:23.594485Z","steps":["trace[1661834621] 'process raft request' (duration: 358.166871ms)","trace[1661834621] 'compare' (duration: 247.573269ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:40:23.594702Z","caller":"traceutil/trace.go:171","msg":"trace[1767175968] transaction","detail":"{read_only:false; response_revision:14949; number_of_response:1; }","duration":"282.128572ms","start":"2026-10-16T00:40:23.312549Z","end":"2026-10-16T00:40:23.594677Z","steps":["trace[1767175968] 'process raft request' (duration: 282.061341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:23.594765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:40:22.988033Z","time spent":"606.62836ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:40:23.594928Z","caller":"traceutil/trace.go:171","msg":"trace[686207608] transaction","detail":"{read_only:false; response_revision:14948; number_of_response:1; }","duration":"601.990708ms","start":"2026-10-16T00:40:22.992927Z","end":"2026-10-16T00:40:23.594917Z","steps":["trace[686207608] 'process raft request' (duration: 601.405655ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:23.594937Z","caller":"traceutil/trace.go:171","msg":"trace[1085488032] linearizableReadLoop","detail":"{readStateIndex:15472; appliedIndex:15469; }","duration":"585.018453ms","start":"2026-10-16T00:40:23.0099Z","end":"2026-10-16T00:40:23.594918Z","steps":["trace[1085488032] 'read index received' (duration: 297.954292ms)","trace[1085488032] 'applied index is now lower than readState.Index' (duration: 287.061671ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:40:23.594985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:40:22.992879Z","time spent":"602.074909ms","remote":"127.0.0.1:59550","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-10-16T00:40:23.595293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.775671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-16T00:40:23.595251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.310319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:40:23.595361Z","caller":"traceutil/trace.go:171","msg":"trace[136315913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14949; }","duration":"576.873403ms","start":"2026-10-16T00:40:23.018465Z","end":"2026-10-16T00:40:23.595338Z","steps":["trace[136315913] 'agreement among raft nodes before linearized reading' (duration: 576.689999ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:23.595384Z","caller":"traceutil/trace.go:171","msg":"trace[904830076] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:14949; }","duration":"585.467402ms","start":"2026-10-16T00:40:23.009886Z","end":"2026-10-16T00:40:23.595354Z","steps":["trace[904830076] 'agreement among raft nodes before linearized reading' (duration: 585.236997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:23.595446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:40:23.009835Z","time spent":"585.590875ms","remote":"127.0.0.1:59768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:40:23.59544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.626478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.14\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T00:40:23.595511Z","caller":"traceutil/trace.go:171","msg":"trace[1688451838] range","detail":"{range_begin:/registry/masterleases/162.253.55.14; range_end:; response_count:1; response_revision:14949; }","duration":"174.74603ms","start":"2026-10-16T00:40:23.420749Z","end":"2026-10-16T00:40:23.595495Z","steps":["trace[1688451838] 'agreement among raft nodes before linearized reading' (duration: 174.503945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:23.595473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:40:23.018441Z","time spent":"576.960034ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T00:40:25.058814Z","caller":"traceutil/trace.go:171","msg":"trace[1462202058] linearizableReadLoop","detail":"{readStateIndex:15485; appliedIndex:15484; }","duration":"129.989551ms","start":"2026-10-16T00:40:24.928799Z","end":"2026-10-16T00:40:25.058788Z","steps":["trace[1462202058] 'read index received' (duration: 129.727475ms)","trace[1462202058] 'applied index is now lower than readState.Index' (duration: 261.216µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:40:25.058828Z","caller":"traceutil/trace.go:171","msg":"trace[128130880] transaction","detail":"{read_only:false; response_revision:14960; number_of_response:1; }","duration":"152.350035ms","start":"2026-10-16T00:40:24.90646Z","end":"2026-10-16T00:40:25.05881Z","steps":["trace[128130880] 'process raft request' (duration: 152.13895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:25.059198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.37163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T00:40:25.059233Z","caller":"traceutil/trace.go:171","msg":"trace[359778368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14960; }","duration":"130.477302ms","start":"2026-10-16T00:40:24.928747Z","end":"2026-10-16T00:40:25.059225Z","steps":["trace[359778368] 'agreement among raft nodes before linearized reading' (duration: 130.125124ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:34.192366Z","caller":"traceutil/trace.go:171","msg":"trace[883360933] linearizableReadLoop","detail":"{readStateIndex:15655; appliedIndex:15654; }","duration":"159.737248ms","start":"2026-10-16T00:40:34.032598Z","end":"2026-10-16T00:40:34.192335Z","steps":["trace[883360933] 'read index received' (duration: 159.397281ms)","trace[883360933] 'applied index is now lower than readState.Index' (duration: 338.747µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:40:34.192973Z","caller":"traceutil/trace.go:171","msg":"trace[233016334] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"192.621044ms","start":"2026-10-16T00:40:34.000306Z","end":"2026-10-16T00:40:34.192927Z","steps":["trace[233016334] 'process raft request' (duration: 191.817396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:34.193069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.67209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-16T00:40:34.193153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.524596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-56bf169f-c868-49a3-9d52-262a45c41676\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-10-16T00:40:34.19326Z","caller":"traceutil/trace.go:171","msg":"trace[1593858060] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-56bf169f-c868-49a3-9d52-262a45c41676; range_end:; response_count:1; response_revision:15126; }","duration":"160.70182ms","start":"2026-10-16T00:40:34.032535Z","end":"2026-10-16T00:40:34.193237Z","steps":["trace[1593858060] 'agreement among raft nodes before linearized reading' (duration: 160.309761ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:34.19327Z","caller":"traceutil/trace.go:171","msg":"trace[137088086] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15126; }","duration":"156.860955ms","start":"2026-10-16T00:40:34.036263Z","end":"2026-10-16T00:40:34.193124Z","steps":["trace[137088086] 'agreement among raft nodes before linearized reading' (duration: 156.733112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:34.193281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.267685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:40:34.193573Z","caller":"traceutil/trace.go:171","msg":"trace[974720011] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:15126; }","duration":"100.702384ms","start":"2026-10-16T00:40:34.092849Z","end":"2026-10-16T00:40:34.193552Z","steps":["trace[974720011] 'agreement among raft nodes before linearized reading' (duration: 100.305715ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:34.418815Z","caller":"traceutil/trace.go:171","msg":"trace[1212182875] linearizableReadLoop","detail":"{readStateIndex:15656; appliedIndex:15655; }","duration":"191.402168ms","start":"2026-10-16T00:40:34.227382Z","end":"2026-10-16T00:40:34.418784Z","steps":["trace[1212182875] 'read index received' (duration: 191.192073ms)","trace[1212182875] 'applied index is now lower than readState.Index' (duration: 208.655µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:40:34.41921Z","caller":"traceutil/trace.go:171","msg":"trace[1081827515] transaction","detail":"{read_only:false; response_revision:15127; number_of_response:1; }","duration":"217.892023ms","start":"2026-10-16T00:40:34.201303Z","end":"2026-10-16T00:40:34.419195Z","steps":["trace[1081827515] 'process raft request' (duration: 217.29915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:40:34.419357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.938969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ms79r\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-16T00:40:34.419388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.162112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-qqscc.18dedbabd1f3f597\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-16T00:40:34.419448Z","caller":"traceutil/trace.go:171","msg":"trace[1141458489] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ms79r; range_end:; response_count:1; response_revision:15127; }","duration":"192.100313ms","start":"2026-10-16T00:40:34.227325Z","end":"2026-10-16T00:40:34.419425Z","steps":["trace[1141458489] 'agreement among raft nodes before linearized reading' (duration: 191.732145ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:40:34.419499Z","caller":"traceutil/trace.go:171","msg":"trace[375927957] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-qqscc.18dedbabd1f3f597; range_end:; response_count:1; response_revision:15127; }","duration":"143.351636ms","start":"2026-10-16T00:40:34.276118Z","end":"2026-10-16T00:40:34.419469Z","steps":["trace[375927957] 'agreement among raft nodes before linearized reading' (duration: 143.000048ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:21.875864Z","caller":"traceutil/trace.go:171","msg":"trace[1611521881] transaction","detail":"{read_only:false; response_revision:16250; number_of_response:1; }","duration":"142.758445ms","start":"2026-10-16T00:42:21.733072Z","end":"2026-10-16T00:42:21.87583Z","steps":["trace[1611521881] 'process raft request' (duration: 142.585391ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:21.877029Z","caller":"traceutil/trace.go:171","msg":"trace[1047132167] linearizableReadLoop","detail":"{readStateIndex:16816; appliedIndex:16814; }","duration":"106.468522ms","start":"2026-10-16T00:42:21.770543Z","end":"2026-10-16T00:42:21.877011Z","steps":["trace[1047132167] 'read index received' (duration: 105.128603ms)","trace[1047132167] 'applied index is now lower than readState.Index' (duration: 1.338709ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:42:21.877254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.698628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ms79r\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-16T00:42:21.877297Z","caller":"traceutil/trace.go:171","msg":"trace[623917459] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ms79r; range_end:; response_count:1; response_revision:16251; }","duration":"106.80462ms","start":"2026-10-16T00:42:21.770481Z","end":"2026-10-16T00:42:21.877286Z","steps":["trace[623917459] 'agreement among raft nodes before linearized reading' (duration: 106.598616ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:21.877262Z","caller":"traceutil/trace.go:171","msg":"trace[2127739418] transaction","detail":"{read_only:false; response_revision:16251; number_of_response:1; }","duration":"127.771763ms","start":"2026-10-16T00:42:21.749424Z","end":"2026-10-16T00:42:21.877196Z","steps":["trace[2127739418] 'process raft request' (duration: 127.494427ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:22.25178Z","caller":"traceutil/trace.go:171","msg":"trace[1509197297] transaction","detail":"{read_only:false; response_revision:16252; number_of_response:1; }","duration":"170.755583ms","start":"2026-10-16T00:42:22.080992Z","end":"2026-10-16T00:42:22.251748Z","steps":["trace[1509197297] 'process raft request' (duration: 170.583259ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:22.262995Z","caller":"traceutil/trace.go:171","msg":"trace[1849814816] transaction","detail":"{read_only:false; response_revision:16253; number_of_response:1; }","duration":"172.4177ms","start":"2026-10-16T00:42:22.09055Z","end":"2026-10-16T00:42:22.262968Z","steps":["trace[1849814816] 'process raft request' (duration: 172.237246ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:22.263231Z","caller":"traceutil/trace.go:171","msg":"trace[1540081630] transaction","detail":"{read_only:false; response_revision:16254; number_of_response:1; }","duration":"165.450347ms","start":"2026-10-16T00:42:22.09777Z","end":"2026-10-16T00:42:22.26322Z","steps":["trace[1540081630] 'process raft request' (duration: 165.15641ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:22.263314Z","caller":"traceutil/trace.go:171","msg":"trace[759086703] transaction","detail":"{read_only:false; response_revision:16255; number_of_response:1; }","duration":"157.221495ms","start":"2026-10-16T00:42:22.106058Z","end":"2026-10-16T00:42:22.263279Z","steps":["trace[759086703] 'process raft request' (duration: 157.070162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:22.263556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.49398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-nwf9s\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-10-16T00:42:22.263611Z","caller":"traceutil/trace.go:171","msg":"trace[47507229] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-nwf9s; range_end:; response_count:1; response_revision:16255; }","duration":"133.608513ms","start":"2026-10-16T00:42:22.12999Z","end":"2026-10-16T00:42:22.263599Z","steps":["trace[47507229] 'agreement among raft nodes before linearized reading' (duration: 133.387898ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:22.263259Z","caller":"traceutil/trace.go:171","msg":"trace[1270921067] linearizableReadLoop","detail":"{readStateIndex:16820; appliedIndex:16816; }","duration":"133.188543ms","start":"2026-10-16T00:42:22.130042Z","end":"2026-10-16T00:42:22.263231Z","steps":["trace[1270921067] 'read index received' (duration: 121.566006ms)","trace[1270921067] 'applied index is now lower than readState.Index' (duration: 11.621597ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:44:30.014694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14613} {"level":"info","ts":"2026-10-16T00:44:30.103417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14613,"took":"85.857897ms","hash":935323142,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:44:30.103506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":935323142,"revision":14613,"compact-revision":12608} {"level":"info","ts":"2026-10-16T00:47:55.673214Z","caller":"traceutil/trace.go:171","msg":"trace[1753551188] transaction","detail":"{read_only:false; response_revision:18714; number_of_response:1; }","duration":"181.453369ms","start":"2026-10-16T00:47:55.491696Z","end":"2026-10-16T00:47:55.673149Z","steps":["trace[1753551188] 'process raft request' (duration: 114.130518ms)","trace[1753551188] 'compare' (duration: 67.132807ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:55.673323Z","caller":"traceutil/trace.go:171","msg":"trace[190888700] transaction","detail":"{read_only:false; response_revision:18715; number_of_response:1; }","duration":"178.246547ms","start":"2026-10-16T00:47:55.495045Z","end":"2026-10-16T00:47:55.673291Z","steps":["trace[190888700] 'process raft request' (duration: 178.048363ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:57.750967Z","caller":"traceutil/trace.go:171","msg":"trace[1632068616] linearizableReadLoop","detail":"{readStateIndex:19379; appliedIndex:19378; }","duration":"383.81071ms","start":"2026-10-16T00:47:57.367108Z","end":"2026-10-16T00:47:57.750919Z","steps":["trace[1632068616] 'read index received' (duration: 383.503533ms)","trace[1632068616] 'applied index is now lower than readState.Index' (duration: 306.217µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:57.751399Z","caller":"traceutil/trace.go:171","msg":"trace[629786689] transaction","detail":"{read_only:false; response_revision:18730; number_of_response:1; }","duration":"443.772648ms","start":"2026-10-16T00:47:57.307588Z","end":"2026-10-16T00:47:57.75136Z","steps":["trace[629786689] 'process raft request' (duration: 442.98364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:57.7515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.365483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:47:57.75157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:57.307565Z","time spent":"443.922661ms","remote":"127.0.0.1:55766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417994,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:47:57.75161Z","caller":"traceutil/trace.go:171","msg":"trace[1762410780] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18730; }","duration":"384.506056ms","start":"2026-10-16T00:47:57.367048Z","end":"2026-10-16T00:47:57.751554Z","steps":["trace[1762410780] 'agreement among raft nodes before linearized reading' (duration: 384.334792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:57.751667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:57.367025Z","time spent":"384.629838ms","remote":"127.0.0.1:59704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-16T00:47:57.777764Z","caller":"traceutil/trace.go:171","msg":"trace[1314451587] transaction","detail":"{read_only:false; response_revision:18731; number_of_response:1; }","duration":"219.680975ms","start":"2026-10-16T00:47:57.558056Z","end":"2026-10-16T00:47:57.777737Z","steps":["trace[1314451587] 'process raft request' (duration: 219.147463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:57.778611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.044599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:47:57.778655Z","caller":"traceutil/trace.go:171","msg":"trace[1439341257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18733; }","duration":"330.148272ms","start":"2026-10-16T00:47:57.448495Z","end":"2026-10-16T00:47:57.778643Z","steps":["trace[1439341257] 'agreement among raft nodes before linearized reading' (duration: 330.05167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:57.778682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:57.448472Z","time spent":"330.201312ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:47:58.105968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.464688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:47:58.106088Z","caller":"traceutil/trace.go:171","msg":"trace[48451822] linearizableReadLoop","detail":"{readStateIndex:19383; appliedIndex:19382; }","duration":"318.481263ms","start":"2026-10-16T00:47:57.787586Z","end":"2026-10-16T00:47:58.106068Z","steps":["trace[48451822] 'read index received' (duration: 183.814631ms)","trace[48451822] 'applied index is now lower than readState.Index' (duration: 134.665472ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:58.106206Z","caller":"traceutil/trace.go:171","msg":"trace[1563312042] transaction","detail":"{read_only:false; response_revision:18734; number_of_response:1; }","duration":"322.882921ms","start":"2026-10-16T00:47:57.783311Z","end":"2026-10-16T00:47:58.106194Z","steps":["trace[1563312042] 'process raft request' (duration: 188.095146ms)","trace[1563312042] 'compare' (duration: 134.324325ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:47:58.106277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:57.783282Z","time spent":"322.954583ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:47:58.106592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.965324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-16T00:47:58.106687Z","caller":"traceutil/trace.go:171","msg":"trace[1099292203] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18734; }","duration":"319.096377ms","start":"2026-10-16T00:47:57.787563Z","end":"2026-10-16T00:47:58.10666Z","steps":["trace[1099292203] 'agreement among raft nodes before linearized reading' (duration: 318.813091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:58.106739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:57.787552Z","time spent":"319.175179ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-16T00:48:01.824695Z","caller":"traceutil/trace.go:171","msg":"trace[1660814762] transaction","detail":"{read_only:false; response_revision:18833; number_of_response:1; }","duration":"103.499882ms","start":"2026-10-16T00:48:01.72116Z","end":"2026-10-16T00:48:01.82466Z","steps":["trace[1660814762] 'process raft request' (duration: 103.296997ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:01.879995Z","caller":"traceutil/trace.go:171","msg":"trace[163780825] transaction","detail":"{read_only:false; response_revision:18834; number_of_response:1; }","duration":"137.869014ms","start":"2026-10-16T00:48:01.742098Z","end":"2026-10-16T00:48:01.879967Z","steps":["trace[163780825] 'process raft request' (duration: 123.842213ms)","trace[163780825] 'compare' (duration: 13.864387ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:48:01.88034Z","caller":"traceutil/trace.go:171","msg":"trace[1033754104] transaction","detail":"{read_only:false; response_revision:18835; number_of_response:1; }","duration":"129.237242ms","start":"2026-10-16T00:48:01.751068Z","end":"2026-10-16T00:48:01.880305Z","steps":["trace[1033754104] 'process raft request' (duration: 128.864944ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:01.880674Z","caller":"traceutil/trace.go:171","msg":"trace[2105407825] linearizableReadLoop","detail":"{readStateIndex:19484; appliedIndex:19482; }","duration":"136.555884ms","start":"2026-10-16T00:48:01.744081Z","end":"2026-10-16T00:48:01.880637Z","steps":["trace[2105407825] 'read index received' (duration: 80.36122ms)","trace[2105407825] 'applied index is now lower than readState.Index' (duration: 56.193224ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:48:01.880879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.770339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:48:01.880959Z","caller":"traceutil/trace.go:171","msg":"trace[805944784] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:18838; }","duration":"136.911032ms","start":"2026-10-16T00:48:01.744034Z","end":"2026-10-16T00:48:01.880945Z","steps":["trace[805944784] 'agreement among raft nodes before linearized reading' (duration: 136.781199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:48:01.881044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.296846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-10-16T00:48:01.88116Z","caller":"traceutil/trace.go:171","msg":"trace[25432982] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:18838; }","duration":"130.423308ms","start":"2026-10-16T00:48:01.750688Z","end":"2026-10-16T00:48:01.881112Z","steps":["trace[25432982] 'agreement among raft nodes before linearized reading' (duration: 130.151872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:48:01.881535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.481247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9502"} {"level":"info","ts":"2026-10-16T00:48:01.881607Z","caller":"traceutil/trace.go:171","msg":"trace[1430329013] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18838; }","duration":"129.578999ms","start":"2026-10-16T00:48:01.752009Z","end":"2026-10-16T00:48:01.881587Z","steps":["trace[1430329013] 'agreement among raft nodes before linearized reading' (duration: 129.198901ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:43.583707Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:48:43.613155Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:48:43.613253Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-10-16T00:49:08.655113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.003231ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-16T00:49:30.027032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17138} {"level":"info","ts":"2026-10-16T00:49:30.131577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17138,"took":"100.992367ms","hash":3648420990,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":34385920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T00:49:30.13165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3648420990,"revision":17138,"compact-revision":14613} {"level":"info","ts":"2026-10-16T00:53:33.383576Z","caller":"traceutil/trace.go:171","msg":"trace[651325957] transaction","detail":"{read_only:false; response_revision:21383; number_of_response:1; }","duration":"284.523651ms","start":"2026-10-16T00:53:33.099028Z","end":"2026-10-16T00:53:33.383552Z","steps":["trace[651325957] 'process raft request' (duration: 284.396298ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:33.396964Z","caller":"traceutil/trace.go:171","msg":"trace[1023801391] transaction","detail":"{read_only:false; response_revision:21385; number_of_response:1; }","duration":"292.866426ms","start":"2026-10-16T00:53:33.104081Z","end":"2026-10-16T00:53:33.396947Z","steps":["trace[1023801391] 'process raft request' (duration: 292.801464ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:33.397122Z","caller":"traceutil/trace.go:171","msg":"trace[1060945708] transaction","detail":"{read_only:false; response_revision:21384; number_of_response:1; }","duration":"296.311192ms","start":"2026-10-16T00:53:33.10079Z","end":"2026-10-16T00:53:33.397101Z","steps":["trace[1060945708] 'process raft request' (duration: 296.011425ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:43.803957Z","caller":"traceutil/trace.go:171","msg":"trace[1033787609] transaction","detail":"{read_only:false; response_revision:21436; number_of_response:1; }","duration":"178.897842ms","start":"2026-10-16T00:53:43.625034Z","end":"2026-10-16T00:53:43.803932Z","steps":["trace[1033787609] 'process raft request' (duration: 178.754679ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:49.607402Z","caller":"traceutil/trace.go:171","msg":"trace[629050230] transaction","detail":"{read_only:false; response_revision:21468; number_of_response:1; }","duration":"170.738981ms","start":"2026-10-16T00:53:49.436639Z","end":"2026-10-16T00:53:49.607378Z","steps":["trace[629050230] 'process raft request' (duration: 170.566447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:49.607583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.190289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:53:49.607686Z","caller":"traceutil/trace.go:171","msg":"trace[86278515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21468; }","duration":"161.330713ms","start":"2026-10-16T00:53:49.446334Z","end":"2026-10-16T00:53:49.607665Z","steps":["trace[86278515] 'agreement among raft nodes before linearized reading' (duration: 161.135349ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:49.607724Z","caller":"traceutil/trace.go:171","msg":"trace[783387912] linearizableReadLoop","detail":"{readStateIndex:22205; appliedIndex:22204; }","duration":"161.335183ms","start":"2026-10-16T00:53:49.446375Z","end":"2026-10-16T00:53:49.60771Z","steps":["trace[783387912] 'read index received' (duration: 160.787141ms)","trace[783387912] 'applied index is now lower than readState.Index' (duration: 169.644µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:53:49.859757Z","caller":"traceutil/trace.go:171","msg":"trace[1378624311] transaction","detail":"{read_only:false; response_revision:21469; number_of_response:1; }","duration":"405.138831ms","start":"2026-10-16T00:53:49.454589Z","end":"2026-10-16T00:53:49.859728Z","steps":["trace[1378624311] 'process raft request' (duration: 404.896116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:49.859931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:49.454581Z","time spent":"405.258324ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:53:49.859946Z","caller":"traceutil/trace.go:171","msg":"trace[2091376723] linearizableReadLoop","detail":"{readStateIndex:22207; appliedIndex:22205; }","duration":"251.154451ms","start":"2026-10-16T00:53:49.608762Z","end":"2026-10-16T00:53:49.859916Z","steps":["trace[2091376723] 'read index received' (duration: 250.61704ms)","trace[2091376723] 'applied index is now lower than readState.Index' (duration: 534.601µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:53:49.859988Z","caller":"traceutil/trace.go:171","msg":"trace[859317931] transaction","detail":"{read_only:false; response_revision:21470; number_of_response:1; }","duration":"404.185609ms","start":"2026-10-16T00:53:49.455779Z","end":"2026-10-16T00:53:49.859964Z","steps":["trace[859317931] 'process raft request' (duration: 403.897833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:49.860082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:49.455754Z","time spent":"404.279412ms","remote":"127.0.0.1:59550","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-10-16T00:53:49.860094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.317485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:53:49.860147Z","caller":"traceutil/trace.go:171","msg":"trace[442892515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21470; }","duration":"251.414277ms","start":"2026-10-16T00:53:49.608711Z","end":"2026-10-16T00:53:49.860126Z","steps":["trace[442892515] 'agreement among raft nodes before linearized reading' (duration: 251.311315ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:50.256611Z","caller":"traceutil/trace.go:171","msg":"trace[628879019] transaction","detail":"{read_only:false; response_revision:21471; number_of_response:1; }","duration":"180.945867ms","start":"2026-10-16T00:53:50.07564Z","end":"2026-10-16T00:53:50.256586Z","steps":["trace[628879019] 'process raft request' (duration: 180.773304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:50.472561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.480902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-16T00:53:50.472625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.777451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-16T00:53:50.472653Z","caller":"traceutil/trace.go:171","msg":"trace[1659716742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21471; }","duration":"187.614895ms","start":"2026-10-16T00:53:50.285014Z","end":"2026-10-16T00:53:50.472629Z","steps":["trace[1659716742] 'range keys from in-memory index tree' (duration: 187.284337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:50.47282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.16102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T00:53:50.473037Z","caller":"traceutil/trace.go:171","msg":"trace[368674061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21471; }","duration":"180.61674ms","start":"2026-10-16T00:53:50.292401Z","end":"2026-10-16T00:53:50.473018Z","steps":["trace[368674061] 'range keys from in-memory index tree' (duration: 179.947205ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:50.472769Z","caller":"traceutil/trace.go:171","msg":"trace[632753162] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:21471; }","duration":"137.830513ms","start":"2026-10-16T00:53:50.33483Z","end":"2026-10-16T00:53:50.472661Z","steps":["trace[632753162] 'range keys from in-memory index tree' (duration: 137.612298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:50.881626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.429951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:53:50.881843Z","caller":"traceutil/trace.go:171","msg":"trace[1902276433] transaction","detail":"{read_only:false; response_revision:21474; number_of_response:1; }","duration":"332.441122ms","start":"2026-10-16T00:53:50.549381Z","end":"2026-10-16T00:53:50.881822Z","steps":["trace[1902276433] 'process raft request' (duration: 332.3563ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:50.88186Z","caller":"traceutil/trace.go:171","msg":"trace[395873268] transaction","detail":"{read_only:false; response_revision:21473; number_of_response:1; }","duration":"404.011667ms","start":"2026-10-16T00:53:50.477811Z","end":"2026-10-16T00:53:50.881823Z","steps":["trace[395873268] 'process raft request' (duration: 188.062224ms)","trace[395873268] 'compare' (duration: 214.570902ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:53:50.881939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:50.549365Z","time spent":"332.525304ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:53:50.882001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:50.477772Z","time spent":"404.15022ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:53:50.882134Z","caller":"traceutil/trace.go:171","msg":"trace[1174150931] linearizableReadLoop","detail":"{readStateIndex:22210; appliedIndex:22209; }","duration":"379.540955ms","start":"2026-10-16T00:53:50.50258Z","end":"2026-10-16T00:53:50.882121Z","steps":["trace[1174150931] 'read index received' (duration: 163.328057ms)","trace[1174150931] 'applied index is now lower than readState.Index' (duration: 216.211278ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:53:50.882359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.7835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T00:53:50.882424Z","caller":"traceutil/trace.go:171","msg":"trace[1370586374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21474; }","duration":"379.890003ms","start":"2026-10-16T00:53:50.502518Z","end":"2026-10-16T00:53:50.882408Z","steps":["trace[1370586374] 'agreement among raft nodes before linearized reading' (duration: 379.685718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:50.882467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:50.502501Z","time spent":"379.954765ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:53:50.882369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.865696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T00:53:50.882604Z","caller":"traceutil/trace.go:171","msg":"trace[181326995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21474; }","duration":"125.12241ms","start":"2026-10-16T00:53:50.757454Z","end":"2026-10-16T00:53:50.882577Z","steps":["trace[181326995] 'agreement among raft nodes before linearized reading' (duration: 124.775443ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:30.034131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19708} {"level":"info","ts":"2026-10-16T00:54:30.199102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19708,"took":"161.637869ms","hash":1470158402,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T00:54:30.199191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1470158402,"revision":19708,"compact-revision":17138} {"level":"warn","ts":"2026-10-16T00:57:54.21815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.744535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:57:54.218408Z","caller":"traceutil/trace.go:171","msg":"trace[354125269] transaction","detail":"{read_only:false; response_revision:23997; number_of_response:1; }","duration":"407.769644ms","start":"2026-10-16T00:57:53.810617Z","end":"2026-10-16T00:57:54.218386Z","steps":["trace[354125269] 'process raft request' (duration: 407.682862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:54.218501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:53.8106Z","time spent":"407.857466ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:57:54.218757Z","caller":"traceutil/trace.go:171","msg":"trace[1806236762] transaction","detail":"{read_only:false; response_revision:23996; number_of_response:1; }","duration":"433.907311ms","start":"2026-10-16T00:57:53.784811Z","end":"2026-10-16T00:57:54.218718Z","steps":["trace[1806236762] 'process raft request' (duration: 168.48142ms)","trace[1806236762] 'compare' (duration: 264.634083ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:57:54.218934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:57:53.784796Z","time spent":"434.038864ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:59:30.040357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21908} {"level":"info","ts":"2026-10-16T00:59:30.159762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21908,"took":"115.277346ms","hash":2095998078,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":43560960,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-16T00:59:30.159821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2095998078,"revision":21908,"compact-revision":19708} {"level":"info","ts":"2026-10-16T00:59:31.885197Z","caller":"traceutil/trace.go:171","msg":"trace[1597156119] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"141.371811ms","start":"2026-10-16T00:59:31.743799Z","end":"2026-10-16T00:59:31.885171Z","steps":["trace[1597156119] 'process raft request' (duration: 141.200357ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:00:57.223489Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006845.wal"} {"level":"info","ts":"2026-10-16T01:01:32.027599Z","caller":"traceutil/trace.go:171","msg":"trace[2081671007] linearizableReadLoop","detail":"{readStateIndex:27031; appliedIndex:27028; }","duration":"579.469533ms","start":"2026-10-16T01:01:31.448101Z","end":"2026-10-16T01:01:32.02757Z","steps":["trace[2081671007] 'read index received' (duration: 19.792957ms)","trace[2081671007] 'applied index is now lower than readState.Index' (duration: 559.675436ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:01:32.027666Z","caller":"traceutil/trace.go:171","msg":"trace[1344401028] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"600.376746ms","start":"2026-10-16T01:01:31.427253Z","end":"2026-10-16T01:01:32.02763Z","steps":["trace[1344401028] 'process raft request' (duration: 599.887845ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:01:32.027781Z","caller":"traceutil/trace.go:171","msg":"trace[1589494584] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"600.424607ms","start":"2026-10-16T01:01:31.427338Z","end":"2026-10-16T01:01:32.027763Z","steps":["trace[1589494584] 'process raft request' (duration: 600.170671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.027864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.42722Z","time spent":"600.54048ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T01:01:32.027859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.74419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T01:01:32.027919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.427329Z","time spent":"600.500108ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:01:32.027957Z","caller":"traceutil/trace.go:171","msg":"trace[919388996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26167; }","duration":"579.893232ms","start":"2026-10-16T01:01:31.448045Z","end":"2026-10-16T01:01:32.027939Z","steps":["trace[919388996] 'agreement among raft nodes before linearized reading' (duration: 579.729979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.448025Z","time spent":"579.966465ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T01:01:32.45188Z","caller":"traceutil/trace.go:171","msg":"trace[568473922] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"835.433768ms","start":"2026-10-16T01:01:31.616432Z","end":"2026-10-16T01:01:32.451866Z","steps":["trace[568473922] 'process raft request' (duration: 835.384647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.451973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.616426Z","time spent":"835.49838ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:01:32.452088Z","caller":"traceutil/trace.go:171","msg":"trace[596683399] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"836.778477ms","start":"2026-10-16T01:01:31.615304Z","end":"2026-10-16T01:01:32.452082Z","steps":["trace[596683399] 'process raft request' (duration: 834.840704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.452121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.615282Z","time spent":"836.822149ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:01:32.4521Z","caller":"traceutil/trace.go:171","msg":"trace[770260343] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"673.657405ms","start":"2026-10-16T01:01:31.778423Z","end":"2026-10-16T01:01:32.45208Z","steps":["trace[770260343] 'process raft request' (duration: 673.42115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.452255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.778392Z","time spent":"673.770858ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:01:32.452525Z","caller":"traceutil/trace.go:171","msg":"trace[1419322365] linearizableReadLoop","detail":"{readStateIndex:27035; appliedIndex:27031; }","duration":"424.814272ms","start":"2026-10-16T01:01:32.027704Z","end":"2026-10-16T01:01:32.452518Z","steps":["trace[1419322365] 'read index received' (duration: 422.386748ms)","trace[1419322365] 'applied index is now lower than readState.Index' (duration: 2.426784ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:01:32.452593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.79905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:01:32.45261Z","caller":"traceutil/trace.go:171","msg":"trace[323075267] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:26171; }","duration":"520.857852ms","start":"2026-10-16T01:01:31.931746Z","end":"2026-10-16T01:01:32.452604Z","steps":["trace[323075267] 'agreement among raft nodes before linearized reading' (duration: 520.825211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.452624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.931722Z","time spent":"520.898022ms","remote":"127.0.0.1:51518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"warn","ts":"2026-10-16T01:01:32.45272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.534045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:01:32.452697Z","caller":"traceutil/trace.go:171","msg":"trace[520837435] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"468.020805ms","start":"2026-10-16T01:01:31.984621Z","end":"2026-10-16T01:01:32.452642Z","steps":["trace[520837435] 'process raft request' (duration: 467.388681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.452759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.693957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-16T01:01:32.452752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.588189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-16T01:01:32.452849Z","caller":"traceutil/trace.go:171","msg":"trace[1155054024] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26171; }","duration":"483.740303ms","start":"2026-10-16T01:01:31.969091Z","end":"2026-10-16T01:01:32.452831Z","steps":["trace[1155054024] 'agreement among raft nodes before linearized reading' (duration: 483.472997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.452697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.734197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T01:01:32.452928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.984599Z","time spent":"468.192249ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:01:32.452841Z","caller":"traceutil/trace.go:171","msg":"trace[4666752] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26171; }","duration":"116.83483ms","start":"2026-10-16T01:01:32.335984Z","end":"2026-10-16T01:01:32.452819Z","steps":["trace[4666752] 'agreement among raft nodes before linearized reading' (duration: 116.656426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.45294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.866543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-16T01:01:32.453129Z","caller":"traceutil/trace.go:171","msg":"trace[1216031083] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26171; }","duration":"123.15721ms","start":"2026-10-16T01:01:32.329946Z","end":"2026-10-16T01:01:32.453103Z","steps":["trace[1216031083] 'agreement among raft nodes before linearized reading' (duration: 122.69883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.452973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.969071Z","time spent":"483.882535ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-10-16T01:01:32.452984Z","caller":"traceutil/trace.go:171","msg":"trace[1803357610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26171; }","duration":"424.019354ms","start":"2026-10-16T01:01:32.028908Z","end":"2026-10-16T01:01:32.452928Z","steps":["trace[1803357610] 'agreement among raft nodes before linearized reading' (duration: 423.709937ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:01:32.452787Z","caller":"traceutil/trace.go:171","msg":"trace[930014345] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26171; }","duration":"722.645817ms","start":"2026-10-16T01:01:31.730121Z","end":"2026-10-16T01:01:32.452767Z","steps":["trace[930014345] 'agreement among raft nodes before linearized reading' (duration: 722.541305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:01:32.453346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:32.028878Z","time spent":"424.414793ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T01:01:32.453393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:01:31.730098Z","time spent":"723.26728ms","remote":"127.0.0.1:44906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-10-16T01:01:32.728184Z","caller":"traceutil/trace.go:171","msg":"trace[1885704827] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"128.763053ms","start":"2026-10-16T01:01:32.599391Z","end":"2026-10-16T01:01:32.728154Z","steps":["trace[1885704827] 'process raft request' (duration: 118.462065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:33.652102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.007326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T01:02:33.652405Z","caller":"traceutil/trace.go:171","msg":"trace[603380593] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"298.683935ms","start":"2026-10-16T01:02:33.35369Z","end":"2026-10-16T01:02:33.652374Z","steps":["trace[603380593] 'process raft request' (duration: 117.127566ms)","trace[603380593] 'compare' (duration: 180.868394ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:02:33.652224Z","caller":"traceutil/trace.go:171","msg":"trace[1398746557] linearizableReadLoop","detail":"{readStateIndex:27595; appliedIndex:27594; }","duration":"155.111915ms","start":"2026-10-16T01:02:33.497096Z","end":"2026-10-16T01:02:33.652208Z","steps":["trace[1398746557] 'read index received' (duration: 47.991µs)","trace[1398746557] 'applied index is now lower than readState.Index' (duration: 155.062744ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:02:33.65274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.629906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.14\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-16T01:02:33.652827Z","caller":"traceutil/trace.go:171","msg":"trace[2082268959] range","detail":"{range_begin:/registry/masterleases/162.253.55.14; range_end:; response_count:1; response_revision:26719; }","duration":"155.766089ms","start":"2026-10-16T01:02:33.49704Z","end":"2026-10-16T01:02:33.652806Z","steps":["trace[2082268959] 'agreement among raft nodes before linearized reading' (duration: 155.460773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.021345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.488101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T01:02:34.021451Z","caller":"traceutil/trace.go:171","msg":"trace[1532409917] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"377.732311ms","start":"2026-10-16T01:02:33.643698Z","end":"2026-10-16T01:02:34.02143Z","steps":["trace[1532409917] 'process raft request' (duration: 223.066256ms)","trace[1532409917] 'compare' (duration: 154.009811ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:02:34.021508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:33.64367Z","time spent":"377.810042ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:02:34.025095Z","caller":"traceutil/trace.go:171","msg":"trace[52046539] linearizableReadLoop","detail":"{readStateIndex:27597; appliedIndex:27595; }","duration":"249.580891ms","start":"2026-10-16T01:02:33.775489Z","end":"2026-10-16T01:02:34.02507Z","steps":["trace[52046539] 'read index received' (duration: 91.264876ms)","trace[52046539] 'applied index is now lower than readState.Index' (duration: 158.314605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:02:34.025116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:33.653873Z","time spent":"371.234857ms","remote":"127.0.0.1:59358","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-16T01:02:34.025331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.829867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T01:02:34.025374Z","caller":"traceutil/trace.go:171","msg":"trace[908979411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26720; }","duration":"249.922579ms","start":"2026-10-16T01:02:33.77544Z","end":"2026-10-16T01:02:34.025362Z","steps":["trace[908979411] 'agreement among raft nodes before linearized reading' (duration: 249.723314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.43775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.957272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T01:02:34.43792Z","caller":"traceutil/trace.go:171","msg":"trace[1022740912] linearizableReadLoop","detail":"{readStateIndex:27599; appliedIndex:27597; }","duration":"100.186142ms","start":"2026-10-16T01:02:34.337717Z","end":"2026-10-16T01:02:34.437904Z","steps":["trace[1022740912] 'read index received' (duration: 82.021441ms)","trace[1022740912] 'applied index is now lower than readState.Index' (duration: 18.163711ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:02:34.438005Z","caller":"traceutil/trace.go:171","msg":"trace[250947901] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"408.921599ms","start":"2026-10-16T01:02:34.029074Z","end":"2026-10-16T01:02:34.437995Z","steps":["trace[250947901] 'process raft request' (duration: 408.774226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.438058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:34.029037Z","time spent":"408.990291ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:02:34.438194Z","caller":"traceutil/trace.go:171","msg":"trace[441948073] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"411.65525ms","start":"2026-10-16T01:02:34.026504Z","end":"2026-10-16T01:02:34.438159Z","steps":["trace[441948073] 'process raft request' (duration: 198.211287ms)","trace[441948073] 'compare' (duration: 212.786608ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:02:34.43835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.628382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-16T01:02:34.438359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:34.026484Z","time spent":"411.791652ms","remote":"127.0.0.1:59358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:02:34.438387Z","caller":"traceutil/trace.go:171","msg":"trace[830645975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26722; }","duration":"100.700084ms","start":"2026-10-16T01:02:34.337677Z","end":"2026-10-16T01:02:34.438377Z","steps":["trace[830645975] 'agreement among raft nodes before linearized reading' (duration: 100.575831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.82035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.2508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T01:02:34.820576Z","caller":"traceutil/trace.go:171","msg":"trace[1203340336] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"377.913365ms","start":"2026-10-16T01:02:34.442633Z","end":"2026-10-16T01:02:34.820547Z","steps":["trace[1203340336] 'process raft request' (duration: 106.383109ms)","trace[1203340336] 'compare' (duration: 270.834381ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:02:34.820633Z","caller":"traceutil/trace.go:171","msg":"trace[571103529] linearizableReadLoop","detail":"{readStateIndex:27601; appliedIndex:27599; }","duration":"376.827141ms","start":"2026-10-16T01:02:34.443794Z","end":"2026-10-16T01:02:34.820622Z","steps":["trace[571103529] 'read index received' (duration: 105.304155ms)","trace[571103529] 'applied index is now lower than readState.Index' (duration: 271.521976ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T01:02:34.82068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:34.442614Z","time spent":"378.016397ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:02:34.820596Z","caller":"traceutil/trace.go:171","msg":"trace[2118047426] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"112.074835ms","start":"2026-10-16T01:02:34.708508Z","end":"2026-10-16T01:02:34.820583Z","steps":["trace[2118047426] 'process raft request' (duration: 112.030194ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:34.820697Z","caller":"traceutil/trace.go:171","msg":"trace[490003990] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"377.978296ms","start":"2026-10-16T01:02:34.44269Z","end":"2026-10-16T01:02:34.820668Z","steps":["trace[490003990] 'process raft request' (duration: 377.759981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.820793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.001584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T01:02:34.821114Z","caller":"traceutil/trace.go:171","msg":"trace[1737981595] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26725; }","duration":"377.338042ms","start":"2026-10-16T01:02:34.443763Z","end":"2026-10-16T01:02:34.821102Z","steps":["trace[1737981595] 'agreement among raft nodes before linearized reading' (duration: 376.913103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.821157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:34.443754Z","time spent":"377.391743ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-10-16T01:02:34.820948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:34.442664Z","time spent":"378.188731ms","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T01:02:34.823342Z","caller":"traceutil/trace.go:171","msg":"trace[1655388425] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"103.099167ms","start":"2026-10-16T01:02:34.720217Z","end":"2026-10-16T01:02:34.823316Z","steps":["trace[1655388425] 'process raft request' (duration: 102.966544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.823367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.620694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T01:02:34.823436Z","caller":"traceutil/trace.go:171","msg":"trace[1083966183] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"103.332342ms","start":"2026-10-16T01:02:34.720083Z","end":"2026-10-16T01:02:34.823416Z","steps":["trace[1083966183] 'process raft request' (duration: 102.990044ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:34.823456Z","caller":"traceutil/trace.go:171","msg":"trace[909339547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26728; }","duration":"375.738877ms","start":"2026-10-16T01:02:34.447693Z","end":"2026-10-16T01:02:34.823432Z","steps":["trace[909339547] 'agreement among raft nodes before linearized reading' (duration: 375.605764ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:34.823437Z","caller":"traceutil/trace.go:171","msg":"trace[2012236311] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"100.449958ms","start":"2026-10-16T01:02:34.722932Z","end":"2026-10-16T01:02:34.823382Z","steps":["trace[2012236311] 'process raft request' (duration: 100.287934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T01:02:34.823504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:02:34.447682Z","time spent":"375.812078ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T01:02:34.823578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.903763ms","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-10-16T01:02:34.82362Z","caller":"traceutil/trace.go:171","msg":"trace[1062660514] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26728; }","duration":"285.952434ms","start":"2026-10-16T01:02:34.537655Z","end":"2026-10-16T01:02:34.823607Z","steps":["trace[1062660514] 'agreement among raft nodes before linearized reading' (duration: 285.874592ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:55.153357Z","caller":"traceutil/trace.go:171","msg":"trace[1078727452] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"108.019375ms","start":"2026-10-16T01:02:55.045301Z","end":"2026-10-16T01:02:55.153321Z","steps":["trace[1078727452] 'process raft request' (duration: 107.730229ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:04:30.048331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24965} {"level":"info","ts":"2026-10-16T01:04:30.216677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24965,"took":"162.940367ms","hash":128962974,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":36220928,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T01:04:30.216749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":128962974,"revision":24965,"compact-revision":21908} {"level":"info","ts":"2026-10-16T01:06:31.397626Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:06:31.404718Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:06:31.404874Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:09:30.058094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27808} {"level":"info","ts":"2026-10-16T01:09:30.205207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27808,"took":"141.393591ms","hash":1726924878,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":34545664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T01:09:30.205291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1726924878,"revision":27808,"compact-revision":24965} {"level":"info","ts":"2026-10-16T01:14:30.066902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30473} {"level":"info","ts":"2026-10-16T01:14:30.219759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30473,"took":"147.72004ms","hash":2944542027,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":30736384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:14:30.219823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2944542027,"revision":30473,"compact-revision":27808} {"level":"info","ts":"2026-10-16T01:19:30.076631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32725} {"level":"info","ts":"2026-10-16T01:19:30.243427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32725,"took":"162.736311ms","hash":1280858888,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":30318592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T01:19:30.243496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1280858888,"revision":32725,"compact-revision":30473} {"level":"info","ts":"2026-10-16T01:24:30.08466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34953} {"level":"info","ts":"2026-10-16T01:24:30.245488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34953,"took":"155.752627ms","hash":3704961412,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:24:30.245555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3704961412,"revision":34953,"compact-revision":32725} {"level":"info","ts":"2026-10-16T01:27:49.756738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:27:49.763939Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:27:49.764054Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:29:30.091352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37249} {"level":"info","ts":"2026-10-16T01:29:30.224233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37249,"took":"130.003456ms","hash":635844363,"current-db-size-bytes":60829696,"current-db-size":"61 MB","current-db-size-in-use-bytes":32677888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:29:30.22432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":635844363,"revision":37249,"compact-revision":34953} {"level":"info","ts":"2026-10-16T01:34:30.099011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39525} {"level":"info","ts":"2026-10-16T01:34:30.242346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39525,"took":"139.043426ms","hash":4281547753,"current-db-size-bytes":61673472,"current-db-size":"62 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:34:30.242422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4281547753,"revision":39525,"compact-revision":37249}