{"level":"warn","ts":"2026-08-22T10:32:29.633422Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:32:29.633567Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-08-22T10:32:29.633662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:32:29.633674Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-22T10:32:29.633701Z","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-08-22T10:32:29.634503Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-08-22T10:32:29.634587Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-08-22T10:32:29.638113Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.255471ms"} {"level":"info","ts":"2026-08-22T10:32:29.644185Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-22T10:32:29.644262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:32:29.644286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-22T10:32:29.644299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:32:29.644313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-22T10:32:29.644354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-22T10:32:29.650616Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:32:29.652181Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:32:29.653259Z","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-08-22T10:32:29.656035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:32:29.658227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:32:29.657611Z","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-08-22T10:32:29.658726Z","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-08-22T10:32:29.658941Z","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-08-22T10:32:29.659699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-22T10:32:29.659847Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-22T10:32:29.6615Z","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-08-22T10:32:29.661515Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-22T10:32:29.661555Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-22T10:32:29.662413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:32:29.662462Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:32:30.145414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:32:30.14548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:32:30.145505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-22T10:32:30.145527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:32:30.145539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-22T10:32:30.14556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-22T10:32:30.145593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-22T10:32:30.146484Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:30.146733Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:32:30.146769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:32:30.146746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:32:30.147033Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:32:30.147108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:32:30.147553Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:30.147675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:30.147712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:30.150145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-08-22T10:32:30.150916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:33:13.582668Z","caller":"traceutil/trace.go:171","msg":"trace[1564881763] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"168.332624ms","start":"2026-08-22T10:33:13.4143Z","end":"2026-08-22T10:33:13.582632Z","steps":["trace[1564881763] 'process raft request' (duration: 167.031249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:14.176225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.936401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:33:14.176496Z","caller":"traceutil/trace.go:171","msg":"trace[848369210] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"111.782732ms","start":"2026-08-22T10:33:14.064692Z","end":"2026-08-22T10:33:14.176474Z","steps":["trace[848369210] 'read index received' (duration: 109.562µs)","trace[848369210] 'applied index is now lower than readState.Index' (duration: 111.67152ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:14.176563Z","caller":"traceutil/trace.go:171","msg":"trace[698472366] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"466.829158ms","start":"2026-08-22T10:33:13.709686Z","end":"2026-08-22T10:33:14.176515Z","steps":["trace[698472366] 'process raft request' (duration: 250.949229ms)","trace[698472366] 'compare' (duration: 214.504932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:14.176788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:13.709658Z","time spent":"467.029112ms","remote":"127.0.0.1:47938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:14.176888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.110708ms","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-08-22T10:33:14.176994Z","caller":"traceutil/trace.go:171","msg":"trace[104811986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"112.338062ms","start":"2026-08-22T10:33:14.064628Z","end":"2026-08-22T10:33:14.176966Z","steps":["trace[104811986] 'agreement among raft nodes before linearized reading' (duration: 111.907744ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:58.613077Z","caller":"traceutil/trace.go:171","msg":"trace[1845124468] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"199.991603ms","start":"2026-08-22T10:33:58.41306Z","end":"2026-08-22T10:33:58.613052Z","steps":["trace[1845124468] 'process raft request' (duration: 199.82044ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:21.982863Z","caller":"traceutil/trace.go:171","msg":"trace[2049353885] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"120.871713ms","start":"2026-08-22T10:34:21.861954Z","end":"2026-08-22T10:34:21.982825Z","steps":["trace[2049353885] 'process raft request' (duration: 120.680429ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:46.0564Z","caller":"traceutil/trace.go:171","msg":"trace[2094801733] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"173.413674ms","start":"2026-08-22T10:34:45.882932Z","end":"2026-08-22T10:34:46.056346Z","steps":["trace[2094801733] 'process raft request' (duration: 173.121848ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:46.110698Z","caller":"traceutil/trace.go:171","msg":"trace[1470176790] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"188.837135ms","start":"2026-08-22T10:34:45.921807Z","end":"2026-08-22T10:34:46.110644Z","steps":["trace[1470176790] 'read index received' (duration: 134.551888ms)","trace[1470176790] 'applied index is now lower than readState.Index' (duration: 54.283897ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:34:46.110834Z","caller":"traceutil/trace.go:171","msg":"trace[1489143853] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"226.867696ms","start":"2026-08-22T10:34:45.883924Z","end":"2026-08-22T10:34:46.110792Z","steps":["trace[1489143853] 'process raft request' (duration: 222.194858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:46.110991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.155901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54a39d0e-2d8b-46fc-bd5e-ff2ea6522a7f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T10:34:46.11107Z","caller":"traceutil/trace.go:171","msg":"trace[1763419686] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54a39d0e-2d8b-46fc-bd5e-ff2ea6522a7f; range_end:; response_count:1; response_revision:1875; }","duration":"189.297035ms","start":"2026-08-22T10:34:45.921756Z","end":"2026-08-22T10:34:46.111053Z","steps":["trace[1763419686] 'agreement among raft nodes before linearized reading' (duration: 189.029949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:46.120353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.62461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54a39d0e-2d8b-46fc-bd5e-ff2ea6522a7f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T10:34:46.120439Z","caller":"traceutil/trace.go:171","msg":"trace[1616756276] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54a39d0e-2d8b-46fc-bd5e-ff2ea6522a7f; range_end:; response_count:1; response_revision:1876; }","duration":"181.795423ms","start":"2026-08-22T10:34:45.938623Z","end":"2026-08-22T10:34:46.120418Z","steps":["trace[1616756276] 'agreement among raft nodes before linearized reading' (duration: 181.513498ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:46.513849Z","caller":"traceutil/trace.go:171","msg":"trace[1528989359] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"305.995104ms","start":"2026-08-22T10:34:46.207815Z","end":"2026-08-22T10:34:46.51381Z","steps":["trace[1528989359] 'process raft request' (duration: 259.519634ms)","trace[1528989359] 'compare' (duration: 46.280266ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:34:46.514366Z","caller":"traceutil/trace.go:171","msg":"trace[2085066370] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"305.185948ms","start":"2026-08-22T10:34:46.209152Z","end":"2026-08-22T10:34:46.514338Z","steps":["trace[2085066370] 'process raft request' (duration: 305.089707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:46.514527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:46.209131Z","time spent":"305.308831ms","remote":"127.0.0.1:47552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:34:46.514573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:46.20778Z","time spent":"306.163487ms","remote":"127.0.0.1:47552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:46.514819Z","caller":"traceutil/trace.go:171","msg":"trace[1288233454] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"305.633837ms","start":"2026-08-22T10:34:46.209175Z","end":"2026-08-22T10:34:46.514809Z","steps":["trace[1288233454] 'process raft request' (duration: 305.107456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:46.514886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:46.209157Z","time spent":"305.698309ms","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:46.514374Z","caller":"traceutil/trace.go:171","msg":"trace[16559593] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"305.701368ms","start":"2026-08-22T10:34:46.208633Z","end":"2026-08-22T10:34:46.514335Z","steps":["trace[16559593] 'process raft request' (duration: 305.505445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:46.515326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:46.208582Z","time spent":"306.634457ms","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:30.549573Z","caller":"traceutil/trace.go:171","msg":"trace[1595427314] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"135.626969ms","start":"2026-08-22T10:35:30.41388Z","end":"2026-08-22T10:35:30.549507Z","steps":["trace[1595427314] 'process raft request' (duration: 115.786434ms)","trace[1595427314] 'compare' (duration: 19.639401ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:36.546441Z","caller":"traceutil/trace.go:171","msg":"trace[1925635891] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"126.081057ms","start":"2026-08-22T10:35:36.420325Z","end":"2026-08-22T10:35:36.546406Z","steps":["trace[1925635891] 'process raft request' (duration: 125.910644ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:36.721126Z","caller":"traceutil/trace.go:171","msg":"trace[1855237057] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"130.916179ms","start":"2026-08-22T10:35:36.590177Z","end":"2026-08-22T10:35:36.721093Z","steps":["trace[1855237057] 'read index received' (duration: 113.823636ms)","trace[1855237057] 'applied index is now lower than readState.Index' (duration: 17.091143ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:36.7212Z","caller":"traceutil/trace.go:171","msg":"trace[51235716] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"151.010159ms","start":"2026-08-22T10:35:36.570146Z","end":"2026-08-22T10:35:36.721156Z","steps":["trace[51235716] 'process raft request' (duration: 133.809944ms)","trace[51235716] 'compare' (duration: 17.022392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:36.721352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.158204ms","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-08-22T10:35:36.721419Z","caller":"traceutil/trace.go:171","msg":"trace[379215480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2202; }","duration":"131.281436ms","start":"2026-08-22T10:35:36.59012Z","end":"2026-08-22T10:35:36.721402Z","steps":["trace[379215480] 'agreement among raft nodes before linearized reading' (duration: 131.084872ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:36.845565Z","caller":"traceutil/trace.go:171","msg":"trace[175183769] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"118.83927ms","start":"2026-08-22T10:35:36.726698Z","end":"2026-08-22T10:35:36.845537Z","steps":["trace[175183769] 'process raft request' (duration: 118.155368ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:23.749776Z","caller":"traceutil/trace.go:171","msg":"trace[765985913] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2732; }","duration":"111.917879ms","start":"2026-08-22T10:36:23.637829Z","end":"2026-08-22T10:36:23.749747Z","steps":["trace[765985913] 'read index received' (duration: 111.750425ms)","trace[765985913] 'applied index is now lower than readState.Index' (duration: 166.324µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:23.750216Z","caller":"traceutil/trace.go:171","msg":"trace[741572087] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"230.055277ms","start":"2026-08-22T10:36:23.520142Z","end":"2026-08-22T10:36:23.750198Z","steps":["trace[741572087] 'process raft request' (duration: 229.365293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.750577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.723294ms","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-08-22T10:36:23.75063Z","caller":"traceutil/trace.go:171","msg":"trace[987358737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2632; }","duration":"112.820516ms","start":"2026-08-22T10:36:23.637796Z","end":"2026-08-22T10:36:23.750616Z","steps":["trace[987358737] 'agreement among raft nodes before linearized reading' (duration: 112.649833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.750802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.853627ms","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-08-22T10:36:23.750848Z","caller":"traceutil/trace.go:171","msg":"trace[276125975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2632; }","duration":"112.940728ms","start":"2026-08-22T10:36:23.637894Z","end":"2026-08-22T10:36:23.750835Z","steps":["trace[276125975] 'agreement among raft nodes before linearized reading' (duration: 112.838646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:58.697188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.092275ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T10:39:39.033321Z","caller":"traceutil/trace.go:171","msg":"trace[68664775] transaction","detail":"{read_only:false; response_revision:3885; number_of_response:1; }","duration":"153.813712ms","start":"2026-08-22T10:39:38.879473Z","end":"2026-08-22T10:39:39.033286Z","steps":["trace[68664775] 'process raft request' (duration: 135.466743ms)","trace[68664775] 'compare' (duration: 18.128735ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:59.203473Z","caller":"traceutil/trace.go:171","msg":"trace[585892988] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"196.613674ms","start":"2026-08-22T10:40:59.006811Z","end":"2026-08-22T10:40:59.203425Z","steps":["trace[585892988] 'process raft request' (duration: 164.582186ms)","trace[585892988] 'compare' (duration: 31.522518ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:59.203621Z","caller":"traceutil/trace.go:171","msg":"trace[766249699] linearizableReadLoop","detail":"{readStateIndex:4999; appliedIndex:4996; }","duration":"194.464183ms","start":"2026-08-22T10:40:59.009126Z","end":"2026-08-22T10:40:59.20359Z","steps":["trace[766249699] 'read index received' (duration: 31.742273ms)","trace[766249699] 'applied index is now lower than readState.Index' (duration: 162.72115ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:59.20371Z","caller":"traceutil/trace.go:171","msg":"trace[864592743] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"194.557585ms","start":"2026-08-22T10:40:59.009136Z","end":"2026-08-22T10:40:59.203693Z","steps":["trace[864592743] 'process raft request' (duration: 194.255339ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:59.20394Z","caller":"traceutil/trace.go:171","msg":"trace[1656180179] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"194.215248ms","start":"2026-08-22T10:40:59.009713Z","end":"2026-08-22T10:40:59.203928Z","steps":["trace[1656180179] 'process raft request' (duration: 193.76278ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:59.203973Z","caller":"traceutil/trace.go:171","msg":"trace[1545897503] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"194.865441ms","start":"2026-08-22T10:40:59.009098Z","end":"2026-08-22T10:40:59.203963Z","steps":["trace[1545897503] 'process raft request' (duration: 194.152547ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:59.204185Z","caller":"traceutil/trace.go:171","msg":"trace[1758942235] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"192.440174ms","start":"2026-08-22T10:40:59.01173Z","end":"2026-08-22T10:40:59.204171Z","steps":["trace[1758942235] 'process raft request' (duration: 191.812612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:59.204427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.281378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-22T10:40:59.204478Z","caller":"traceutil/trace.go:171","msg":"trace[504353448] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4817; }","duration":"195.37467ms","start":"2026-08-22T10:40:59.009091Z","end":"2026-08-22T10:40:59.204466Z","steps":["trace[504353448] 'agreement among raft nodes before linearized reading' (duration: 195.210007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:59.204446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.201967ms","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:4737"} {"level":"info","ts":"2026-08-22T10:40:59.204744Z","caller":"traceutil/trace.go:171","msg":"trace[2015354756] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4817; }","duration":"195.507622ms","start":"2026-08-22T10:40:59.009226Z","end":"2026-08-22T10:40:59.204734Z","steps":["trace[2015354756] 'agreement among raft nodes before linearized reading' (duration: 195.150435ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:59.204793Z","caller":"traceutil/trace.go:171","msg":"trace[635892389] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"195.618354ms","start":"2026-08-22T10:40:59.009119Z","end":"2026-08-22T10:40:59.204738Z","steps":["trace[635892389] 'process raft request' (duration: 194.210068ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:00.134114Z","caller":"traceutil/trace.go:171","msg":"trace[1296965784] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"158.411868ms","start":"2026-08-22T10:40:59.975678Z","end":"2026-08-22T10:41:00.13409Z","steps":["trace[1296965784] 'process raft request' (duration: 158.124262ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:00.161275Z","caller":"traceutil/trace.go:171","msg":"trace[706083982] linearizableReadLoop","detail":"{readStateIndex:5024; appliedIndex:5022; }","duration":"146.033613ms","start":"2026-08-22T10:41:00.015212Z","end":"2026-08-22T10:41:00.161245Z","steps":["trace[706083982] 'read index received' (duration: 118.660043ms)","trace[706083982] 'applied index is now lower than readState.Index' (duration: 27.37166ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:00.161419Z","caller":"traceutil/trace.go:171","msg":"trace[1676773600] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"156.240767ms","start":"2026-08-22T10:41:00.005132Z","end":"2026-08-22T10:41:00.161373Z","steps":["trace[1676773600] 'process raft request' (duration: 155.880821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:00.161622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.3625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-251717d0-7662-4917-9569-2f98fac77ea4\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-08-22T10:41:00.161729Z","caller":"traceutil/trace.go:171","msg":"trace[2048083338] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-251717d0-7662-4917-9569-2f98fac77ea4; range_end:; response_count:1; response_revision:4839; }","duration":"146.506683ms","start":"2026-08-22T10:41:00.015205Z","end":"2026-08-22T10:41:00.161712Z","steps":["trace[2048083338] 'agreement among raft nodes before linearized reading' (duration: 146.206077ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:00.162594Z","caller":"traceutil/trace.go:171","msg":"trace[121366501] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4839; }","duration":"100.009009ms","start":"2026-08-22T10:41:00.062575Z","end":"2026-08-22T10:41:00.162584Z","steps":["trace[121366501] 'agreement among raft nodes before linearized reading' (duration: 98.888458ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:09.285065Z","caller":"traceutil/trace.go:171","msg":"trace[472016136] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"122.839494ms","start":"2026-08-22T10:41:09.162183Z","end":"2026-08-22T10:41:09.285023Z","steps":["trace[472016136] 'process raft request' (duration: 110.284095ms)","trace[472016136] 'compare' (duration: 12.255693ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:09.657855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.36491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T10:41:09.657958Z","caller":"traceutil/trace.go:171","msg":"trace[2089034950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5113; }","duration":"146.529013ms","start":"2026-08-22T10:41:09.511408Z","end":"2026-08-22T10:41:09.657937Z","steps":["trace[2089034950] 'range keys from in-memory index tree' (duration: 146.164196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:09.657941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.195555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:41:09.658079Z","caller":"traceutil/trace.go:171","msg":"trace[619649269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5113; }","duration":"268.372238ms","start":"2026-08-22T10:41:09.389646Z","end":"2026-08-22T10:41:09.658019Z","steps":["trace[619649269] 'range keys from in-memory index tree' (duration: 267.95409ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:10.317703Z","caller":"traceutil/trace.go:171","msg":"trace[2053031522] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"133.850472ms","start":"2026-08-22T10:41:10.183821Z","end":"2026-08-22T10:41:10.317671Z","steps":["trace[2053031522] 'process raft request' (duration: 133.646238ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:22.786182Z","caller":"traceutil/trace.go:171","msg":"trace[1301102325] linearizableReadLoop","detail":"{readStateIndex:5569; appliedIndex:5568; }","duration":"240.190392ms","start":"2026-08-22T10:41:22.54595Z","end":"2026-08-22T10:41:22.78614Z","steps":["trace[1301102325] 'read index received' (duration: 168.46719ms)","trace[1301102325] 'applied index is now lower than readState.Index' (duration: 71.721032ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:22.786587Z","caller":"traceutil/trace.go:171","msg":"trace[586614300] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"305.696417ms","start":"2026-08-22T10:41:22.480847Z","end":"2026-08-22T10:41:22.786544Z","steps":["trace[586614300] 'process raft request' (duration: 233.553368ms)","trace[586614300] 'compare' (duration: 71.554038ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:22.786777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:22.480829Z","time spent":"305.85182ms","remote":"127.0.0.1:47676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:41:22.786886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.849825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9zl9k\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:41:22.78698Z","caller":"traceutil/trace.go:171","msg":"trace[2041781175] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9zl9k; range_end:; response_count:1; response_revision:5366; }","duration":"241.054229ms","start":"2026-08-22T10:41:22.545907Z","end":"2026-08-22T10:41:22.786962Z","steps":["trace[2041781175] 'agreement among raft nodes before linearized reading' (duration: 240.528238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:22.841276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.008301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T10:41:22.841354Z","caller":"traceutil/trace.go:171","msg":"trace[1893275296] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5367; }","duration":"149.157112ms","start":"2026-08-22T10:41:22.692182Z","end":"2026-08-22T10:41:22.841339Z","steps":["trace[1893275296] 'agreement among raft nodes before linearized reading' (duration: 148.923258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:22.841342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.89419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:41:22.841318Z","caller":"traceutil/trace.go:171","msg":"trace[2012135537] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"273.071568ms","start":"2026-08-22T10:41:22.568176Z","end":"2026-08-22T10:41:22.841247Z","steps":["trace[2012135537] 'process raft request' (duration: 272.64571ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:22.841395Z","caller":"traceutil/trace.go:171","msg":"trace[748421570] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5367; }","duration":"246.979131ms","start":"2026-08-22T10:41:22.594402Z","end":"2026-08-22T10:41:22.841381Z","steps":["trace[748421570] 'agreement among raft nodes before linearized reading' (duration: 246.880159ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:30.014076Z","caller":"traceutil/trace.go:171","msg":"trace[528619418] linearizableReadLoop","detail":"{readStateIndex:5745; appliedIndex:5744; }","duration":"151.424067ms","start":"2026-08-22T10:41:29.862631Z","end":"2026-08-22T10:41:30.014055Z","steps":["trace[528619418] 'read index received' (duration: 151.233433ms)","trace[528619418] 'applied index is now lower than readState.Index' (duration: 189.924µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:30.014138Z","caller":"traceutil/trace.go:171","msg":"trace[729950747] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"164.602406ms","start":"2026-08-22T10:41:29.849507Z","end":"2026-08-22T10:41:30.01411Z","steps":["trace[729950747] 'process raft request' (duration: 164.411302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:30.014227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.567089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:41:30.014257Z","caller":"traceutil/trace.go:171","msg":"trace[1535234150] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5537; }","duration":"151.624701ms","start":"2026-08-22T10:41:29.862624Z","end":"2026-08-22T10:41:30.014248Z","steps":["trace[1535234150] 'agreement among raft nodes before linearized reading' (duration: 151.545859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:30.014433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.397727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:41:30.014536Z","caller":"traceutil/trace.go:171","msg":"trace[294522485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5537; }","duration":"121.56283ms","start":"2026-08-22T10:41:29.89295Z","end":"2026-08-22T10:41:30.014513Z","steps":["trace[294522485] 'agreement among raft nodes before linearized reading' (duration: 121.288875ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:30.274632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3016} {"level":"info","ts":"2026-08-22T10:42:30.3698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3016,"took":"93.878313ms","hash":799747514,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":31920128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T10:42:30.369895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":799747514,"revision":3016,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:47:30.310252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6086} {"level":"info","ts":"2026-08-22T10:47:30.540545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6086,"took":"228.085824ms","hash":3806723560,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":21782528,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-22T10:47:30.540711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3806723560,"revision":6086,"compact-revision":3016} {"level":"info","ts":"2026-08-22T10:48:03.630838Z","caller":"traceutil/trace.go:171","msg":"trace[1383054567] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"207.361316ms","start":"2026-08-22T10:48:03.423448Z","end":"2026-08-22T10:48:03.63081Z","steps":["trace[1383054567] 'process raft request' (duration: 192.377661ms)","trace[1383054567] 'compare' (duration: 14.856463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:04.340628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.294068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:48:04.340842Z","caller":"traceutil/trace.go:171","msg":"trace[1675168227] linearizableReadLoop","detail":"{readStateIndex:8790; appliedIndex:8788; }","duration":"218.712502ms","start":"2026-08-22T10:48:04.122107Z","end":"2026-08-22T10:48:04.34082Z","steps":["trace[1675168227] 'read index received' (duration: 194.501121ms)","trace[1675168227] 'applied index is now lower than readState.Index' (duration: 24.210351ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:04.340948Z","caller":"traceutil/trace.go:171","msg":"trace[1898632627] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"257.302827ms","start":"2026-08-22T10:48:04.083565Z","end":"2026-08-22T10:48:04.340868Z","steps":["trace[1898632627] 'process raft request' (duration: 31.648302ms)","trace[1898632627] 'compare' (duration: 224.928862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:04.341096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.998738ms","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-08-22T10:48:04.341145Z","caller":"traceutil/trace.go:171","msg":"trace[2082822737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8486; }","duration":"219.09426ms","start":"2026-08-22T10:48:04.12204Z","end":"2026-08-22T10:48:04.341134Z","steps":["trace[2082822737] 'agreement among raft nodes before linearized reading' (duration: 218.897376ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:04.341075Z","caller":"traceutil/trace.go:171","msg":"trace[1443541694] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"229.853924ms","start":"2026-08-22T10:48:04.11108Z","end":"2026-08-22T10:48:04.340934Z","steps":["trace[1443541694] 'process raft request' (duration: 229.671551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:04.341274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.544711ms","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-08-22T10:48:04.341351Z","caller":"traceutil/trace.go:171","msg":"trace[1667825500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8486; }","duration":"175.638043ms","start":"2026-08-22T10:48:04.165689Z","end":"2026-08-22T10:48:04.341327Z","steps":["trace[1667825500] 'agreement among raft nodes before linearized reading' (duration: 175.341927ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:05.25278Z","caller":"traceutil/trace.go:171","msg":"trace[1973650228] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"104.299895ms","start":"2026-08-22T10:48:05.148433Z","end":"2026-08-22T10:48:05.252733Z","steps":["trace[1973650228] 'process raft request' (duration: 43.526969ms)","trace[1973650228] 'compare' (duration: 60.564552ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:05.620599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.146612ms","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-08-22T10:48:05.620711Z","caller":"traceutil/trace.go:171","msg":"trace[820838266] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8493; }","duration":"104.307045ms","start":"2026-08-22T10:48:05.516379Z","end":"2026-08-22T10:48:05.620686Z","steps":["trace[820838266] 'count revisions from in-memory index tree' (duration: 104.06175ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:06.180261Z","caller":"traceutil/trace.go:171","msg":"trace[1864249724] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"110.658656ms","start":"2026-08-22T10:48:06.069564Z","end":"2026-08-22T10:48:06.180222Z","steps":["trace[1864249724] 'process raft request' (duration: 110.148896ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:06.495863Z","caller":"traceutil/trace.go:171","msg":"trace[905804944] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"123.525231ms","start":"2026-08-22T10:48:06.372311Z","end":"2026-08-22T10:48:06.495837Z","steps":["trace[905804944] 'process raft request' (duration: 87.454544ms)","trace[905804944] 'compare' (duration: 35.884073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:06.4961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.054588ms","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-08-22T10:48:06.496155Z","caller":"traceutil/trace.go:171","msg":"trace[1152596858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8499; }","duration":"106.162811ms","start":"2026-08-22T10:48:06.389981Z","end":"2026-08-22T10:48:06.496144Z","steps":["trace[1152596858] 'agreement among raft nodes before linearized reading' (duration: 105.923276ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:06.495818Z","caller":"traceutil/trace.go:171","msg":"trace[485622138] linearizableReadLoop","detail":"{readStateIndex:8803; appliedIndex:8802; }","duration":"105.735832ms","start":"2026-08-22T10:48:06.390056Z","end":"2026-08-22T10:48:06.495792Z","steps":["trace[485622138] 'read index received' (duration: 69.722186ms)","trace[485622138] 'applied index is now lower than readState.Index' (duration: 36.012306ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:12.165275Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002289.wal"} {"level":"info","ts":"2026-08-22T10:48:34.927431Z","caller":"traceutil/trace.go:171","msg":"trace[1387713093] linearizableReadLoop","detail":"{readStateIndex:9018; appliedIndex:9017; }","duration":"257.147854ms","start":"2026-08-22T10:48:34.670241Z","end":"2026-08-22T10:48:34.927389Z","steps":["trace[1387713093] 'read index received' (duration: 256.907339ms)","trace[1387713093] 'applied index is now lower than readState.Index' (duration: 238.835µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:34.927536Z","caller":"traceutil/trace.go:171","msg":"trace[1128601456] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"288.129373ms","start":"2026-08-22T10:48:34.639364Z","end":"2026-08-22T10:48:34.927494Z","steps":["trace[1128601456] 'process raft request' (duration: 287.773046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:34.927848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.561822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T10:48:34.927927Z","caller":"traceutil/trace.go:171","msg":"trace[1320730622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8705; }","duration":"257.708634ms","start":"2026-08-22T10:48:34.670205Z","end":"2026-08-22T10:48:34.927913Z","steps":["trace[1320730622] 'agreement among raft nodes before linearized reading' (duration: 257.403048ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:35.010072Z","caller":"traceutil/trace.go:171","msg":"trace[2134088542] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"257.867847ms","start":"2026-08-22T10:48:34.752179Z","end":"2026-08-22T10:48:35.010047Z","steps":["trace[2134088542] 'process raft request' (duration: 229.578259ms)","trace[2134088542] 'compare' (duration: 27.432932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:48:35.010348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.669795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:48:35.010451Z","caller":"traceutil/trace.go:171","msg":"trace[1801658656] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8707; }","duration":"235.814558ms","start":"2026-08-22T10:48:34.774609Z","end":"2026-08-22T10:48:35.010423Z","steps":["trace[1801658656] 'agreement among raft nodes before linearized reading' (duration: 235.656035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:35.010685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.717803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:48:35.010794Z","caller":"traceutil/trace.go:171","msg":"trace[1742546817] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8707; }","duration":"186.869087ms","start":"2026-08-22T10:48:34.823909Z","end":"2026-08-22T10:48:35.010778Z","steps":["trace[1742546817] 'agreement among raft nodes before linearized reading' (duration: 186.716784ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:37.325874Z","caller":"traceutil/trace.go:171","msg":"trace[2114105107] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"228.925677ms","start":"2026-08-22T10:48:37.096878Z","end":"2026-08-22T10:48:37.325804Z","steps":["trace[2114105107] 'process raft request' (duration: 185.027081ms)","trace[2114105107] 'compare' (duration: 43.685931ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:45.53571Z","caller":"traceutil/trace.go:171","msg":"trace[1991696630] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"135.156992ms","start":"2026-08-22T10:48:45.400524Z","end":"2026-08-22T10:48:45.535681Z","steps":["trace[1991696630] 'process raft request' (duration: 129.958412ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:46.435822Z","caller":"traceutil/trace.go:171","msg":"trace[1611191164] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"144.849457ms","start":"2026-08-22T10:48:46.290939Z","end":"2026-08-22T10:48:46.435788Z","steps":["trace[1611191164] 'process raft request' (duration: 144.672483ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:46.464754Z","caller":"traceutil/trace.go:171","msg":"trace[812768023] transaction","detail":"{read_only:false; response_revision:8768; number_of_response:1; }","duration":"122.139805ms","start":"2026-08-22T10:48:46.342574Z","end":"2026-08-22T10:48:46.464714Z","steps":["trace[812768023] 'process raft request' (duration: 121.128915ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:19.043219Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:50:19.049103Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:50:19.049247Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T10:52:30.317322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8130} {"level":"info","ts":"2026-08-22T10:52:30.395506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8130,"took":"76.014766ms","hash":4204625221,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":28151808,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T10:52:30.39561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4204625221,"revision":8130,"compact-revision":6086} {"level":"info","ts":"2026-08-22T10:55:50.82142Z","caller":"traceutil/trace.go:171","msg":"trace[1377834975] transaction","detail":"{read_only:false; response_revision:12036; number_of_response:1; }","duration":"126.381716ms","start":"2026-08-22T10:55:50.695005Z","end":"2026-08-22T10:55:50.821387Z","steps":["trace[1377834975] 'process raft request' (duration: 126.133791ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:52.171152Z","caller":"traceutil/trace.go:171","msg":"trace[1242990176] transaction","detail":"{read_only:false; response_revision:12046; number_of_response:1; }","duration":"119.824941ms","start":"2026-08-22T10:55:52.051294Z","end":"2026-08-22T10:55:52.171119Z","steps":["trace[1242990176] 'process raft request' (duration: 119.602257ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:52.184205Z","caller":"traceutil/trace.go:171","msg":"trace[915058881] linearizableReadLoop","detail":"{readStateIndex:12464; appliedIndex:12462; }","duration":"103.468409ms","start":"2026-08-22T10:55:52.080704Z","end":"2026-08-22T10:55:52.184173Z","steps":["trace[915058881] 'read index received' (duration: 90.152906ms)","trace[915058881] 'applied index is now lower than readState.Index' (duration: 13.314263ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:55:52.184334Z","caller":"traceutil/trace.go:171","msg":"trace[988870459] transaction","detail":"{read_only:false; response_revision:12047; number_of_response:1; }","duration":"128.303682ms","start":"2026-08-22T10:55:52.055999Z","end":"2026-08-22T10:55:52.184303Z","steps":["trace[988870459] 'process raft request' (duration: 128.010716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:52.184476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.880568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:55:52.184507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.806125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-22T10:55:52.184537Z","caller":"traceutil/trace.go:171","msg":"trace[1396109287] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12047; }","duration":"102.95541ms","start":"2026-08-22T10:55:52.081566Z","end":"2026-08-22T10:55:52.184522Z","steps":["trace[1396109287] 'agreement among raft nodes before linearized reading' (duration: 102.879289ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:52.184574Z","caller":"traceutil/trace.go:171","msg":"trace[1811638446] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12047; }","duration":"103.944208ms","start":"2026-08-22T10:55:52.080609Z","end":"2026-08-22T10:55:52.184553Z","steps":["trace[1811638446] 'agreement among raft nodes before linearized reading' (duration: 103.722613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:52.184537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.742295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:55:52.18466Z","caller":"traceutil/trace.go:171","msg":"trace[780257778] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12047; }","duration":"102.945229ms","start":"2026-08-22T10:55:52.081688Z","end":"2026-08-22T10:55:52.184634Z","steps":["trace[780257778] 'agreement among raft nodes before linearized reading' (duration: 102.683384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:52.184661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.341997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:55:52.184708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.695726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-08-22T10:55:52.184806Z","caller":"traceutil/trace.go:171","msg":"trace[169861611] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12047; }","duration":"101.838259ms","start":"2026-08-22T10:55:52.082944Z","end":"2026-08-22T10:55:52.184783Z","steps":["trace[169861611] 'agreement among raft nodes before linearized reading' (duration: 101.532243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:52.184412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.159513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:55:52.184797Z","caller":"traceutil/trace.go:171","msg":"trace[65854216] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12047; }","duration":"103.51543ms","start":"2026-08-22T10:55:52.081253Z","end":"2026-08-22T10:55:52.184769Z","steps":["trace[65854216] 'agreement among raft nodes before linearized reading' (duration: 103.258855ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:52.184922Z","caller":"traceutil/trace.go:171","msg":"trace[1115606081] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12047; }","duration":"103.696553ms","start":"2026-08-22T10:55:52.081168Z","end":"2026-08-22T10:55:52.184864Z","steps":["trace[1115606081] 'agreement among raft nodes before linearized reading' (duration: 103.166723ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:57:30.323971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-08-22T10:57:30.421405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"84.308776ms","hash":1316553075,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":26161152,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T10:57:30.421484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1316553075,"revision":10536,"compact-revision":8130} {"level":"info","ts":"2026-08-22T11:02:24.153591Z","caller":"traceutil/trace.go:171","msg":"trace[772417576] transaction","detail":"{read_only:false; response_revision:14658; number_of_response:1; }","duration":"177.531921ms","start":"2026-08-22T11:02:23.976037Z","end":"2026-08-22T11:02:24.153569Z","steps":["trace[772417576] 'process raft request' (duration: 177.409529ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:24.154967Z","caller":"traceutil/trace.go:171","msg":"trace[1180762132] transaction","detail":"{read_only:false; response_revision:14660; number_of_response:1; }","duration":"172.089455ms","start":"2026-08-22T11:02:23.982869Z","end":"2026-08-22T11:02:24.154959Z","steps":["trace[1180762132] 'process raft request' (duration: 172.011003ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:24.154959Z","caller":"traceutil/trace.go:171","msg":"trace[651607778] linearizableReadLoop","detail":"{readStateIndex:15169; appliedIndex:15166; }","duration":"160.438753ms","start":"2026-08-22T11:02:23.994495Z","end":"2026-08-22T11:02:24.154933Z","steps":["trace[651607778] 'read index received' (duration: 158.968586ms)","trace[651607778] 'applied index is now lower than readState.Index' (duration: 1.469187ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:02:24.154998Z","caller":"traceutil/trace.go:171","msg":"trace[739037131] transaction","detail":"{read_only:false; response_revision:14659; number_of_response:1; }","duration":"175.694145ms","start":"2026-08-22T11:02:23.979262Z","end":"2026-08-22T11:02:24.154956Z","steps":["trace[739037131] 'process raft request' (duration: 175.472791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:24.155286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.641737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:02:24.155402Z","caller":"traceutil/trace.go:171","msg":"trace[1019892076] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14660; }","duration":"160.900922ms","start":"2026-08-22T11:02:23.994469Z","end":"2026-08-22T11:02:24.15537Z","steps":["trace[1019892076] 'agreement among raft nodes before linearized reading' (duration: 160.567695ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:30.335148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12698} {"level":"info","ts":"2026-08-22T11:02:30.414734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12698,"took":"76.676165ms","hash":1804376232,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":27639808,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T11:02:30.414813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1804376232,"revision":12698,"compact-revision":10536} {"level":"info","ts":"2026-08-22T11:02:51.399537Z","caller":"traceutil/trace.go:171","msg":"trace[2006572549] transaction","detail":"{read_only:false; response_revision:14930; number_of_response:1; }","duration":"301.491417ms","start":"2026-08-22T11:02:51.09801Z","end":"2026-08-22T11:02:51.399501Z","steps":["trace[2006572549] 'process raft request' (duration: 301.273982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:51.399755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:51.097982Z","time spent":"301.659179ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:04:35.063721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.838496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:04:35.063629Z","caller":"traceutil/trace.go:171","msg":"trace[1198854624] transaction","detail":"{read_only:false; response_revision:16040; number_of_response:1; }","duration":"182.681587ms","start":"2026-08-22T11:04:34.880923Z","end":"2026-08-22T11:04:35.063604Z","steps":["trace[1198854624] 'process raft request' (duration: 182.564035ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:04:35.063703Z","caller":"traceutil/trace.go:171","msg":"trace[1992441398] transaction","detail":"{read_only:false; response_revision:16041; number_of_response:1; }","duration":"176.02818ms","start":"2026-08-22T11:04:34.887624Z","end":"2026-08-22T11:04:35.063653Z","steps":["trace[1992441398] 'process raft request' (duration: 175.907878ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:04:35.063893Z","caller":"traceutil/trace.go:171","msg":"trace[618375151] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16041; }","duration":"144.082101ms","start":"2026-08-22T11:04:34.919783Z","end":"2026-08-22T11:04:35.063865Z","steps":["trace[618375151] 'agreement among raft nodes before linearized reading' (duration: 143.846656ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:04:35.06362Z","caller":"traceutil/trace.go:171","msg":"trace[1869854919] linearizableReadLoop","detail":"{readStateIndex:16593; appliedIndex:16590; }","duration":"143.744345ms","start":"2026-08-22T11:04:34.919835Z","end":"2026-08-22T11:04:35.063579Z","steps":["trace[1869854919] 'read index received' (duration: 143.332397ms)","trace[1869854919] 'applied index is now lower than readState.Index' (duration: 410.557µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:04:35.063621Z","caller":"traceutil/trace.go:171","msg":"trace[1050194387] transaction","detail":"{read_only:false; response_revision:16039; number_of_response:1; }","duration":"189.122441ms","start":"2026-08-22T11:04:34.874461Z","end":"2026-08-22T11:04:35.063583Z","steps":["trace[1050194387] 'process raft request' (duration: 188.798875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:04:35.064349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.645125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-08-22T11:04:35.064603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.52294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-22T11:04:35.06463Z","caller":"traceutil/trace.go:171","msg":"trace[1115777208] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16041; }","duration":"141.995602ms","start":"2026-08-22T11:04:34.922601Z","end":"2026-08-22T11:04:35.064597Z","steps":["trace[1115777208] 'agreement among raft nodes before linearized reading' (duration: 141.593434ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:04:35.064679Z","caller":"traceutil/trace.go:171","msg":"trace[687858826] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16041; }","duration":"144.637292ms","start":"2026-08-22T11:04:34.920022Z","end":"2026-08-22T11:04:35.064659Z","steps":["trace[687858826] 'agreement among raft nodes before linearized reading' (duration: 144.169103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:04:35.064712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.02472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-22T11:04:35.064772Z","caller":"traceutil/trace.go:171","msg":"trace[166827352] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16041; }","duration":"144.112132ms","start":"2026-08-22T11:04:34.920644Z","end":"2026-08-22T11:04:35.064756Z","steps":["trace[166827352] 'agreement among raft nodes before linearized reading' (duration: 143.920658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:04:35.064848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.390267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:04:35.06495Z","caller":"traceutil/trace.go:171","msg":"trace[1578039175] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16041; }","duration":"144.53784ms","start":"2026-08-22T11:04:34.92039Z","end":"2026-08-22T11:04:35.064928Z","steps":["trace[1578039175] 'agreement among raft nodes before linearized reading' (duration: 144.346686ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:05:57.649678Z","caller":"traceutil/trace.go:171","msg":"trace[1299373960] transaction","detail":"{read_only:false; response_revision:16601; number_of_response:1; }","duration":"113.587915ms","start":"2026-08-22T11:05:57.536051Z","end":"2026-08-22T11:05:57.649639Z","steps":["trace[1299373960] 'process raft request' (duration: 113.286049ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:30.344506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14692} {"level":"info","ts":"2026-08-22T11:07:30.423396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14692,"took":"75.55634ms","hash":414469230,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":32321536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:07:30.423537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":414469230,"revision":14692,"compact-revision":12698} {"level":"info","ts":"2026-08-22T11:10:13.61237Z","caller":"traceutil/trace.go:171","msg":"trace[1903610786] linearizableReadLoop","detail":"{readStateIndex:19171; appliedIndex:19170; }","duration":"101.053404ms","start":"2026-08-22T11:10:13.511295Z","end":"2026-08-22T11:10:13.612348Z","steps":["trace[1903610786] 'read index received' (duration: 100.959033ms)","trace[1903610786] 'applied index is now lower than readState.Index' (duration: 92.751µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:10:13.61273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.398351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-22T11:10:13.612853Z","caller":"traceutil/trace.go:171","msg":"trace[1173367464] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18536; }","duration":"101.580444ms","start":"2026-08-22T11:10:13.511245Z","end":"2026-08-22T11:10:13.612826Z","steps":["trace[1173367464] 'agreement among raft nodes before linearized reading' (duration: 101.238208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:13.737586Z","caller":"traceutil/trace.go:171","msg":"trace[1264827641] transaction","detail":"{read_only:false; response_revision:18538; number_of_response:1; }","duration":"113.061044ms","start":"2026-08-22T11:10:13.624502Z","end":"2026-08-22T11:10:13.737563Z","steps":["trace[1264827641] 'process raft request' (duration: 95.616101ms)","trace[1264827641] 'compare' (duration: 16.682578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:10:18.145215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.271345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-22T11:10:18.145384Z","caller":"traceutil/trace.go:171","msg":"trace[1280925349] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18585; }","duration":"188.49419ms","start":"2026-08-22T11:10:17.956869Z","end":"2026-08-22T11:10:18.145363Z","steps":["trace[1280925349] 'range keys from in-memory index tree' (duration: 188.079002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:10:18.145714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.939961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-gtr5c\" ","response":"range_response_count:1 size:20740"} {"level":"info","ts":"2026-08-22T11:10:18.145792Z","caller":"traceutil/trace.go:171","msg":"trace[975429544] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-gtr5c; range_end:; response_count:1; response_revision:18585; }","duration":"113.054673ms","start":"2026-08-22T11:10:18.032724Z","end":"2026-08-22T11:10:18.145778Z","steps":["trace[975429544] 'range keys from in-memory index tree' (duration: 112.807728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:10:18.145849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.569359ms","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-08-22T11:10:18.145907Z","caller":"traceutil/trace.go:171","msg":"trace[877451623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18585; }","duration":"170.62966ms","start":"2026-08-22T11:10:17.975263Z","end":"2026-08-22T11:10:18.145893Z","steps":["trace[877451623] 'range keys from in-memory index tree' (duration: 170.424807ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:18.623028Z","caller":"traceutil/trace.go:171","msg":"trace[694035386] transaction","detail":"{read_only:false; response_revision:18588; number_of_response:1; }","duration":"263.429727ms","start":"2026-08-22T11:10:18.35957Z","end":"2026-08-22T11:10:18.623Z","steps":["trace[694035386] 'process raft request' (duration: 263.265484ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:18.623022Z","caller":"traceutil/trace.go:171","msg":"trace[1996079762] linearizableReadLoop","detail":"{readStateIndex:19223; appliedIndex:19222; }","duration":"253.416666ms","start":"2026-08-22T11:10:18.369566Z","end":"2026-08-22T11:10:18.622982Z","steps":["trace[1996079762] 'read index received' (duration: 253.362265ms)","trace[1996079762] 'applied index is now lower than readState.Index' (duration: 53.221µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:10:18.623199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.621331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-22T11:10:18.623249Z","caller":"traceutil/trace.go:171","msg":"trace[324482795] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18588; }","duration":"253.719873ms","start":"2026-08-22T11:10:18.369521Z","end":"2026-08-22T11:10:18.623241Z","steps":["trace[324482795] 'agreement among raft nodes before linearized reading' (duration: 253.55565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:10:18.709118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.890818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-22T11:10:18.709184Z","caller":"traceutil/trace.go:171","msg":"trace[228122438] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18589; }","duration":"252.00499ms","start":"2026-08-22T11:10:18.457163Z","end":"2026-08-22T11:10:18.709168Z","steps":["trace[228122438] 'agreement among raft nodes before linearized reading' (duration: 251.820867ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:18.709219Z","caller":"traceutil/trace.go:171","msg":"trace[1443795971] transaction","detail":"{read_only:false; response_revision:18589; number_of_response:1; }","duration":"279.407321ms","start":"2026-08-22T11:10:18.429781Z","end":"2026-08-22T11:10:18.709188Z","steps":["trace[1443795971] 'process raft request' (duration: 240.688273ms)","trace[1443795971] 'compare' (duration: 38.410022ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:11:23.903686Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:11:23.908595Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:11:23.908719Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:12:30.349756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17311} {"level":"info","ts":"2026-08-22T11:12:30.454149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17311,"took":"101.167927ms","hash":3020984449,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":34586624,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T11:12:30.454213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3020984449,"revision":17311,"compact-revision":14692} {"level":"info","ts":"2026-08-22T11:16:52.093573Z","caller":"traceutil/trace.go:171","msg":"trace[1287977631] transaction","detail":"{read_only:false; response_revision:21543; number_of_response:1; }","duration":"139.019097ms","start":"2026-08-22T11:16:51.954518Z","end":"2026-08-22T11:16:52.093537Z","steps":["trace[1287977631] 'process raft request' (duration: 138.791683ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:52.417825Z","caller":"traceutil/trace.go:171","msg":"trace[78779299] linearizableReadLoop","detail":"{readStateIndex:22276; appliedIndex:22275; }","duration":"140.036217ms","start":"2026-08-22T11:16:52.277758Z","end":"2026-08-22T11:16:52.417795Z","steps":["trace[78779299] 'read index received' (duration: 139.703661ms)","trace[78779299] 'applied index is now lower than readState.Index' (duration: 331.366µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:16:52.418072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.288392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T11:16:52.418107Z","caller":"traceutil/trace.go:171","msg":"trace[1803253349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21544; }","duration":"140.372183ms","start":"2026-08-22T11:16:52.277725Z","end":"2026-08-22T11:16:52.418097Z","steps":["trace[1803253349] 'agreement among raft nodes before linearized reading' (duration: 140.19114ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:52.418323Z","caller":"traceutil/trace.go:171","msg":"trace[1025886008] transaction","detail":"{read_only:false; response_revision:21544; number_of_response:1; }","duration":"151.970924ms","start":"2026-08-22T11:16:52.266301Z","end":"2026-08-22T11:16:52.418272Z","steps":["trace[1025886008] 'process raft request' (duration: 151.277001ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:52.594086Z","caller":"traceutil/trace.go:171","msg":"trace[945257944] transaction","detail":"{read_only:false; response_revision:21545; number_of_response:1; }","duration":"171.11412ms","start":"2026-08-22T11:16:52.422931Z","end":"2026-08-22T11:16:52.594046Z","steps":["trace[945257944] 'process raft request' (duration: 156.9441ms)","trace[945257944] 'compare' (duration: 13.6537ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:16:53.017775Z","caller":"traceutil/trace.go:171","msg":"trace[598309550] transaction","detail":"{read_only:false; response_revision:21547; number_of_response:1; }","duration":"127.834464ms","start":"2026-08-22T11:16:52.889901Z","end":"2026-08-22T11:16:53.017736Z","steps":["trace[598309550] 'process raft request' (duration: 127.557859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:16:53.297577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.987534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:16:53.297687Z","caller":"traceutil/trace.go:171","msg":"trace[694231256] linearizableReadLoop","detail":"{readStateIndex:22280; appliedIndex:22279; }","duration":"175.584925ms","start":"2026-08-22T11:16:53.122085Z","end":"2026-08-22T11:16:53.29767Z","steps":["trace[694231256] 'read index received' (duration: 21.992619ms)","trace[694231256] 'applied index is now lower than readState.Index' (duration: 153.591026ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:16:53.297738Z","caller":"traceutil/trace.go:171","msg":"trace[833972341] transaction","detail":"{read_only:false; response_revision:21548; number_of_response:1; }","duration":"251.771336ms","start":"2026-08-22T11:16:53.045943Z","end":"2026-08-22T11:16:53.297714Z","steps":["trace[833972341] 'process raft request' (duration: 98.14882ms)","trace[833972341] 'compare' (duration: 152.646228ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:16:53.297798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.629189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:16:53.297837Z","caller":"traceutil/trace.go:171","msg":"trace[1697180617] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:21548; }","duration":"107.707251ms","start":"2026-08-22T11:16:53.190118Z","end":"2026-08-22T11:16:53.297825Z","steps":["trace[1697180617] 'agreement among raft nodes before linearized reading' (duration: 107.6459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:16:53.297867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.775459ms","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-08-22T11:16:53.297918Z","caller":"traceutil/trace.go:171","msg":"trace[1701081546] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21548; }","duration":"175.86041ms","start":"2026-08-22T11:16:53.122045Z","end":"2026-08-22T11:16:53.297905Z","steps":["trace[1701081546] 'agreement among raft nodes before linearized reading' (duration: 175.703837ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:53.593605Z","caller":"traceutil/trace.go:171","msg":"trace[1321762000] transaction","detail":"{read_only:false; response_revision:21550; number_of_response:1; }","duration":"279.242259ms","start":"2026-08-22T11:16:53.314313Z","end":"2026-08-22T11:16:53.593555Z","steps":["trace[1321762000] 'process raft request' (duration: 278.958334ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:53.59367Z","caller":"traceutil/trace.go:171","msg":"trace[68013217] transaction","detail":"{read_only:false; response_revision:21551; number_of_response:1; }","duration":"274.008559ms","start":"2026-08-22T11:16:53.319609Z","end":"2026-08-22T11:16:53.593617Z","steps":["trace[68013217] 'process raft request' (duration: 273.842676ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:53.593522Z","caller":"traceutil/trace.go:171","msg":"trace[171878624] linearizableReadLoop","detail":"{readStateIndex:22283; appliedIndex:22281; }","duration":"229.715665ms","start":"2026-08-22T11:16:53.36378Z","end":"2026-08-22T11:16:53.593495Z","steps":["trace[171878624] 'read index received' (duration: 229.493081ms)","trace[171878624] 'applied index is now lower than readState.Index' (duration: 221.214µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:16:53.593975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.157824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:16:53.595787Z","caller":"traceutil/trace.go:171","msg":"trace[1406520284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21551; }","duration":"231.736653ms","start":"2026-08-22T11:16:53.363739Z","end":"2026-08-22T11:16:53.595476Z","steps":["trace[1406520284] 'agreement among raft nodes before linearized reading' (duration: 229.96842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:16:53.595992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.992826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:16:53.596074Z","caller":"traceutil/trace.go:171","msg":"trace[489311205] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21551; }","duration":"225.090138ms","start":"2026-08-22T11:16:53.370956Z","end":"2026-08-22T11:16:53.596046Z","steps":["trace[489311205] 'agreement among raft nodes before linearized reading' (duration: 224.979446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:16:53.596468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.887148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T11:16:53.596514Z","caller":"traceutil/trace.go:171","msg":"trace[1121520439] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:21551; }","duration":"103.96717ms","start":"2026-08-22T11:16:53.492536Z","end":"2026-08-22T11:16:53.596503Z","steps":["trace[1121520439] 'agreement among raft nodes before linearized reading' (duration: 103.851838ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:30.357215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19807} {"level":"info","ts":"2026-08-22T11:17:30.466288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19807,"took":"105.745954ms","hash":4040043672,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:17:30.466362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4040043672,"revision":19807,"compact-revision":17311} {"level":"info","ts":"2026-08-22T11:20:53.186235Z","caller":"traceutil/trace.go:171","msg":"trace[707196081] transaction","detail":"{read_only:false; response_revision:23537; number_of_response:1; }","duration":"123.527861ms","start":"2026-08-22T11:20:53.062665Z","end":"2026-08-22T11:20:53.186193Z","steps":["trace[707196081] 'process raft request' (duration: 121.511402ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:53.195101Z","caller":"traceutil/trace.go:171","msg":"trace[923428757] linearizableReadLoop","detail":"{readStateIndex:24336; appliedIndex:24334; }","duration":"127.867774ms","start":"2026-08-22T11:20:53.067171Z","end":"2026-08-22T11:20:53.195039Z","steps":["trace[923428757] 'read index received' (duration: 117.035028ms)","trace[923428757] 'applied index is now lower than readState.Index' (duration: 10.831156ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:53.195603Z","caller":"traceutil/trace.go:171","msg":"trace[1952680922] transaction","detail":"{read_only:false; response_revision:23538; number_of_response:1; }","duration":"131.461363ms","start":"2026-08-22T11:20:53.064125Z","end":"2026-08-22T11:20:53.195587Z","steps":["trace[1952680922] 'process raft request' (duration: 130.366252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:53.196255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.143687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/providers.clusterctl.cluster.x-k8s.io\" ","response":"range_response_count:1 size:4238"} {"level":"info","ts":"2026-08-22T11:20:53.196332Z","caller":"traceutil/trace.go:171","msg":"trace[1751299363] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/providers.clusterctl.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23538; }","duration":"121.28251ms","start":"2026-08-22T11:20:53.075011Z","end":"2026-08-22T11:20:53.196294Z","steps":["trace[1751299363] 'agreement among raft nodes before linearized reading' (duration: 121.065136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:53.196967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.791711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:53.197107Z","caller":"traceutil/trace.go:171","msg":"trace[573520021] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:23538; }","duration":"129.943224ms","start":"2026-08-22T11:20:53.067115Z","end":"2026-08-22T11:20:53.197058Z","steps":["trace[573520021] 'agreement among raft nodes before linearized reading' (duration: 129.526756ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:53.323764Z","caller":"traceutil/trace.go:171","msg":"trace[913022447] transaction","detail":"{read_only:false; response_revision:23539; number_of_response:1; }","duration":"120.030965ms","start":"2026-08-22T11:20:53.203312Z","end":"2026-08-22T11:20:53.323343Z","steps":["trace[913022447] 'process raft request' (duration: 114.243375ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:22:30.363825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21971} {"level":"info","ts":"2026-08-22T11:22:30.46998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21971,"took":"101.38233ms","hash":2012963592,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":42827776,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-22T11:22:30.470061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2012963592,"revision":21971,"compact-revision":19807} {"level":"info","ts":"2026-08-22T11:25:06.071758Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000698e.wal"} {"level":"info","ts":"2026-08-22T11:27:30.369687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24833} {"level":"info","ts":"2026-08-22T11:27:30.513898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24833,"took":"139.969764ms","hash":3237910660,"current-db-size-bytes":59400192,"current-db-size":"59 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:27:30.513976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3237910660,"revision":24833,"compact-revision":21971} {"level":"info","ts":"2026-08-22T11:31:36.494372Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:31:36.500409Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T11:31:36.500591Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}