{"level":"warn","ts":"2026-08-16T12:51:22.049438Z","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-16T12:51:22.049529Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-16T12:51:22.049594Z","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-16T12:51:22.049607Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-16T12:51:22.049626Z","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-16T12:51:22.050262Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-08-16T12:51:22.050346Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-16T12:51:22.053127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.56841ms"} {"level":"info","ts":"2026-08-16T12:51:22.05752Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-08-16T12:51:22.0576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:51:22.057623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-08-16T12:51:22.057634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:51:22.057655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-08-16T12:51:22.057697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-08-16T12:51:22.062893Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:51:22.065476Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:51:22.067331Z","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-16T12:51:22.069881Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:51:22.070049Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:51:22.070137Z","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-16T12:51:22.070275Z","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-16T12:51:22.070295Z","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-16T12:51:22.072354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-08-16T12:51:22.072665Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-16T12:51:22.074664Z","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-16T12:51:22.074836Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-16T12:51:22.074939Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-16T12:51:22.075053Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:51:22.07509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:51:22.258451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:51:22.258505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:51:22.258544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-08-16T12:51:22.258567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:51:22.25858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-16T12:51:22.258613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-08-16T12:51:22.258635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-16T12:51:22.259768Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:51:22.259832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:51:22.25986Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:22.259926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:51:22.260433Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:51:22.260557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:51:22.261088Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:22.261234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:22.261278Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:22.263984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:51:22.264417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-08-16T12:51:39.402546Z","caller":"traceutil/trace.go:171","msg":"trace[518196372] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"132.059811ms","start":"2026-08-16T12:51:39.270454Z","end":"2026-08-16T12:51:39.402514Z","steps":["trace[518196372] 'process raft request' (duration: 131.891208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:51:59.686398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.113542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:51:59.686602Z","caller":"traceutil/trace.go:171","msg":"trace[424087186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"120.416427ms","start":"2026-08-16T12:51:59.566151Z","end":"2026-08-16T12:51:59.686568Z","steps":["trace[424087186] 'range keys from in-memory index tree' (duration: 119.954489ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:00.487386Z","caller":"traceutil/trace.go:171","msg":"trace[2088313471] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"110.243041ms","start":"2026-08-16T12:52:00.377119Z","end":"2026-08-16T12:52:00.487362Z","steps":["trace[2088313471] 'process raft request' (duration: 60.80547ms)","trace[2088313471] 'compare' (duration: 49.168236ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:00.507645Z","caller":"traceutil/trace.go:171","msg":"trace[81593630] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"120.289114ms","start":"2026-08-16T12:52:00.387318Z","end":"2026-08-16T12:52:00.507607Z","steps":["trace[81593630] 'process raft request' (duration: 120.05147ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:00.785539Z","caller":"traceutil/trace.go:171","msg":"trace[330401850] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"241.925404ms","start":"2026-08-16T12:52:00.54358Z","end":"2026-08-16T12:52:00.785505Z","steps":["trace[330401850] 'process raft request' (duration: 230.095267ms)","trace[330401850] 'compare' (duration: 11.600823ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:00.78562Z","caller":"traceutil/trace.go:171","msg":"trace[1120529452] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"218.81349ms","start":"2026-08-16T12:52:00.566769Z","end":"2026-08-16T12:52:00.785582Z","steps":["trace[1120529452] 'read index received' (duration: 206.964812ms)","trace[1120529452] 'applied index is now lower than readState.Index' (duration: 11.846758ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:00.785734Z","caller":"traceutil/trace.go:171","msg":"trace[994745611] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"194.543522ms","start":"2026-08-16T12:52:00.591179Z","end":"2026-08-16T12:52:00.785722Z","steps":["trace[994745611] 'process raft request' (duration: 194.43735ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:00.785728Z","caller":"traceutil/trace.go:171","msg":"trace[1315480054] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"207.232227ms","start":"2026-08-16T12:52:00.578482Z","end":"2026-08-16T12:52:00.785714Z","steps":["trace[1315480054] 'process raft request' (duration: 207.027243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:00.79158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.794546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:52:00.791674Z","caller":"traceutil/trace.go:171","msg":"trace[967645243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"224.943799ms","start":"2026-08-16T12:52:00.566705Z","end":"2026-08-16T12:52:00.791649Z","steps":["trace[967645243] 'agreement among raft nodes before linearized reading' (duration: 218.987964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:05.698391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.387498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:05.698524Z","caller":"traceutil/trace.go:171","msg":"trace[1897673751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"132.545241ms","start":"2026-08-16T12:52:05.565936Z","end":"2026-08-16T12:52:05.698481Z","steps":["trace[1897673751] 'range keys from in-memory index tree' (duration: 132.293837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:05.698569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.886143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-16T12:52:05.698655Z","caller":"traceutil/trace.go:171","msg":"trace[74980540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"112.043296ms","start":"2026-08-16T12:52:05.586595Z","end":"2026-08-16T12:52:05.698638Z","steps":["trace[74980540] 'range keys from in-memory index tree' (duration: 111.663239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:06.377236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.59587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:52:06.377386Z","caller":"traceutil/trace.go:171","msg":"trace[163722040] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"362.678178ms","start":"2026-08-16T12:52:06.014694Z","end":"2026-08-16T12:52:06.377373Z","steps":["trace[163722040] 'read index received' (duration: 56.557088ms)","trace[163722040] 'applied index is now lower than readState.Index' (duration: 306.11998ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:06.377431Z","caller":"traceutil/trace.go:171","msg":"trace[1741053002] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"430.148357ms","start":"2026-08-16T12:52:05.947225Z","end":"2026-08-16T12:52:06.377373Z","steps":["trace[1741053002] 'process raft request' (duration: 123.882834ms)","trace[1741053002] 'compare' (duration: 305.399957ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:06.377502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.815641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:52:06.377531Z","caller":"traceutil/trace.go:171","msg":"trace[978380179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"362.886862ms","start":"2026-08-16T12:52:06.014635Z","end":"2026-08-16T12:52:06.377522Z","steps":["trace[978380179] 'agreement among raft nodes before linearized reading' (duration: 362.77176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:06.377561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:06.014613Z","time spent":"362.936104ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:52:06.377535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:05.947204Z","time spent":"430.28304ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:52:06.377495Z","caller":"traceutil/trace.go:171","msg":"trace[2067492783] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"388.888983ms","start":"2026-08-16T12:52:05.988516Z","end":"2026-08-16T12:52:06.377405Z","steps":["trace[2067492783] 'process raft request' (duration: 388.811181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:06.377857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:05.988494Z","time spent":"389.203309ms","remote":"127.0.0.1:58512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:52:20.795842Z","caller":"traceutil/trace.go:171","msg":"trace[723811358] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"151.655785ms","start":"2026-08-16T12:52:20.644156Z","end":"2026-08-16T12:52:20.795812Z","steps":["trace[723811358] 'read index received' (duration: 99.742702ms)","trace[723811358] 'applied index is now lower than readState.Index' (duration: 51.911063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:20.795991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.805198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:20.796033Z","caller":"traceutil/trace.go:171","msg":"trace[836885799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"151.890951ms","start":"2026-08-16T12:52:20.644127Z","end":"2026-08-16T12:52:20.796018Z","steps":["trace[836885799] 'agreement among raft nodes before linearized reading' (duration: 151.798989ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:20.798415Z","caller":"traceutil/trace.go:171","msg":"trace[1177894082] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"148.780296ms","start":"2026-08-16T12:52:20.649602Z","end":"2026-08-16T12:52:20.798382Z","steps":["trace[1177894082] 'process raft request' (duration: 148.536671ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:48.82705Z","caller":"traceutil/trace.go:171","msg":"trace[1363868527] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"216.903842ms","start":"2026-08-16T12:52:48.610116Z","end":"2026-08-16T12:52:48.82702Z","steps":["trace[1363868527] 'read index received' (duration: 216.575156ms)","trace[1363868527] 'applied index is now lower than readState.Index' (duration: 327.486µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:48.827134Z","caller":"traceutil/trace.go:171","msg":"trace[1288604657] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"241.993227ms","start":"2026-08-16T12:52:48.585095Z","end":"2026-08-16T12:52:48.827088Z","steps":["trace[1288604657] 'process raft request' (duration: 241.744472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:48.827285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.157048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:52:48.827336Z","caller":"traceutil/trace.go:171","msg":"trace[732161965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1146; }","duration":"217.248069ms","start":"2026-08-16T12:52:48.610073Z","end":"2026-08-16T12:52:48.827321Z","steps":["trace[732161965] 'agreement among raft nodes before linearized reading' (duration: 217.078066ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:11.072024Z","caller":"traceutil/trace.go:171","msg":"trace[338247805] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"119.160942ms","start":"2026-08-16T12:53:10.952834Z","end":"2026-08-16T12:53:11.071995Z","steps":["trace[338247805] 'process raft request' (duration: 119.001999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:34.896246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.148658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:34.896383Z","caller":"traceutil/trace.go:171","msg":"trace[1599862423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"331.344592ms","start":"2026-08-16T12:53:34.565014Z","end":"2026-08-16T12:53:34.896359Z","steps":["trace[1599862423] 'range keys from in-memory index tree' (duration: 330.983025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:34.896442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:34.564992Z","time spent":"331.434735ms","remote":"127.0.0.1:58142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:53:34.896222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.055656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:34.896688Z","caller":"traceutil/trace.go:171","msg":"trace[68610675] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1801; }","duration":"202.504006ms","start":"2026-08-16T12:53:34.694069Z","end":"2026-08-16T12:53:34.896573Z","steps":["trace[68610675] 'count revisions from in-memory index tree' (duration: 201.913092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:35.395572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.170932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-16T12:53:35.395598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.637033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:53:35.395666Z","caller":"traceutil/trace.go:171","msg":"trace[178856997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1803; }","duration":"124.328636ms","start":"2026-08-16T12:53:35.271316Z","end":"2026-08-16T12:53:35.395644Z","steps":["trace[178856997] 'range keys from in-memory index tree' (duration: 123.927718ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:35.395712Z","caller":"traceutil/trace.go:171","msg":"trace[291652395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1803; }","duration":"106.787145ms","start":"2026-08-16T12:53:35.288896Z","end":"2026-08-16T12:53:35.395683Z","steps":["trace[291652395] 'range keys from in-memory index tree' (duration: 106.455139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:35.39584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.246311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-16T12:53:35.395854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.256711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-16T12:53:35.39593Z","caller":"traceutil/trace.go:171","msg":"trace[1793623150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1803; }","duration":"204.380453ms","start":"2026-08-16T12:53:35.191527Z","end":"2026-08-16T12:53:35.395908Z","steps":["trace[1793623150] 'range keys from in-memory index tree' (duration: 204.008175ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:35.39594Z","caller":"traceutil/trace.go:171","msg":"trace[1932597402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1803; }","duration":"204.398693ms","start":"2026-08-16T12:53:35.19152Z","end":"2026-08-16T12:53:35.395919Z","steps":["trace[1932597402] 'range keys from in-memory index tree' (duration: 204.056296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:30.021553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.025462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:54:30.021875Z","caller":"traceutil/trace.go:171","msg":"trace[107124584] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"281.180065ms","start":"2026-08-16T12:54:29.740614Z","end":"2026-08-16T12:54:30.021794Z","steps":["trace[107124584] 'process raft request' (duration: 128.785765ms)","trace[107124584] 'compare' (duration: 151.674255ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:30.022042Z","caller":"traceutil/trace.go:171","msg":"trace[623612846] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2233; }","duration":"223.972364ms","start":"2026-08-16T12:54:29.798045Z","end":"2026-08-16T12:54:30.022017Z","steps":["trace[623612846] 'read index received' (duration: 71.269108ms)","trace[623612846] 'applied index is now lower than readState.Index' (duration: 152.701946ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:30.022254Z","caller":"traceutil/trace.go:171","msg":"trace[474969553] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"280.322328ms","start":"2026-08-16T12:54:29.741844Z","end":"2026-08-16T12:54:30.022167Z","steps":["trace[474969553] 'process raft request' (duration: 279.865608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:30.022578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.822286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-16T12:54:30.022547Z","caller":"traceutil/trace.go:171","msg":"trace[1496745832] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"219.004883ms","start":"2026-08-16T12:54:29.80342Z","end":"2026-08-16T12:54:30.022425Z","steps":["trace[1496745832] 'process raft request' (duration: 218.551634ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:30.022704Z","caller":"traceutil/trace.go:171","msg":"trace[1001485430] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2156; }","duration":"276.968789ms","start":"2026-08-16T12:54:29.745707Z","end":"2026-08-16T12:54:30.022676Z","steps":["trace[1001485430] 'agreement among raft nodes before linearized reading' (duration: 276.604892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:30.023605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.05178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-16T12:54:30.023695Z","caller":"traceutil/trace.go:171","msg":"trace[580535735] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"213.487209ms","start":"2026-08-16T12:54:29.810189Z","end":"2026-08-16T12:54:30.023676Z","steps":["trace[580535735] 'agreement among raft nodes before linearized reading' (duration: 212.682283ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:30.274234Z","caller":"traceutil/trace.go:171","msg":"trace[2105976159] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"238.001102ms","start":"2026-08-16T12:54:30.03621Z","end":"2026-08-16T12:54:30.274211Z","steps":["trace[2105976159] 'process raft request' (duration: 237.788497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:20.896759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.496395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-16T12:55:20.896863Z","caller":"traceutil/trace.go:171","msg":"trace[838468650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2603; }","duration":"156.659018ms","start":"2026-08-16T12:55:20.740183Z","end":"2026-08-16T12:55:20.896842Z","steps":["trace[838468650] 'range keys from in-memory index tree' (duration: 156.30355ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:55.734146Z","caller":"traceutil/trace.go:171","msg":"trace[309316048] linearizableReadLoop","detail":"{readStateIndex:2918; appliedIndex:2917; }","duration":"106.436234ms","start":"2026-08-16T12:55:55.627665Z","end":"2026-08-16T12:55:55.734101Z","steps":["trace[309316048] 'read index received' (duration: 106.22644ms)","trace[309316048] 'applied index is now lower than readState.Index' (duration: 208.124µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:55.734404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.723499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:55:55.734647Z","caller":"traceutil/trace.go:171","msg":"trace[1097996094] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2812; }","duration":"106.977954ms","start":"2026-08-16T12:55:55.627636Z","end":"2026-08-16T12:55:55.734614Z","steps":["trace[1097996094] 'agreement among raft nodes before linearized reading' (duration: 106.549186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:37.549365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.015193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:58:37.549482Z","caller":"traceutil/trace.go:171","msg":"trace[823237743] linearizableReadLoop","detail":"{readStateIndex:4013; appliedIndex:4012; }","duration":"145.675314ms","start":"2026-08-16T12:58:37.403789Z","end":"2026-08-16T12:58:37.549464Z","steps":["trace[823237743] 'read index received' (duration: 18.374826ms)","trace[823237743] 'applied index is now lower than readState.Index' (duration: 127.299118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:58:37.549583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.791066ms","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-16T12:58:37.54964Z","caller":"traceutil/trace.go:171","msg":"trace[674286852] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:3868; }","duration":"145.893008ms","start":"2026-08-16T12:58:37.403734Z","end":"2026-08-16T12:58:37.549627Z","steps":["trace[674286852] 'agreement among raft nodes before linearized reading' (duration: 145.806747ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:58:37.549632Z","caller":"traceutil/trace.go:171","msg":"trace[769282485] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"152.041343ms","start":"2026-08-16T12:58:37.39756Z","end":"2026-08-16T12:58:37.549602Z","steps":["trace[769282485] 'process raft request' (duration: 24.715964ms)","trace[769282485] 'compare' (duration: 126.736327ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:00:05.711359Z","caller":"traceutil/trace.go:171","msg":"trace[329058010] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"120.122815ms","start":"2026-08-16T13:00:05.591199Z","end":"2026-08-16T13:00:05.711321Z","steps":["trace[329058010] 'process raft request' (duration: 99.110168ms)","trace[329058010] 'compare' (duration: 20.750712ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:00:05.724912Z","caller":"traceutil/trace.go:171","msg":"trace[1946368664] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5239; }","duration":"108.543079ms","start":"2026-08-16T13:00:05.616346Z","end":"2026-08-16T13:00:05.724889Z","steps":["trace[1946368664] 'read index received' (duration: 73.939565ms)","trace[1946368664] 'applied index is now lower than readState.Index' (duration: 34.602374ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:00:05.725017Z","caller":"traceutil/trace.go:171","msg":"trace[553829283] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"121.668145ms","start":"2026-08-16T13:00:05.603326Z","end":"2026-08-16T13:00:05.724995Z","steps":["trace[553829283] 'process raft request' (duration: 121.4041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:05.72506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.694052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:05.725097Z","caller":"traceutil/trace.go:171","msg":"trace[835755766] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5049; }","duration":"108.782324ms","start":"2026-08-16T13:00:05.616304Z","end":"2026-08-16T13:00:05.725087Z","steps":["trace[835755766] 'agreement among raft nodes before linearized reading' (duration: 108.707072ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:05.89112Z","caller":"traceutil/trace.go:171","msg":"trace[1794656021] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"180.702197ms","start":"2026-08-16T13:00:05.71039Z","end":"2026-08-16T13:00:05.891092Z","steps":["trace[1794656021] 'process raft request' (duration: 119.340568ms)","trace[1794656021] 'compare' (duration: 61.220836ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:00:05.891127Z","caller":"traceutil/trace.go:171","msg":"trace[1363245132] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5241; }","duration":"166.10237ms","start":"2026-08-16T13:00:05.724989Z","end":"2026-08-16T13:00:05.891091Z","steps":["trace[1363245132] 'read index received' (duration: 104.755041ms)","trace[1363245132] 'applied index is now lower than readState.Index' (duration: 61.346269ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:00:05.891261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.561839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:05.891315Z","caller":"traceutil/trace.go:171","msg":"trace[902327508] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5050; }","duration":"225.680892ms","start":"2026-08-16T13:00:05.665623Z","end":"2026-08-16T13:00:05.891304Z","steps":["trace[902327508] 'agreement among raft nodes before linearized reading' (duration: 225.5593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:05.891368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.336474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:05.891413Z","caller":"traceutil/trace.go:171","msg":"trace[1452414648] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:5050; }","duration":"163.425856ms","start":"2026-08-16T13:00:05.727975Z","end":"2026-08-16T13:00:05.8914Z","steps":["trace[1452414648] 'agreement among raft nodes before linearized reading' (duration: 163.328903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:05.891408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.45829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58302cbc-9dd6-4db2-a0ff-9c1d68a31a16\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-08-16T13:00:05.891499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.227599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-16T13:00:05.891504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.813919ms","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-16T13:00:05.891591Z","caller":"traceutil/trace.go:171","msg":"trace[2076783528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5050; }","duration":"154.375472ms","start":"2026-08-16T13:00:05.737192Z","end":"2026-08-16T13:00:05.891567Z","steps":["trace[2076783528] 'agreement among raft nodes before linearized reading' (duration: 154.169957ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:05.891599Z","caller":"traceutil/trace.go:171","msg":"trace[427903813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5050; }","duration":"150.944002ms","start":"2026-08-16T13:00:05.740636Z","end":"2026-08-16T13:00:05.89158Z","steps":["trace[427903813] 'agreement among raft nodes before linearized reading' (duration: 150.677825ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:05.891528Z","caller":"traceutil/trace.go:171","msg":"trace[685506960] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58302cbc-9dd6-4db2-a0ff-9c1d68a31a16; range_end:; response_count:1; response_revision:5050; }","duration":"174.628734ms","start":"2026-08-16T13:00:05.716877Z","end":"2026-08-16T13:00:05.891505Z","steps":["trace[685506960] 'agreement among raft nodes before linearized reading' (duration: 174.277036ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:18.520983Z","caller":"traceutil/trace.go:171","msg":"trace[1162831319] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"121.986502ms","start":"2026-08-16T13:00:18.39895Z","end":"2026-08-16T13:00:18.520937Z","steps":["trace[1162831319] 'process raft request' (duration: 52.001128ms)","trace[1162831319] 'compare' (duration: 69.572296ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:00:19.036983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.938227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-08-16T13:00:19.03705Z","caller":"traceutil/trace.go:171","msg":"trace[410561734] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5314; }","duration":"197.053649ms","start":"2026-08-16T13:00:18.839982Z","end":"2026-08-16T13:00:19.037036Z","steps":["trace[410561734] 'range keys from in-memory index tree' (duration: 196.763424ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:00.455769Z","caller":"traceutil/trace.go:171","msg":"trace[1836078753] linearizableReadLoop","detail":"{readStateIndex:5986; appliedIndex:5985; }","duration":"309.59092ms","start":"2026-08-16T13:01:00.146152Z","end":"2026-08-16T13:01:00.455743Z","steps":["trace[1836078753] 'read index received' (duration: 309.469507ms)","trace[1836078753] 'applied index is now lower than readState.Index' (duration: 120.663µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:00.455808Z","caller":"traceutil/trace.go:171","msg":"trace[920377746] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"366.808865ms","start":"2026-08-16T13:01:00.088975Z","end":"2026-08-16T13:01:00.455784Z","steps":["trace[920377746] 'process raft request' (duration: 366.618501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:00.45599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.825094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T13:01:00.456029Z","caller":"traceutil/trace.go:171","msg":"trace[493590597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5775; }","duration":"309.925486ms","start":"2026-08-16T13:01:00.146094Z","end":"2026-08-16T13:01:00.456019Z","steps":["trace[493590597] 'agreement among raft nodes before linearized reading' (duration: 309.776663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:00.456061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:01:00.146071Z","time spent":"309.982417ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T13:01:00.456122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:01:00.088958Z","time spent":"367.012578ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:01:00.456894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.288903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-16T13:01:00.456977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.36858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-16T13:01:00.456984Z","caller":"traceutil/trace.go:171","msg":"trace[125336743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5776; }","duration":"276.423985ms","start":"2026-08-16T13:01:00.180538Z","end":"2026-08-16T13:01:00.456962Z","steps":["trace[125336743] 'agreement among raft nodes before linearized reading' (duration: 276.210881ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:00.457024Z","caller":"traceutil/trace.go:171","msg":"trace[1512404573] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5776; }","duration":"151.454942ms","start":"2026-08-16T13:01:00.305553Z","end":"2026-08-16T13:01:00.457008Z","steps":["trace[1512404573] 'agreement among raft nodes before linearized reading' (duration: 151.34357ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:22.414306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-08-16T13:01:22.48736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"71.424314ms","hash":3240301105,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":33370112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-16T13:01:22.487433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3240301105,"revision":2956,"compact-revision":-1} {"level":"warn","ts":"2026-08-16T13:06:15.616437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.485591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:06:15.616551Z","caller":"traceutil/trace.go:171","msg":"trace[1610501953] linearizableReadLoop","detail":"{readStateIndex:8502; appliedIndex:8501; }","duration":"392.965513ms","start":"2026-08-16T13:06:15.223567Z","end":"2026-08-16T13:06:15.616532Z","steps":["trace[1610501953] 'read index received' (duration: 282.336699ms)","trace[1610501953] 'applied index is now lower than readState.Index' (duration: 110.627674ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:06:15.616615Z","caller":"traceutil/trace.go:171","msg":"trace[1538599533] transaction","detail":"{read_only:false; response_revision:8214; number_of_response:1; }","duration":"403.247382ms","start":"2026-08-16T13:06:15.213356Z","end":"2026-08-16T13:06:15.616603Z","steps":["trace[1538599533] 'process raft request' (duration: 292.508737ms)","trace[1538599533] 'compare' (duration: 110.380688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:06:15.616675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:06:15.213334Z","time spent":"403.312494ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:06:15.61678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.183127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:06:15.616877Z","caller":"traceutil/trace.go:171","msg":"trace[812762776] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8214; }","duration":"393.33273ms","start":"2026-08-16T13:06:15.223523Z","end":"2026-08-16T13:06:15.616856Z","steps":["trace[812762776] 'agreement among raft nodes before linearized reading' (duration: 393.164647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:06:15.616927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:06:15.223512Z","time spent":"393.403453ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:06:15.633273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.165911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:06:15.633362Z","caller":"traceutil/trace.go:171","msg":"trace[1809970368] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8215; }","duration":"265.266373ms","start":"2026-08-16T13:06:15.368072Z","end":"2026-08-16T13:06:15.633339Z","steps":["trace[1809970368] 'agreement among raft nodes before linearized reading' (duration: 265.086919ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:06:22.420712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6004} {"level":"info","ts":"2026-08-16T13:06:22.516635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6004,"took":"93.901994ms","hash":676472461,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":23351296,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-16T13:06:22.516765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":676472461,"revision":6004,"compact-revision":2956} {"level":"warn","ts":"2026-08-16T13:06:27.654612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.407319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:06:27.654722Z","caller":"traceutil/trace.go:171","msg":"trace[975040324] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8278; }","duration":"248.586983ms","start":"2026-08-16T13:06:27.406108Z","end":"2026-08-16T13:06:27.654695Z","steps":["trace[975040324] 'count revisions from in-memory index tree' (duration: 248.278076ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:06:28.481213Z","caller":"traceutil/trace.go:171","msg":"trace[764698961] linearizableReadLoop","detail":"{readStateIndex:8577; appliedIndex:8576; }","duration":"253.116504ms","start":"2026-08-16T13:06:28.228067Z","end":"2026-08-16T13:06:28.481184Z","steps":["trace[764698961] 'read index received' (duration: 167.775317ms)","trace[764698961] 'applied index is now lower than readState.Index' (duration: 85.339857ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:06:28.481305Z","caller":"traceutil/trace.go:171","msg":"trace[1728221550] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"264.616988ms","start":"2026-08-16T13:06:28.216641Z","end":"2026-08-16T13:06:28.481258Z","steps":["trace[1728221550] 'process raft request' (duration: 179.254271ms)","trace[1728221550] 'compare' (duration: 85.159343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:06:28.481408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.314999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:06:28.481492Z","caller":"traceutil/trace.go:171","msg":"trace[1579632424] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8285; }","duration":"253.442291ms","start":"2026-08-16T13:06:28.228029Z","end":"2026-08-16T13:06:28.481471Z","steps":["trace[1579632424] 'agreement among raft nodes before linearized reading' (duration: 253.247127ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:06:55.623957Z","caller":"traceutil/trace.go:171","msg":"trace[757170505] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"261.421153ms","start":"2026-08-16T13:06:55.362507Z","end":"2026-08-16T13:06:55.623928Z","steps":["trace[757170505] 'process raft request' (duration: 261.23899ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:06:55.792803Z","caller":"traceutil/trace.go:171","msg":"trace[1949035241] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8779; }","duration":"102.138039ms","start":"2026-08-16T13:06:55.690633Z","end":"2026-08-16T13:06:55.792771Z","steps":["trace[1949035241] 'read index received' (duration: 101.427555ms)","trace[1949035241] 'applied index is now lower than readState.Index' (duration: 709.314µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:06:55.793027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.378435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-16T13:06:55.79308Z","caller":"traceutil/trace.go:171","msg":"trace[1495888210] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8479; }","duration":"102.465947ms","start":"2026-08-16T13:06:55.690583Z","end":"2026-08-16T13:06:55.793049Z","steps":["trace[1495888210] 'agreement among raft nodes before linearized reading' (duration: 102.304103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:06:57.982484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.502082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-16T13:06:57.982581Z","caller":"traceutil/trace.go:171","msg":"trace[1640005535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8493; }","duration":"142.646805ms","start":"2026-08-16T13:06:57.83991Z","end":"2026-08-16T13:06:57.982557Z","steps":["trace[1640005535] 'range keys from in-memory index tree' (duration: 142.317868ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:08.431913Z","caller":"traceutil/trace.go:171","msg":"trace[1504325081] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"178.859263ms","start":"2026-08-16T13:07:08.25302Z","end":"2026-08-16T13:07:08.431879Z","steps":["trace[1504325081] 'process raft request' (duration: 167.317278ms)","trace[1504325081] 'compare' (duration: 10.898382ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:07:08.43191Z","caller":"traceutil/trace.go:171","msg":"trace[804023182] linearizableReadLoop","detail":"{readStateIndex:8895; appliedIndex:8894; }","duration":"159.360405ms","start":"2026-08-16T13:07:08.272275Z","end":"2026-08-16T13:07:08.431635Z","steps":["trace[804023182] 'read index received' (duration: 148.081296ms)","trace[804023182] 'applied index is now lower than readState.Index' (duration: 11.278059ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:07:08.432123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.825864ms","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-16T13:07:08.432202Z","caller":"traceutil/trace.go:171","msg":"trace[1522519394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8589; }","duration":"159.939807ms","start":"2026-08-16T13:07:08.272244Z","end":"2026-08-16T13:07:08.432184Z","steps":["trace[1522519394] 'agreement among raft nodes before linearized reading' (duration: 159.706013ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:08.46455Z","caller":"traceutil/trace.go:171","msg":"trace[1228466969] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"119.702247ms","start":"2026-08-16T13:07:08.344821Z","end":"2026-08-16T13:07:08.464523Z","steps":["trace[1228466969] 'process raft request' (duration: 119.424992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:08.464668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.115797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-16T13:07:08.464742Z","caller":"traceutil/trace.go:171","msg":"trace[2144421047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8590; }","duration":"177.248169ms","start":"2026-08-16T13:07:08.287478Z","end":"2026-08-16T13:07:08.464726Z","steps":["trace[2144421047] 'agreement among raft nodes before linearized reading' (duration: 177.056136ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:09.874291Z","caller":"traceutil/trace.go:171","msg":"trace[465091701] linearizableReadLoop","detail":"{readStateIndex:8906; appliedIndex:8905; }","duration":"130.557768ms","start":"2026-08-16T13:07:09.743711Z","end":"2026-08-16T13:07:09.874269Z","steps":["trace[465091701] 'read index received' (duration: 130.393435ms)","trace[465091701] 'applied index is now lower than readState.Index' (duration: 163.243µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:07:09.874428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.694372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:07:09.874488Z","caller":"traceutil/trace.go:171","msg":"trace[1319547142] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8600; }","duration":"130.873446ms","start":"2026-08-16T13:07:09.743588Z","end":"2026-08-16T13:07:09.874461Z","steps":["trace[1319547142] 'agreement among raft nodes before linearized reading' (duration: 130.769264ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:09.874786Z","caller":"traceutil/trace.go:171","msg":"trace[1753854536] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"131.304434ms","start":"2026-08-16T13:07:09.743438Z","end":"2026-08-16T13:07:09.874743Z","steps":["trace[1753854536] 'process raft request' (duration: 130.655081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:10.147116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.540227ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:07:10.147233Z","caller":"traceutil/trace.go:171","msg":"trace[619519114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8601; }","duration":"211.680561ms","start":"2026-08-16T13:07:09.935537Z","end":"2026-08-16T13:07:10.147218Z","steps":["trace[619519114] 'range keys from in-memory index tree' (duration: 211.520427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:10.147461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.693715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:07:10.147888Z","caller":"traceutil/trace.go:171","msg":"trace[569294386] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"249.94881ms","start":"2026-08-16T13:07:09.897923Z","end":"2026-08-16T13:07:10.147872Z","steps":["trace[569294386] 'process raft request' (duration: 25.775335ms)","trace[569294386] 'compare' (duration: 223.468851ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:07:53.178278Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002492.wal"} {"level":"info","ts":"2026-08-16T13:09:06.722409Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:09:06.728131Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-16T13:09:06.728196Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-16T13:11:22.428933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8253} {"level":"info","ts":"2026-08-16T13:11:22.507445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8253,"took":"76.408916ms","hash":776744855,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":26439680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-16T13:11:22.507521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":776744855,"revision":8253,"compact-revision":6004} {"level":"info","ts":"2026-08-16T13:11:53.838823Z","caller":"traceutil/trace.go:171","msg":"trace[942091291] transaction","detail":"{read_only:false; response_revision:11107; number_of_response:1; }","duration":"106.158202ms","start":"2026-08-16T13:11:53.732632Z","end":"2026-08-16T13:11:53.83879Z","steps":["trace[942091291] 'process raft request' (duration: 69.369922ms)","trace[942091291] 'compare' (duration: 36.657427ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:11:54.115192Z","caller":"traceutil/trace.go:171","msg":"trace[1750032418] transaction","detail":"{read_only:false; response_revision:11108; number_of_response:1; }","duration":"173.043334ms","start":"2026-08-16T13:11:53.942121Z","end":"2026-08-16T13:11:54.115164Z","steps":["trace[1750032418] 'process raft request' (duration: 172.870811ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:11:54.138952Z","caller":"traceutil/trace.go:171","msg":"trace[1133055990] linearizableReadLoop","detail":"{readStateIndex:11487; appliedIndex:11485; }","duration":"188.723383ms","start":"2026-08-16T13:11:53.950202Z","end":"2026-08-16T13:11:54.138925Z","steps":["trace[1133055990] 'read index received' (duration: 164.775445ms)","trace[1133055990] 'applied index is now lower than readState.Index' (duration: 23.946718ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:11:54.139029Z","caller":"traceutil/trace.go:171","msg":"trace[649791839] transaction","detail":"{read_only:false; response_revision:11109; number_of_response:1; }","duration":"196.603804ms","start":"2026-08-16T13:11:53.942399Z","end":"2026-08-16T13:11:54.139002Z","steps":["trace[649791839] 'process raft request' (duration: 196.39245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:11:54.139151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.930368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-16T13:11:54.139197Z","caller":"traceutil/trace.go:171","msg":"trace[1803034091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11109; }","duration":"189.0021ms","start":"2026-08-16T13:11:53.950181Z","end":"2026-08-16T13:11:54.139183Z","steps":["trace[1803034091] 'agreement among raft nodes before linearized reading' (duration: 188.848576ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:11:54.294019Z","caller":"traceutil/trace.go:171","msg":"trace[546236390] transaction","detail":"{read_only:false; response_revision:11111; number_of_response:1; }","duration":"106.149902ms","start":"2026-08-16T13:11:54.187833Z","end":"2026-08-16T13:11:54.293983Z","steps":["trace[546236390] 'process raft request' (duration: 66.792781ms)","trace[546236390] 'compare' (duration: 39.160607ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:16:22.436887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10751} {"level":"info","ts":"2026-08-16T13:16:22.525077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10751,"took":"83.438269ms","hash":1733815522,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":27787264,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-16T13:16:22.525143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1733815522,"revision":10751,"compact-revision":8253} {"level":"info","ts":"2026-08-16T13:17:40.207089Z","caller":"traceutil/trace.go:171","msg":"trace[1628324008] transaction","detail":"{read_only:false; response_revision:13483; number_of_response:1; }","duration":"104.009378ms","start":"2026-08-16T13:17:40.103042Z","end":"2026-08-16T13:17:40.207052Z","steps":["trace[1628324008] 'process raft request' (duration: 56.839338ms)","trace[1628324008] 'compare' (duration: 47.020167ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:18:23.816704Z","caller":"traceutil/trace.go:171","msg":"trace[953894914] transaction","detail":"{read_only:false; response_revision:13729; number_of_response:1; }","duration":"268.729152ms","start":"2026-08-16T13:18:23.547949Z","end":"2026-08-16T13:18:23.816678Z","steps":["trace[953894914] 'process raft request' (duration: 268.573439ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:19:40.698131Z","caller":"traceutil/trace.go:171","msg":"trace[1688283159] transaction","detail":"{read_only:false; response_revision:14681; number_of_response:1; }","duration":"136.697803ms","start":"2026-08-16T13:19:40.561407Z","end":"2026-08-16T13:19:40.698104Z","steps":["trace[1688283159] 'process raft request' (duration: 104.984337ms)","trace[1688283159] 'compare' (duration: 31.153184ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:19:40.698166Z","caller":"traceutil/trace.go:171","msg":"trace[322037831] linearizableReadLoop","detail":"{readStateIndex:15183; appliedIndex:15182; }","duration":"132.201472ms","start":"2026-08-16T13:19:40.565922Z","end":"2026-08-16T13:19:40.698124Z","steps":["trace[322037831] 'read index received' (duration: 100.469795ms)","trace[322037831] 'applied index is now lower than readState.Index' (duration: 31.729517ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:19:40.701154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.208823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-16T13:19:40.70123Z","caller":"traceutil/trace.go:171","msg":"trace[2114235856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14681; }","duration":"135.313337ms","start":"2026-08-16T13:19:40.565896Z","end":"2026-08-16T13:19:40.701209Z","steps":["trace[2114235856] 'agreement among raft nodes before linearized reading' (duration: 132.323806ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:21:22.443487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12939} {"level":"info","ts":"2026-08-16T13:21:22.520004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12939,"took":"73.664339ms","hash":3272842801,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":30769152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-16T13:21:22.520073Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3272842801,"revision":12939,"compact-revision":10751} {"level":"info","ts":"2026-08-16T13:25:30.121123Z","caller":"traceutil/trace.go:171","msg":"trace[1422000304] linearizableReadLoop","detail":"{readStateIndex:17865; appliedIndex:17864; }","duration":"115.294268ms","start":"2026-08-16T13:25:30.005804Z","end":"2026-08-16T13:25:30.121099Z","steps":["trace[1422000304] 'read index received' (duration: 115.082444ms)","trace[1422000304] 'applied index is now lower than readState.Index' (duration: 210.934µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:25:30.121312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.488162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-16T13:25:30.121333Z","caller":"traceutil/trace.go:171","msg":"trace[1748092428] transaction","detail":"{read_only:false; response_revision:17277; number_of_response:1; }","duration":"122.347652ms","start":"2026-08-16T13:25:29.998972Z","end":"2026-08-16T13:25:30.121319Z","steps":["trace[1748092428] 'process raft request' (duration: 121.974734ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:25:30.121353Z","caller":"traceutil/trace.go:171","msg":"trace[1403975855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17277; }","duration":"115.575953ms","start":"2026-08-16T13:25:30.005765Z","end":"2026-08-16T13:25:30.121341Z","steps":["trace[1403975855] 'agreement among raft nodes before linearized reading' (duration: 115.43439ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:25:30.4314Z","caller":"traceutil/trace.go:171","msg":"trace[1982857543] linearizableReadLoop","detail":"{readStateIndex:17867; appliedIndex:17866; }","duration":"165.477771ms","start":"2026-08-16T13:25:30.26589Z","end":"2026-08-16T13:25:30.431368Z","steps":["trace[1982857543] 'read index received' (duration: 163.839028ms)","trace[1982857543] 'applied index is now lower than readState.Index' (duration: 1.637593ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:25:30.431526Z","caller":"traceutil/trace.go:171","msg":"trace[1984944919] transaction","detail":"{read_only:false; response_revision:17279; number_of_response:1; }","duration":"172.374061ms","start":"2026-08-16T13:25:30.259141Z","end":"2026-08-16T13:25:30.431516Z","steps":["trace[1984944919] 'process raft request' (duration: 170.513833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:25:30.431754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.857869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-16T13:25:30.431796Z","caller":"traceutil/trace.go:171","msg":"trace[1963251789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17279; }","duration":"165.950341ms","start":"2026-08-16T13:25:30.265834Z","end":"2026-08-16T13:25:30.431785Z","steps":["trace[1963251789] 'agreement among raft nodes before linearized reading' (duration: 165.803088ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:26:22.449444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15333} {"level":"info","ts":"2026-08-16T13:26:22.554819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15333,"took":"100.687941ms","hash":289961048,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":33701888,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-16T13:26:22.55489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":289961048,"revision":15333,"compact-revision":12939} {"level":"info","ts":"2026-08-16T13:29:31.963968Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:29:31.96911Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-16T13:29:31.969256Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-16T13:31:22.456878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17907} {"level":"info","ts":"2026-08-16T13:31:22.572165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17907,"took":"110.789057ms","hash":1599141234,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":29671424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-16T13:31:22.572242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1599141234,"revision":17907,"compact-revision":15333} {"level":"info","ts":"2026-08-16T13:31:26.782092Z","caller":"traceutil/trace.go:171","msg":"trace[23567395] transaction","detail":"{read_only:false; response_revision:20005; number_of_response:1; }","duration":"332.818118ms","start":"2026-08-16T13:31:26.449249Z","end":"2026-08-16T13:31:26.782067Z","steps":["trace[23567395] 'process raft request' (duration: 332.611183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:26.782387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:31:26.449241Z","time spent":"332.9385ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:31:26.782403Z","caller":"traceutil/trace.go:171","msg":"trace[1283892369] linearizableReadLoop","detail":"{readStateIndex:20688; appliedIndex:20686; }","duration":"249.327897ms","start":"2026-08-16T13:31:26.533041Z","end":"2026-08-16T13:31:26.782369Z","steps":["trace[1283892369] 'read index received' (duration: 248.715134ms)","trace[1283892369] 'applied index is now lower than readState.Index' (duration: 611.283µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:31:26.782587Z","caller":"traceutil/trace.go:171","msg":"trace[928149857] transaction","detail":"{read_only:false; response_revision:20006; number_of_response:1; }","duration":"332.798157ms","start":"2026-08-16T13:31:26.449776Z","end":"2026-08-16T13:31:26.782574Z","steps":["trace[928149857] 'process raft request' (duration: 332.241466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:26.782714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.677275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-16T13:31:26.782707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:31:26.449758Z","time spent":"332.869239ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:31:26.782759Z","caller":"traceutil/trace.go:171","msg":"trace[986271445] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:20006; }","duration":"249.758116ms","start":"2026-08-16T13:31:26.532986Z","end":"2026-08-16T13:31:26.782744Z","steps":["trace[986271445] 'agreement among raft nodes before linearized reading' (duration: 249.684354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:26.782768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.969359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:31:26.782798Z","caller":"traceutil/trace.go:171","msg":"trace[667094845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20006; }","duration":"219.01847ms","start":"2026-08-16T13:31:26.563771Z","end":"2026-08-16T13:31:26.782789Z","steps":["trace[667094845] 'agreement among raft nodes before linearized reading' (duration: 218.961969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:26.782922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.201288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-16T13:31:26.783013Z","caller":"traceutil/trace.go:171","msg":"trace[1303433406] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20006; }","duration":"229.343581ms","start":"2026-08-16T13:31:26.553644Z","end":"2026-08-16T13:31:26.782987Z","steps":["trace[1303433406] 'agreement among raft nodes before linearized reading' (duration: 229.049905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:27.048683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.619892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:31:27.048812Z","caller":"traceutil/trace.go:171","msg":"trace[1135878944] linearizableReadLoop","detail":"{readStateIndex:20689; appliedIndex:20688; }","duration":"240.903706ms","start":"2026-08-16T13:31:26.807891Z","end":"2026-08-16T13:31:27.048795Z","steps":["trace[1135878944] 'read index received' (duration: 131.07789ms)","trace[1135878944] 'applied index is now lower than readState.Index' (duration: 109.824596ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:31:27.048851Z","caller":"traceutil/trace.go:171","msg":"trace[325581905] transaction","detail":"{read_only:false; response_revision:20007; number_of_response:1; }","duration":"260.620118ms","start":"2026-08-16T13:31:26.788195Z","end":"2026-08-16T13:31:27.048816Z","steps":["trace[325581905] 'process raft request' (duration: 150.778081ms)","trace[325581905] 'compare' (duration: 109.478229ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:31:27.048967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.082099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-16T13:31:27.049007Z","caller":"traceutil/trace.go:171","msg":"trace[2113807770] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20007; }","duration":"241.153771ms","start":"2026-08-16T13:31:26.807843Z","end":"2026-08-16T13:31:27.048997Z","steps":["trace[2113807770] 'agreement among raft nodes before linearized reading' (duration: 241.011098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:27.482282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.899308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:31:27.482367Z","caller":"traceutil/trace.go:171","msg":"trace[296632552] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:20009; }","duration":"112.049142ms","start":"2026-08-16T13:31:27.370297Z","end":"2026-08-16T13:31:27.482346Z","steps":["trace[296632552] 'count revisions from in-memory index tree' (duration: 111.809206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:27.482461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.239708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:31:27.482505Z","caller":"traceutil/trace.go:171","msg":"trace[190396043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20009; }","duration":"117.30805ms","start":"2026-08-16T13:31:27.365184Z","end":"2026-08-16T13:31:27.482493Z","steps":["trace[190396043] 'range keys from in-memory index tree' (duration: 117.192657ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:35:03.650585Z","caller":"traceutil/trace.go:171","msg":"trace[2117686911] linearizableReadLoop","detail":"{readStateIndex:23075; appliedIndex:23074; }","duration":"188.735495ms","start":"2026-08-16T13:35:03.461816Z","end":"2026-08-16T13:35:03.650551Z","steps":["trace[2117686911] 'read index received' (duration: 188.444669ms)","trace[2117686911] 'applied index is now lower than readState.Index' (duration: 289.356µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:35:03.650628Z","caller":"traceutil/trace.go:171","msg":"trace[158270036] transaction","detail":"{read_only:false; response_revision:22323; number_of_response:1; }","duration":"258.559376ms","start":"2026-08-16T13:35:03.392033Z","end":"2026-08-16T13:35:03.650592Z","steps":["trace[158270036] 'process raft request' (duration: 258.29667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:35:03.650816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.97499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-16T13:35:03.650884Z","caller":"traceutil/trace.go:171","msg":"trace[2076486125] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:22323; }","duration":"189.096772ms","start":"2026-08-16T13:35:03.461766Z","end":"2026-08-16T13:35:03.650863Z","steps":["trace[2076486125] 'agreement among raft nodes before linearized reading' (duration: 188.957929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:35:03.671728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.792522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-16T13:35:03.67181Z","caller":"traceutil/trace.go:171","msg":"trace[1829004249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22324; }","duration":"127.927325ms","start":"2026-08-16T13:35:03.543861Z","end":"2026-08-16T13:35:03.671789Z","steps":["trace[1829004249] 'agreement among raft nodes before linearized reading' (duration: 127.68364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:35:03.671886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.652497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-67b8b974d6-mkrx5\" ","response":"range_response_count:1 size:5627"} {"level":"info","ts":"2026-08-16T13:35:03.671952Z","caller":"traceutil/trace.go:171","msg":"trace[843855204] range","detail":"{range_begin:/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-67b8b974d6-mkrx5; range_end:; response_count:1; response_revision:22324; }","duration":"149.75939ms","start":"2026-08-16T13:35:03.522173Z","end":"2026-08-16T13:35:03.671932Z","steps":["trace[843855204] 'agreement among raft nodes before linearized reading' (duration: 149.605806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:36:12.355448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.409121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:36:12.355586Z","caller":"traceutil/trace.go:171","msg":"trace[299504165] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:23134; }","duration":"119.592375ms","start":"2026-08-16T13:36:12.235966Z","end":"2026-08-16T13:36:12.355558Z","steps":["trace[299504165] 'count revisions from in-memory index tree' (duration: 119.323899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:36:12.35568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.835003ms","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-16T13:36:12.355779Z","caller":"traceutil/trace.go:171","msg":"trace[1004929342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23134; }","duration":"100.970457ms","start":"2026-08-16T13:36:12.254787Z","end":"2026-08-16T13:36:12.355758Z","steps":["trace[1004929342] 'range keys from in-memory index tree' (duration: 100.617049ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:36:22.463111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19984} {"level":"info","ts":"2026-08-16T13:36:22.570722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19984,"took":"104.326335ms","hash":886135394,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":43520000,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-16T13:36:22.5708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":886135394,"revision":19984,"compact-revision":17907} {"level":"info","ts":"2026-08-16T13:41:22.469661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23221} {"level":"info","ts":"2026-08-16T13:41:22.603739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23221,"took":"129.893766ms","hash":4177502515,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":31326208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-16T13:41:22.603831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4177502515,"revision":23221,"compact-revision":19984}