{"level":"warn","ts":"2026-08-10T22:28:53.820913Z","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-10T22:28:53.821007Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-10T22:28:53.821076Z","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-10T22:28:53.821091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-08-10T22:28:53.821107Z","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-10T22:28:53.821715Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-08-10T22:28:53.821784Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-10T22:28:53.825722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.729602ms"} {"level":"info","ts":"2026-08-10T22:28:53.831768Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-08-10T22:28:53.831855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:28:53.831879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-08-10T22:28:53.831901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:28:53.831911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-08-10T22:28:53.831954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-08-10T22:28:53.836059Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:28:53.837494Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:28:53.839065Z","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-10T22:28:53.841337Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:28:53.8416Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:28:53.841822Z","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-10T22:28:53.841935Z","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-10T22:28:53.841954Z","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-10T22:28:53.844051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-08-10T22:28:53.844384Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-08-10T22:28:53.845486Z","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-10T22:28:53.845699Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-08-10T22:28:53.845747Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:28:53.845813Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-08-10T22:28:53.845846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:28:54.433142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:28:54.433207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:28:54.433233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-08-10T22:28:54.433258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:28:54.43327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-08-10T22:28:54.433295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-08-10T22:28:54.433305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-08-10T22:28:54.434417Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:28:54.434567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:28:54.434646Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:28:54.43471Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:28:54.435753Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:28:54.435841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:28:54.436057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:28:54.437215Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:28:54.437283Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:28:54.437335Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-08-10T22:28:54.438346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:29:31.556116Z","caller":"traceutil/trace.go:171","msg":"trace[803640031] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"110.712117ms","start":"2026-08-10T22:29:31.445383Z","end":"2026-08-10T22:29:31.556095Z","steps":["trace[803640031] 'process raft request' (duration: 110.40225ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:31.781756Z","caller":"traceutil/trace.go:171","msg":"trace[861932245] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"257.714934ms","start":"2026-08-10T22:29:31.523997Z","end":"2026-08-10T22:29:31.781712Z","steps":["trace[861932245] 'process raft request' (duration: 202.068084ms)","trace[861932245] 'compare' (duration: 55.403753ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:29:31.786506Z","caller":"traceutil/trace.go:171","msg":"trace[1342917890] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"153.121892ms","start":"2026-08-10T22:29:31.633348Z","end":"2026-08-10T22:29:31.78647Z","steps":["trace[1342917890] 'process raft request' (duration: 152.938548ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:31.787132Z","caller":"traceutil/trace.go:171","msg":"trace[119281808] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"194.938666ms","start":"2026-08-10T22:29:31.592181Z","end":"2026-08-10T22:29:31.78712Z","steps":["trace[119281808] 'process raft request' (duration: 193.906324ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:36.911936Z","caller":"traceutil/trace.go:171","msg":"trace[2012396598] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"138.868628ms","start":"2026-08-10T22:29:36.773032Z","end":"2026-08-10T22:29:36.9119Z","steps":["trace[2012396598] 'process raft request' (duration: 138.270405ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:37.404263Z","caller":"traceutil/trace.go:171","msg":"trace[2121874063] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"439.939239ms","start":"2026-08-10T22:29:36.964294Z","end":"2026-08-10T22:29:37.404233Z","steps":["trace[2121874063] 'process raft request' (duration: 346.648528ms)","trace[2121874063] 'compare' (duration: 93.056226ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:29:37.40515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:29:36.964269Z","time spent":"440.314608ms","remote":"127.0.0.1:36908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T22:29:52.797918Z","caller":"traceutil/trace.go:171","msg":"trace[2042572802] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"120.109629ms","start":"2026-08-10T22:29:52.677781Z","end":"2026-08-10T22:29:52.797891Z","steps":["trace[2042572802] 'process raft request' (duration: 119.962555ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:31:06.918153Z","caller":"traceutil/trace.go:171","msg":"trace[1369024779] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"175.521764ms","start":"2026-08-10T22:31:06.742578Z","end":"2026-08-10T22:31:06.9181Z","steps":["trace[1369024779] 'process raft request' (duration: 175.123674ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:31:07.041854Z","caller":"traceutil/trace.go:171","msg":"trace[1607105242] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"113.926269ms","start":"2026-08-10T22:31:06.927886Z","end":"2026-08-10T22:31:07.041812Z","steps":["trace[1607105242] 'process raft request' (duration: 32.879607ms)","trace[1607105242] 'compare' (duration: 80.834397ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:31:56.964111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.442567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:31:56.964265Z","caller":"traceutil/trace.go:171","msg":"trace[1611370245] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"200.577925ms","start":"2026-08-10T22:31:56.76367Z","end":"2026-08-10T22:31:56.964248Z","steps":["trace[1611370245] 'read index received' (duration: 68.42571ms)","trace[1611370245] 'applied index is now lower than readState.Index' (duration: 132.150055ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:31:56.964336Z","caller":"traceutil/trace.go:171","msg":"trace[42757679] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"202.220054ms","start":"2026-08-10T22:31:56.76209Z","end":"2026-08-10T22:31:56.96431Z","steps":["trace[42757679] 'process raft request' (duration: 70.123811ms)","trace[42757679] 'compare' (duration: 130.935155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:31:56.964408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.737818ms","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-10T22:31:56.964435Z","caller":"traceutil/trace.go:171","msg":"trace[255992327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2169; }","duration":"200.79376ms","start":"2026-08-10T22:31:56.763632Z","end":"2026-08-10T22:31:56.964426Z","steps":["trace[255992327] 'agreement among raft nodes before linearized reading' (duration: 200.660087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:57.232466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.236571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:31:57.232797Z","caller":"traceutil/trace.go:171","msg":"trace[797015724] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"263.897079ms","start":"2026-08-10T22:31:56.968825Z","end":"2026-08-10T22:31:57.232722Z","steps":["trace[797015724] 'process raft request' (duration: 155.33801ms)","trace[797015724] 'compare' (duration: 108.138589ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:32:46.272776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.758037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:32:46.272894Z","caller":"traceutil/trace.go:171","msg":"trace[741274150] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2609; }","duration":"126.915972ms","start":"2026-08-10T22:32:46.145946Z","end":"2026-08-10T22:32:46.272862Z","steps":["trace[741274150] 'count revisions from in-memory index tree' (duration: 126.683956ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:11.805615Z","caller":"traceutil/trace.go:171","msg":"trace[1527187384] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"110.031124ms","start":"2026-08-10T22:37:11.695559Z","end":"2026-08-10T22:37:11.80559Z","steps":["trace[1527187384] 'process raft request' (duration: 109.83914ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:11.805641Z","caller":"traceutil/trace.go:171","msg":"trace[140572927] linearizableReadLoop","detail":"{readStateIndex:4883; appliedIndex:4882; }","duration":"104.449857ms","start":"2026-08-10T22:37:11.701137Z","end":"2026-08-10T22:37:11.805587Z","steps":["trace[140572927] 'read index received' (duration: 104.431107ms)","trace[140572927] 'applied index is now lower than readState.Index' (duration: 15.9µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:37:11.805971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.865068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-10T22:37:11.806168Z","caller":"traceutil/trace.go:171","msg":"trace[343415616] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4702; }","duration":"105.126044ms","start":"2026-08-10T22:37:11.701021Z","end":"2026-08-10T22:37:11.806147Z","steps":["trace[343415616] 'agreement among raft nodes before linearized reading' (duration: 104.711474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:37:11.80913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.40593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-10T22:37:11.809213Z","caller":"traceutil/trace.go:171","msg":"trace[2112377142] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4704; }","duration":"107.533882ms","start":"2026-08-10T22:37:11.70166Z","end":"2026-08-10T22:37:11.809194Z","steps":["trace[2112377142] 'agreement among raft nodes before linearized reading' (duration: 107.290026ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:16.992499Z","caller":"traceutil/trace.go:171","msg":"trace[561636722] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"107.874801ms","start":"2026-08-10T22:37:16.884595Z","end":"2026-08-10T22:37:16.992469Z","steps":["trace[561636722] 'process raft request' (duration: 102.300525ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:17.157278Z","caller":"traceutil/trace.go:171","msg":"trace[1362615176] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"164.820205ms","start":"2026-08-10T22:37:16.992433Z","end":"2026-08-10T22:37:17.157253Z","steps":["trace[1362615176] 'read index received' (duration: 164.641431ms)","trace[1362615176] 'applied index is now lower than readState.Index' (duration: 177.364µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:37:17.157329Z","caller":"traceutil/trace.go:171","msg":"trace[1938708031] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"243.185973ms","start":"2026-08-10T22:37:16.914113Z","end":"2026-08-10T22:37:17.157298Z","steps":["trace[1938708031] 'process raft request' (duration: 242.984538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:37:17.157386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.415417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:37:17.157419Z","caller":"traceutil/trace.go:171","msg":"trace[984516743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4863; }","duration":"209.55822ms","start":"2026-08-10T22:37:16.947851Z","end":"2026-08-10T22:37:17.157409Z","steps":["trace[984516743] 'agreement among raft nodes before linearized reading' (duration: 209.464358ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:17.176681Z","caller":"traceutil/trace.go:171","msg":"trace[2058087312] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"180.716795ms","start":"2026-08-10T22:37:16.995946Z","end":"2026-08-10T22:37:17.176662Z","steps":["trace[2058087312] 'process raft request' (duration: 180.625242ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:21.240215Z","caller":"traceutil/trace.go:171","msg":"trace[561971128] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"161.416853ms","start":"2026-08-10T22:37:21.078777Z","end":"2026-08-10T22:37:21.240193Z","steps":["trace[561971128] 'process raft request' (duration: 161.113096ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:21.393126Z","caller":"traceutil/trace.go:171","msg":"trace[2143783856] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"147.673875ms","start":"2026-08-10T22:37:21.245418Z","end":"2026-08-10T22:37:21.393092Z","steps":["trace[2143783856] 'process raft request' (duration: 123.499524ms)","trace[2143783856] 'compare' (duration: 24.101069ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:37:21.420477Z","caller":"traceutil/trace.go:171","msg":"trace[602685151] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"171.57498ms","start":"2026-08-10T22:37:21.24888Z","end":"2026-08-10T22:37:21.420455Z","steps":["trace[602685151] 'process raft request' (duration: 171.311344ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:21.420529Z","caller":"traceutil/trace.go:171","msg":"trace[1950914706] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"171.446678ms","start":"2026-08-10T22:37:21.249057Z","end":"2026-08-10T22:37:21.420504Z","steps":["trace[1950914706] 'process raft request' (duration: 171.260443ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:41.712907Z","caller":"traceutil/trace.go:171","msg":"trace[1753426810] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"108.236611ms","start":"2026-08-10T22:37:41.604643Z","end":"2026-08-10T22:37:41.71288Z","steps":["trace[1753426810] 'read index received' (duration: 107.925643ms)","trace[1753426810] 'applied index is now lower than readState.Index' (duration: 309.778µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:37:41.712981Z","caller":"traceutil/trace.go:171","msg":"trace[1376150106] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"134.488853ms","start":"2026-08-10T22:37:41.578453Z","end":"2026-08-10T22:37:41.712942Z","steps":["trace[1376150106] 'process raft request' (duration: 134.256388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:37:41.71318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.496497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:37:41.713302Z","caller":"traceutil/trace.go:171","msg":"trace[929267154] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5461; }","duration":"108.695201ms","start":"2026-08-10T22:37:41.604578Z","end":"2026-08-10T22:37:41.713274Z","steps":["trace[929267154] 'agreement among raft nodes before linearized reading' (duration: 108.483316ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:52.296828Z","caller":"traceutil/trace.go:171","msg":"trace[647124873] transaction","detail":"{read_only:false; response_revision:5592; number_of_response:1; }","duration":"104.394532ms","start":"2026-08-10T22:37:52.192402Z","end":"2026-08-10T22:37:52.296796Z","steps":["trace[647124873] 'process raft request' (duration: 104.288379ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:52.296839Z","caller":"traceutil/trace.go:171","msg":"trace[234060853] transaction","detail":"{read_only:false; response_revision:5591; number_of_response:1; }","duration":"105.428588ms","start":"2026-08-10T22:37:52.191368Z","end":"2026-08-10T22:37:52.296797Z","steps":["trace[234060853] 'process raft request' (duration: 105.028198ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:37:52.296969Z","caller":"traceutil/trace.go:171","msg":"trace[1614347382] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"104.239628ms","start":"2026-08-10T22:37:52.1927Z","end":"2026-08-10T22:37:52.29694Z","steps":["trace[1614347382] 'process raft request' (duration: 104.044374ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:31.874944Z","caller":"traceutil/trace.go:171","msg":"trace[822033011] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"101.019029ms","start":"2026-08-10T22:38:31.773902Z","end":"2026-08-10T22:38:31.874921Z","steps":["trace[822033011] 'process raft request' (duration: 35.552303ms)","trace[822033011] 'compare' (duration: 65.296972ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:38:54.463513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3003} {"level":"info","ts":"2026-08-10T22:38:54.54561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3003,"took":"80.403046ms","hash":4001325869,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T22:38:54.545684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4001325869,"revision":3003,"compact-revision":-1} {"level":"info","ts":"2026-08-10T22:43:52.76736Z","caller":"traceutil/trace.go:171","msg":"trace[1211318366] linearizableReadLoop","detail":"{readStateIndex:8578; appliedIndex:8577; }","duration":"245.193775ms","start":"2026-08-10T22:43:52.522133Z","end":"2026-08-10T22:43:52.767327Z","steps":["trace[1211318366] 'read index received' (duration: 244.774555ms)","trace[1211318366] 'applied index is now lower than readState.Index' (duration: 417.75µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:43:52.767417Z","caller":"traceutil/trace.go:171","msg":"trace[62534555] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"249.366608ms","start":"2026-08-10T22:43:52.518006Z","end":"2026-08-10T22:43:52.767373Z","steps":["trace[62534555] 'process raft request' (duration: 249.132432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:52.767615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.453751ms","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-10T22:43:52.767661Z","caller":"traceutil/trace.go:171","msg":"trace[444939146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8289; }","duration":"245.544764ms","start":"2026-08-10T22:43:52.522105Z","end":"2026-08-10T22:43:52.76765Z","steps":["trace[444939146] 'agreement among raft nodes before linearized reading' (duration: 245.352499ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:53.501375Z","caller":"traceutil/trace.go:171","msg":"trace[1327736983] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"159.6391ms","start":"2026-08-10T22:43:53.341706Z","end":"2026-08-10T22:43:53.501345Z","steps":["trace[1327736983] 'process raft request' (duration: 159.418464ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:54.492787Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6111} {"level":"info","ts":"2026-08-10T22:43:54.774583Z","caller":"traceutil/trace.go:171","msg":"trace[1341966142] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"104.38133ms","start":"2026-08-10T22:43:54.670176Z","end":"2026-08-10T22:43:54.774558Z","steps":["trace[1341966142] 'process raft request' (duration: 104.224566ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:54.810585Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6111,"took":"316.118422ms","hash":2883757340,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":23011328,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-10T22:43:54.810671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2883757340,"revision":6111,"compact-revision":3003} {"level":"warn","ts":"2026-08-10T22:44:22.142862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.160753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:44:22.143017Z","caller":"traceutil/trace.go:171","msg":"trace[111451747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8498; }","duration":"195.392748ms","start":"2026-08-10T22:44:21.947598Z","end":"2026-08-10T22:44:22.142991Z","steps":["trace[111451747] 'range keys from in-memory index tree' (duration: 195.037031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:44:22.143056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.63327ms","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-10T22:44:22.143172Z","caller":"traceutil/trace.go:171","msg":"trace[1277271807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8498; }","duration":"363.805724ms","start":"2026-08-10T22:44:21.779342Z","end":"2026-08-10T22:44:22.143147Z","steps":["trace[1277271807] 'range keys from in-memory index tree' (duration: 363.319392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:44:22.143228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:44:21.779291Z","time spent":"363.927018ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:44:22.14366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.700751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:44:22.143759Z","caller":"traceutil/trace.go:171","msg":"trace[1390101472] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8498; }","duration":"358.854774ms","start":"2026-08-10T22:44:21.784881Z","end":"2026-08-10T22:44:22.143736Z","steps":["trace[1390101472] 'count revisions from in-memory index tree' (duration: 358.590566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:44:22.143803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:44:21.78486Z","time spent":"358.933806ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-10T22:44:34.881198Z","caller":"traceutil/trace.go:171","msg":"trace[2104834932] linearizableReadLoop","detail":"{readStateIndex:8914; appliedIndex:8913; }","duration":"209.644799ms","start":"2026-08-10T22:44:34.67153Z","end":"2026-08-10T22:44:34.881175Z","steps":["trace[2104834932] 'read index received' (duration: 136.323065ms)","trace[2104834932] 'applied index is now lower than readState.Index' (duration: 73.320904ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:44:34.881418Z","caller":"traceutil/trace.go:171","msg":"trace[141511779] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8610; }","duration":"234.025241ms","start":"2026-08-10T22:44:34.647382Z","end":"2026-08-10T22:44:34.881407Z","steps":["trace[141511779] 'process raft request' (duration: 160.393158ms)","trace[141511779] 'compare' (duration: 73.15088ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:44:34.881586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.797207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:44:34.881698Z","caller":"traceutil/trace.go:171","msg":"trace[1816721592] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8610; }","duration":"112.982931ms","start":"2026-08-10T22:44:34.76869Z","end":"2026-08-10T22:44:34.881673Z","steps":["trace[1816721592] 'agreement among raft nodes before linearized reading' (duration: 112.795367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:44:34.881802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.252746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g4qll\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-08-10T22:44:34.881888Z","caller":"traceutil/trace.go:171","msg":"trace[1467982152] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-g4qll; range_end:; response_count:1; response_revision:8610; }","duration":"210.393029ms","start":"2026-08-10T22:44:34.671474Z","end":"2026-08-10T22:44:34.881867Z","steps":["trace[1467982152] 'agreement among raft nodes before linearized reading' (duration: 210.141503ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:44:35.577081Z","caller":"traceutil/trace.go:171","msg":"trace[1894737572] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"103.804535ms","start":"2026-08-10T22:44:35.473245Z","end":"2026-08-10T22:44:35.57705Z","steps":["trace[1894737572] 'process raft request' (duration: 103.637161ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:44:36.455639Z","caller":"traceutil/trace.go:171","msg":"trace[1028059400] linearizableReadLoop","detail":"{readStateIndex:8923; appliedIndex:8922; }","duration":"200.377902ms","start":"2026-08-10T22:44:36.255228Z","end":"2026-08-10T22:44:36.455606Z","steps":["trace[1028059400] 'read index received' (duration: 200.237828ms)","trace[1028059400] 'applied index is now lower than readState.Index' (duration: 138.204µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:44:36.455689Z","caller":"traceutil/trace.go:171","msg":"trace[795893982] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"237.938486ms","start":"2026-08-10T22:44:36.217704Z","end":"2026-08-10T22:44:36.455642Z","steps":["trace[795893982] 'process raft request' (duration: 237.730301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:44:36.455989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.740741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T22:44:36.456045Z","caller":"traceutil/trace.go:171","msg":"trace[1761120208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8619; }","duration":"200.845194ms","start":"2026-08-10T22:44:36.255189Z","end":"2026-08-10T22:44:36.456034Z","steps":["trace[1761120208] 'agreement among raft nodes before linearized reading' (duration: 200.620189ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:24.663539Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024bc.wal"} {"level":"info","ts":"2026-08-10T22:45:47.433549Z","caller":"traceutil/trace.go:171","msg":"trace[2062637362] transaction","detail":"{read_only:false; response_revision:9338; number_of_response:1; }","duration":"112.844769ms","start":"2026-08-10T22:45:47.320658Z","end":"2026-08-10T22:45:47.433503Z","steps":["trace[2062637362] 'process raft request' (duration: 72.318642ms)","trace[2062637362] 'compare' (duration: 40.2204ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:47.4337Z","caller":"traceutil/trace.go:171","msg":"trace[762554701] linearizableReadLoop","detail":"{readStateIndex:9659; appliedIndex:9658; }","duration":"112.862029ms","start":"2026-08-10T22:45:47.320811Z","end":"2026-08-10T22:45:47.433673Z","steps":["trace[762554701] 'read index received' (duration: 72.121237ms)","trace[762554701] 'applied index is now lower than readState.Index' (duration: 40.739032ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:47.433919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.095945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8e9d2dca-e124-493b-a56d-d9b5f44a4376\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-10T22:45:47.433983Z","caller":"traceutil/trace.go:171","msg":"trace[148460823] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8e9d2dca-e124-493b-a56d-d9b5f44a4376; range_end:; response_count:1; response_revision:9339; }","duration":"113.214668ms","start":"2026-08-10T22:45:47.32075Z","end":"2026-08-10T22:45:47.433964Z","steps":["trace[148460823] 'agreement among raft nodes before linearized reading' (duration: 113.039743ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:46:32.64187Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cf8c3a138c9a77a2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:46:32.647848Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:46:32.647978Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-10T22:48:54.499652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8299} {"level":"info","ts":"2026-08-10T22:48:54.574497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8299,"took":"72.559952ms","hash":1897522701,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":25427968,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-10T22:48:54.574567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897522701,"revision":8299,"compact-revision":6111} {"level":"info","ts":"2026-08-10T22:49:29.270358Z","caller":"traceutil/trace.go:171","msg":"trace[1322047691] transaction","detail":"{read_only:false; response_revision:11173; number_of_response:1; }","duration":"179.625824ms","start":"2026-08-10T22:49:29.090693Z","end":"2026-08-10T22:49:29.270319Z","steps":["trace[1322047691] 'process raft request' (duration: 179.361847ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:54.506001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10798} {"level":"info","ts":"2026-08-10T22:53:54.581335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10798,"took":"72.914282ms","hash":1691891877,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":27713536,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-10T22:53:54.581393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1691891877,"revision":10798,"compact-revision":8299} {"level":"warn","ts":"2026-08-10T22:55:58.462803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.869351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:55:58.462962Z","caller":"traceutil/trace.go:171","msg":"trace[1285158846] linearizableReadLoop","detail":"{readStateIndex:14263; appliedIndex:14262; }","duration":"337.903875ms","start":"2026-08-10T22:55:58.125034Z","end":"2026-08-10T22:55:58.462938Z","steps":["trace[1285158846] 'read index received' (duration: 146.623254ms)","trace[1285158846] 'applied index is now lower than readState.Index' (duration: 191.278741ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:55:58.463094Z","caller":"traceutil/trace.go:171","msg":"trace[911670481] transaction","detail":"{read_only:false; response_revision:13793; number_of_response:1; }","duration":"348.081816ms","start":"2026-08-10T22:55:58.114994Z","end":"2026-08-10T22:55:58.463076Z","steps":["trace[911670481] 'process raft request' (duration: 156.790304ms)","trace[911670481] 'compare' (duration: 190.660785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:58.463185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.114971Z","time spent":"348.166757ms","remote":"127.0.0.1:36714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12709,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:58.463478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.347786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-10T22:55:58.463546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.475939ms","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-10T22:55:58.463625Z","caller":"traceutil/trace.go:171","msg":"trace[1662557834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13793; }","duration":"338.544541ms","start":"2026-08-10T22:55:58.125058Z","end":"2026-08-10T22:55:58.463602Z","steps":["trace[1662557834] 'agreement among raft nodes before linearized reading' (duration: 338.218213ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:58.463642Z","caller":"traceutil/trace.go:171","msg":"trace[991010209] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13793; }","duration":"338.614992ms","start":"2026-08-10T22:55:58.125Z","end":"2026-08-10T22:55:58.463615Z","steps":["trace[991010209] 'agreement among raft nodes before linearized reading' (duration: 338.272453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:58.463679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.124983Z","time spent":"338.690604ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-10T22:55:58.463678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.125035Z","time spent":"338.633373ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-10T22:55:58.973992Z","caller":"traceutil/trace.go:171","msg":"trace[855907063] transaction","detail":"{read_only:false; response_revision:13794; number_of_response:1; }","duration":"505.678658ms","start":"2026-08-10T22:55:58.468283Z","end":"2026-08-10T22:55:58.973961Z","steps":["trace[855907063] 'process raft request' (duration: 488.424234ms)","trace[855907063] 'compare' (duration: 16.941546ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:58.974171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.46824Z","time spent":"505.827441ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:55:59.014819Z","caller":"traceutil/trace.go:171","msg":"trace[553022703] linearizableReadLoop","detail":"{readStateIndex:14267; appliedIndex:14263; }","duration":"440.886057ms","start":"2026-08-10T22:55:58.573912Z","end":"2026-08-10T22:55:59.014798Z","steps":["trace[553022703] 'read index received' (duration: 382.805389ms)","trace[553022703] 'applied index is now lower than readState.Index' (duration: 58.079598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:59.015017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.082681ms","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-10T22:55:59.014976Z","caller":"traceutil/trace.go:171","msg":"trace[2137203763] transaction","detail":"{read_only:false; response_revision:13795; number_of_response:1; }","duration":"546.301756ms","start":"2026-08-10T22:55:58.468604Z","end":"2026-08-10T22:55:59.014905Z","steps":["trace[2137203763] 'process raft request' (duration: 546.015849ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:59.015068Z","caller":"traceutil/trace.go:171","msg":"trace[935921357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13797; }","duration":"441.183434ms","start":"2026-08-10T22:55:58.573874Z","end":"2026-08-10T22:55:59.015057Z","steps":["trace[935921357] 'agreement among raft nodes before linearized reading' (duration: 441.0036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:59.015105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.573857Z","time spent":"441.240555ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-10T22:55:59.015092Z","caller":"traceutil/trace.go:171","msg":"trace[497914392] transaction","detail":"{read_only:false; response_revision:13796; number_of_response:1; }","duration":"545.910987ms","start":"2026-08-10T22:55:58.46911Z","end":"2026-08-10T22:55:59.015021Z","steps":["trace[497914392] 'process raft request' (duration: 545.62636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:59.01528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.468594Z","time spent":"546.520491ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:59.015472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.469082Z","time spent":"546.093211ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:55:59.015969Z","caller":"traceutil/trace.go:171","msg":"trace[654179355] transaction","detail":"{read_only:false; response_revision:13797; number_of_response:1; }","duration":"457.33969ms","start":"2026-08-10T22:55:58.558263Z","end":"2026-08-10T22:55:59.015603Z","steps":["trace[654179355] 'process raft request' (duration: 456.49964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:59.016541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:58.558231Z","time spent":"458.122348ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:57:47.68345Z","caller":"traceutil/trace.go:171","msg":"trace[2108871924] transaction","detail":"{read_only:false; response_revision:14907; number_of_response:1; }","duration":"127.039243ms","start":"2026-08-10T22:57:47.556383Z","end":"2026-08-10T22:57:47.683422Z","steps":["trace[2108871924] 'process raft request' (duration: 113.829818ms)","trace[2108871924] 'compare' (duration: 13.031901ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:57:47.683456Z","caller":"traceutil/trace.go:171","msg":"trace[535445325] linearizableReadLoop","detail":"{readStateIndex:15413; appliedIndex:15412; }","duration":"124.86354ms","start":"2026-08-10T22:57:47.558549Z","end":"2026-08-10T22:57:47.683412Z","steps":["trace[535445325] 'read index received' (duration: 111.569313ms)","trace[535445325] 'applied index is now lower than readState.Index' (duration: 13.288937ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:57:47.683613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.038074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:57:47.684529Z","caller":"traceutil/trace.go:171","msg":"trace[1123007380] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14907; }","duration":"125.970286ms","start":"2026-08-10T22:57:47.558521Z","end":"2026-08-10T22:57:47.684491Z","steps":["trace[1123007380] 'agreement among raft nodes before linearized reading' (duration: 124.983512ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:58:54.512368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12971} {"level":"info","ts":"2026-08-10T22:58:54.59101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12971,"took":"75.211709ms","hash":3348564438,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T22:58:54.591132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3348564438,"revision":12971,"compact-revision":10798} {"level":"warn","ts":"2026-08-10T23:03:20.524558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.349995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-lqmwd\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-08-10T23:03:20.524735Z","caller":"traceutil/trace.go:171","msg":"trace[60749974] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-lqmwd; range_end:; response_count:1; response_revision:17463; }","duration":"128.56354ms","start":"2026-08-10T23:03:20.396156Z","end":"2026-08-10T23:03:20.52472Z","steps":["trace[60749974] 'range keys from in-memory index tree' (duration: 128.217251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:20.524942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.43115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:03:20.525005Z","caller":"traceutil/trace.go:171","msg":"trace[84556636] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:17463; }","duration":"109.520252ms","start":"2026-08-10T23:03:20.415471Z","end":"2026-08-10T23:03:20.524992Z","steps":["trace[84556636] 'count revisions from in-memory index tree' (duration: 109.320507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:27.660479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.382209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-10T23:03:27.660357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.690635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-10T23:03:27.660606Z","caller":"traceutil/trace.go:171","msg":"trace[547850995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17499; }","duration":"203.984352ms","start":"2026-08-10T23:03:27.456595Z","end":"2026-08-10T23:03:27.660579Z","steps":["trace[547850995] 'range keys from in-memory index tree' (duration: 203.492401ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:27.660551Z","caller":"traceutil/trace.go:171","msg":"trace[1738354304] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:17499; }","duration":"116.515543ms","start":"2026-08-10T23:03:27.544016Z","end":"2026-08-10T23:03:27.660531Z","steps":["trace[1738354304] 'range keys from in-memory index tree' (duration: 116.168564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:27.660974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.682078ms","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-10T23:03:27.661019Z","caller":"traceutil/trace.go:171","msg":"trace[635272171] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17499; }","duration":"177.785991ms","start":"2026-08-10T23:03:27.483219Z","end":"2026-08-10T23:03:27.661005Z","steps":["trace[635272171] 'range keys from in-memory index tree' (duration: 177.495843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:27.661044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.838705ms","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-10T23:03:27.661069Z","caller":"traceutil/trace.go:171","msg":"trace[873717098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17499; }","duration":"202.909908ms","start":"2026-08-10T23:03:27.458153Z","end":"2026-08-10T23:03:27.661063Z","steps":["trace[873717098] 'range keys from in-memory index tree' (duration: 202.691893ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:28.08007Z","caller":"traceutil/trace.go:171","msg":"trace[1768124609] linearizableReadLoop","detail":"{readStateIndex:18094; appliedIndex:18090; }","duration":"394.140237ms","start":"2026-08-10T23:03:27.685904Z","end":"2026-08-10T23:03:28.080044Z","steps":["trace[1768124609] 'read index received' (duration: 72.377288ms)","trace[1768124609] 'applied index is now lower than readState.Index' (duration: 321.761449ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:03:28.080265Z","caller":"traceutil/trace.go:171","msg":"trace[1324259528] transaction","detail":"{read_only:false; response_revision:17504; number_of_response:1; }","duration":"338.09811ms","start":"2026-08-10T23:03:27.742155Z","end":"2026-08-10T23:03:28.080253Z","steps":["trace[1324259528] 'process raft request' (duration: 337.750671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:28.080389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:27.742136Z","time spent":"338.158331ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:03:28.080378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.81233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:03:28.08046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.569618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5xkxc\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-08-10T23:03:28.080576Z","caller":"traceutil/trace.go:171","msg":"trace[1917160618] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5xkxc; range_end:; response_count:1; response_revision:17504; }","duration":"370.742212ms","start":"2026-08-10T23:03:27.709805Z","end":"2026-08-10T23:03:28.080547Z","steps":["trace[1917160618] 'agreement among raft nodes before linearized reading' (duration: 370.470725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:28.080634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:27.709781Z","time spent":"370.840935ms","remote":"127.0.0.1:36714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22152,"request content":"key:\"/registry/pods/openstack/nova-compute-default-5xkxc\" "} {"level":"warn","ts":"2026-08-10T23:03:28.080674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.765962ms","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-10T23:03:28.080722Z","caller":"traceutil/trace.go:171","msg":"trace[277651520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17504; }","duration":"394.847964ms","start":"2026-08-10T23:03:27.685862Z","end":"2026-08-10T23:03:28.08071Z","steps":["trace[277651520] 'agreement among raft nodes before linearized reading' (duration: 394.666369ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:28.080477Z","caller":"traceutil/trace.go:171","msg":"trace[510220790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17504; }","duration":"131.964894ms","start":"2026-08-10T23:03:27.948492Z","end":"2026-08-10T23:03:28.080457Z","steps":["trace[510220790] 'agreement among raft nodes before linearized reading' (duration: 131.79576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:28.080766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:27.685845Z","time spent":"394.911326ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T23:03:28.081107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.716706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lb54z\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-08-10T23:03:28.08121Z","caller":"traceutil/trace.go:171","msg":"trace[315245683] transaction","detail":"{read_only:false; response_revision:17503; number_of_response:1; }","duration":"402.572386ms","start":"2026-08-10T23:03:27.677426Z","end":"2026-08-10T23:03:28.079998Z","steps":["trace[315245683] 'process raft request' (duration: 383.171899ms)","trace[315245683] 'compare' (duration: 18.559256ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:03:28.081327Z","caller":"traceutil/trace.go:171","msg":"trace[160306046] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lb54z; range_end:; response_count:1; response_revision:17504; }","duration":"255.911481ms","start":"2026-08-10T23:03:27.825329Z","end":"2026-08-10T23:03:28.081241Z","steps":["trace[160306046] 'agreement among raft nodes before linearized reading' (duration: 255.189622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:28.084081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:27.677389Z","time spent":"403.889819ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:03:54.519612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15355} {"level":"info","ts":"2026-08-10T23:03:54.616184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15355,"took":"92.662917ms","hash":3353231218,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":32673792,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T23:03:54.616264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3353231218,"revision":15355,"compact-revision":12971} {"level":"info","ts":"2026-08-10T23:04:20.506746Z","caller":"traceutil/trace.go:171","msg":"trace[2146339480] transaction","detail":"{read_only:false; response_revision:18152; number_of_response:1; }","duration":"108.001975ms","start":"2026-08-10T23:04:20.398722Z","end":"2026-08-10T23:04:20.506724Z","steps":["trace[2146339480] 'process raft request' (duration: 107.78936ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:07:03.023419Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cf8c3a138c9a77a2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:07:03.028402Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-10T23:07:03.028495Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-10T23:08:50.806269Z","caller":"traceutil/trace.go:171","msg":"trace[1697749175] transaction","detail":"{read_only:false; response_revision:20108; number_of_response:1; }","duration":"213.812295ms","start":"2026-08-10T23:08:50.592426Z","end":"2026-08-10T23:08:50.806238Z","steps":["trace[1697749175] 'process raft request' (duration: 213.641591ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:08:54.525766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17804} {"level":"info","ts":"2026-08-10T23:08:54.639907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17804,"took":"110.951852ms","hash":4280193509,"current-db-size-bytes":58273792,"current-db-size":"58 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:08:54.639994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4280193509,"revision":17804,"compact-revision":15355} {"level":"info","ts":"2026-08-10T23:09:06.387732Z","caller":"traceutil/trace.go:171","msg":"trace[1412179186] transaction","detail":"{read_only:false; response_revision:20186; number_of_response:1; }","duration":"117.329618ms","start":"2026-08-10T23:09:06.270365Z","end":"2026-08-10T23:09:06.387695Z","steps":["trace[1412179186] 'process raft request' (duration: 117.048711ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:06.738065Z","caller":"traceutil/trace.go:171","msg":"trace[1902779327] transaction","detail":"{read_only:false; response_revision:20187; number_of_response:1; }","duration":"325.182798ms","start":"2026-08-10T23:09:06.412847Z","end":"2026-08-10T23:09:06.73803Z","steps":["trace[1902779327] 'process raft request' (duration: 324.918882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:06.738246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.412798Z","time spent":"325.348682ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:09:07.109455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.542011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:07.109688Z","caller":"traceutil/trace.go:171","msg":"trace[2088593537] transaction","detail":"{read_only:false; response_revision:20189; number_of_response:1; }","duration":"243.777961ms","start":"2026-08-10T23:09:06.865889Z","end":"2026-08-10T23:09:07.109667Z","steps":["trace[2088593537] 'process raft request' (duration: 243.687379ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:07.109823Z","caller":"traceutil/trace.go:171","msg":"trace[101939248] transaction","detail":"{read_only:false; response_revision:20188; number_of_response:1; }","duration":"384.141844ms","start":"2026-08-10T23:09:06.725642Z","end":"2026-08-10T23:09:07.109784Z","steps":["trace[101939248] 'process raft request' (duration: 86.185153ms)","trace[101939248] 'compare' (duration: 297.305624ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:07.109988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.725601Z","time spent":"384.300088ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:07.269335Z","caller":"traceutil/trace.go:171","msg":"trace[1810741242] linearizableReadLoop","detail":"{readStateIndex:20869; appliedIndex:20866; }","duration":"172.725148ms","start":"2026-08-10T23:09:07.09658Z","end":"2026-08-10T23:09:07.269305Z","steps":["trace[1810741242] 'read index received' (duration: 2.999503ms)","trace[1810741242] 'applied index is now lower than readState.Index' (duration: 169.720975ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:07.269387Z","caller":"traceutil/trace.go:171","msg":"trace[134360580] transaction","detail":"{read_only:false; response_revision:20190; number_of_response:1; }","duration":"339.419379ms","start":"2026-08-10T23:09:06.929922Z","end":"2026-08-10T23:09:07.269342Z","steps":["trace[134360580] 'process raft request' (duration: 336.049335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.269621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:06.929892Z","time spent":"339.593272ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:09:07.26964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.028555ms","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-10T23:09:07.269733Z","caller":"traceutil/trace.go:171","msg":"trace[1373628788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20190; }","duration":"173.165739ms","start":"2026-08-10T23:09:07.096543Z","end":"2026-08-10T23:09:07.269709Z","steps":["trace[1373628788] 'agreement among raft nodes before linearized reading' (duration: 172.879842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.726358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.948903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-10T23:09:07.726426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.152316ms","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-10T23:09:07.726461Z","caller":"traceutil/trace.go:171","msg":"trace[358939923] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:20192; }","duration":"164.133538ms","start":"2026-08-10T23:09:07.562306Z","end":"2026-08-10T23:09:07.72644Z","steps":["trace[358939923] 'range keys from in-memory index tree' (duration: 163.724578ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:07.726518Z","caller":"traceutil/trace.go:171","msg":"trace[2077068223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20192; }","duration":"157.292509ms","start":"2026-08-10T23:09:07.569203Z","end":"2026-08-10T23:09:07.726495Z","steps":["trace[2077068223] 'range keys from in-memory index tree' (duration: 156.95202ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:07.949138Z","caller":"traceutil/trace.go:171","msg":"trace[1331512632] transaction","detail":"{read_only:false; response_revision:20194; number_of_response:1; }","duration":"216.937211ms","start":"2026-08-10T23:09:07.732168Z","end":"2026-08-10T23:09:07.949105Z","steps":["trace[1331512632] 'process raft request' (duration: 216.750947ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:07.949304Z","caller":"traceutil/trace.go:171","msg":"trace[2117527153] linearizableReadLoop","detail":"{readStateIndex:20875; appliedIndex:20872; }","duration":"209.811888ms","start":"2026-08-10T23:09:07.739473Z","end":"2026-08-10T23:09:07.949285Z","steps":["trace[2117527153] 'read index received' (duration: 187.135222ms)","trace[2117527153] 'applied index is now lower than readState.Index' (duration: 22.674636ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:07.949306Z","caller":"traceutil/trace.go:171","msg":"trace[14975663] transaction","detail":"{read_only:false; response_revision:20193; number_of_response:1; }","duration":"217.463804ms","start":"2026-08-10T23:09:07.731806Z","end":"2026-08-10T23:09:07.94927Z","steps":["trace[14975663] 'process raft request' (duration: 194.781298ms)","trace[14975663] 'compare' (duration: 21.676052ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:07.950572Z","caller":"traceutil/trace.go:171","msg":"trace[1174156945] transaction","detail":"{read_only:false; response_revision:20195; number_of_response:1; }","duration":"211.503269ms","start":"2026-08-10T23:09:07.738854Z","end":"2026-08-10T23:09:07.950357Z","steps":["trace[1174156945] 'process raft request' (duration: 210.192947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.950825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.170179ms","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-10T23:09:07.950938Z","caller":"traceutil/trace.go:171","msg":"trace[873557277] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20195; }","duration":"206.306481ms","start":"2026-08-10T23:09:07.744591Z","end":"2026-08-10T23:09:07.950898Z","steps":["trace[873557277] 'agreement among raft nodes before linearized reading' (duration: 205.664036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:07.952184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.685248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18259"} {"level":"info","ts":"2026-08-10T23:09:07.952277Z","caller":"traceutil/trace.go:171","msg":"trace[1030294409] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20195; }","duration":"212.77991ms","start":"2026-08-10T23:09:07.739458Z","end":"2026-08-10T23:09:07.952237Z","steps":["trace[1030294409] 'agreement among raft nodes before linearized reading' (duration: 209.94144ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:03.008973Z","caller":"traceutil/trace.go:171","msg":"trace[722044756] transaction","detail":"{read_only:false; response_revision:22647; number_of_response:1; }","duration":"187.048614ms","start":"2026-08-10T23:13:02.821877Z","end":"2026-08-10T23:13:03.008926Z","steps":["trace[722044756] 'process raft request' (duration: 186.635664ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:54.532043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20125} {"level":"info","ts":"2026-08-10T23:13:54.641982Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20125,"took":"105.896722ms","hash":909162405,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":42426368,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-10T23:13:54.642036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":909162405,"revision":20125,"compact-revision":17804} {"level":"info","ts":"2026-08-10T23:18:54.54363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23136} {"level":"info","ts":"2026-08-10T23:18:54.688713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23136,"took":"140.974102ms","hash":1586667434,"current-db-size-bytes":59539456,"current-db-size":"60 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:18:54.688808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586667434,"revision":23136,"compact-revision":20125}