{"level":"warn","ts":"2026-08-28T03:58:52.853097Z","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-28T03:58:52.853206Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.28:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.28:2380","--initial-cluster=instance=https://162.253.55.28:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.28:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.28: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-28T03:58:52.853284Z","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-28T03:58:52.853295Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-08-28T03:58:52.85332Z","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-28T03:58:52.854348Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"]} {"level":"info","ts":"2026-08-28T03:58:52.854433Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.28: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-28T03:58:52.858453Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.776099ms"} {"level":"info","ts":"2026-08-28T03:58:52.868955Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"328ca0518abde8c2","cluster-id":"3b57b7d6a07cbbf8"} {"level":"info","ts":"2026-08-28T03:58:52.869051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T03:58:52.869076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 0"} {"level":"info","ts":"2026-08-28T03:58:52.869085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 328ca0518abde8c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T03:58:52.869092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 1"} {"level":"info","ts":"2026-08-28T03:58:52.869134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"warn","ts":"2026-08-28T03:58:52.877129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T03:58:52.880196Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T03:58:52.882795Z","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-28T03:58:52.885211Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"328ca0518abde8c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T03:58:52.886326Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"328ca0518abde8c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T03:58:52.888635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"info","ts":"2026-08-28T03:58:52.888764Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","added-peer-id":"328ca0518abde8c2","added-peer-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-08-28T03:58:52.893972Z","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-28T03:58:52.894056Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-08-28T03:58:52.894093Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-08-28T03:58:52.894024Z","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-28T03:58:52.894168Z","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-28T03:58:52.894196Z","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-28T03:58:52.895554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"328ca0518abde8c2","initial-advertise-peer-urls":["https://162.253.55.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T03:58:52.895639Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T03:58:53.270078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T03:58:53.270163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T03:58:53.270221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgPreVoteResp from 328ca0518abde8c2 at term 1"} {"level":"info","ts":"2026-08-28T03:58:53.270251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became candidate at term 2"} {"level":"info","ts":"2026-08-28T03:58:53.270267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgVoteResp from 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-08-28T03:58:53.270291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became leader at term 2"} {"level":"info","ts":"2026-08-28T03:58:53.270324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 328ca0518abde8c2 elected leader 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-08-28T03:58:53.271599Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T03:58:53.271948Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"328ca0518abde8c2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.28:2379]}","request-path":"/0/members/328ca0518abde8c2/attributes","cluster-id":"3b57b7d6a07cbbf8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T03:58:53.272261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T03:58:53.272357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T03:58:53.272402Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T03:58:53.272636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T03:58:53.272673Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T03:58:53.272507Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T03:58:53.272698Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T03:58:53.274982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T03:58:53.276573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.28:2379"} {"level":"warn","ts":"2026-08-28T03:58:56.790939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.570665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T03:58:56.791171Z","caller":"traceutil/trace.go:171","msg":"trace[2101562612] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"691.605693ms","start":"2026-08-28T03:58:56.09952Z","end":"2026-08-28T03:58:56.791126Z","steps":["trace[2101562612] 'process raft request' (duration: 313.095097ms)","trace[2101562612] 'compare' (duration: 377.427003ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T03:58:56.791382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T03:58:56.099489Z","time spent":"691.807216ms","remote":"127.0.0.1:34318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T03:58:57.09593Z","caller":"traceutil/trace.go:171","msg":"trace[735602402] linearizableReadLoop","detail":"{readStateIndex:113; appliedIndex:112; }","duration":"236.719151ms","start":"2026-08-28T03:58:56.859179Z","end":"2026-08-28T03:58:57.095899Z","steps":["trace[735602402] 'read index received' (duration: 228.213564ms)","trace[735602402] 'applied index is now lower than readState.Index' (duration: 8.503497ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T03:58:57.095996Z","caller":"traceutil/trace.go:171","msg":"trace[1010059217] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"298.746058ms","start":"2026-08-28T03:58:56.797207Z","end":"2026-08-28T03:58:57.095953Z","steps":["trace[1010059217] 'process raft request' (duration: 290.19024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:58:57.096228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.010425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T03:58:57.0963Z","caller":"traceutil/trace.go:171","msg":"trace[396944811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"237.123197ms","start":"2026-08-28T03:58:56.859156Z","end":"2026-08-28T03:58:57.096279Z","steps":["trace[396944811] 'agreement among raft nodes before linearized reading' (duration: 236.901694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:58:57.098949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.454645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-28T03:58:57.098998Z","caller":"traceutil/trace.go:171","msg":"trace[1497492715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:110; }","duration":"160.536496ms","start":"2026-08-28T03:58:56.93845Z","end":"2026-08-28T03:58:57.098986Z","steps":["trace[1497492715] 'agreement among raft nodes before linearized reading' (duration: 160.379214ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:33.156473Z","caller":"traceutil/trace.go:171","msg":"trace[1496835396] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"126.532595ms","start":"2026-08-28T03:59:33.02992Z","end":"2026-08-28T03:59:33.156452Z","steps":["trace[1496835396] 'process raft request' (duration: 126.468694ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:33.156524Z","caller":"traceutil/trace.go:171","msg":"trace[1894089531] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"126.623916ms","start":"2026-08-28T03:59:33.029875Z","end":"2026-08-28T03:59:33.156499Z","steps":["trace[1894089531] 'process raft request' (duration: 120.142036ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:33.156788Z","caller":"traceutil/trace.go:171","msg":"trace[365589650] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"100.141006ms","start":"2026-08-28T03:59:33.056635Z","end":"2026-08-28T03:59:33.156776Z","steps":["trace[365589650] 'process raft request' (duration: 100.008235ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:33.393922Z","caller":"traceutil/trace.go:171","msg":"trace[1229766274] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"102.416102ms","start":"2026-08-28T03:59:33.29149Z","end":"2026-08-28T03:59:33.393906Z","steps":["trace[1229766274] 'process raft request' (duration: 97.564329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:59:39.969811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.868273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nnv2p\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-28T03:59:39.969905Z","caller":"traceutil/trace.go:171","msg":"trace[2121908376] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nnv2p; range_end:; response_count:1; response_revision:599; }","duration":"139.002994ms","start":"2026-08-28T03:59:39.830884Z","end":"2026-08-28T03:59:39.969887Z","steps":["trace[2121908376] 'range keys from in-memory index tree' (duration: 138.700461ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:40.032701Z","caller":"traceutil/trace.go:171","msg":"trace[2033413439] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"155.862756ms","start":"2026-08-28T03:59:39.876818Z","end":"2026-08-28T03:59:40.032681Z","steps":["trace[2033413439] 'process raft request' (duration: 155.722564ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:40.16425Z","caller":"traceutil/trace.go:171","msg":"trace[324976435] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"127.567977ms","start":"2026-08-28T03:59:40.036654Z","end":"2026-08-28T03:59:40.164222Z","steps":["trace[324976435] 'read index received' (duration: 109.895056ms)","trace[324976435] 'applied index is now lower than readState.Index' (duration: 17.671771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T03:59:40.164396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.728179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-28T03:59:40.164359Z","caller":"traceutil/trace.go:171","msg":"trace[1515809977] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"176.759429ms","start":"2026-08-28T03:59:39.987534Z","end":"2026-08-28T03:59:40.164294Z","steps":["trace[1515809977] 'process raft request' (duration: 159.100358ms)","trace[1515809977] 'compare' (duration: 17.433709ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T03:59:40.164445Z","caller":"traceutil/trace.go:171","msg":"trace[408060674] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:601; }","duration":"127.83047ms","start":"2026-08-28T03:59:40.0366Z","end":"2026-08-28T03:59:40.16443Z","steps":["trace[408060674] 'agreement among raft nodes before linearized reading' (duration: 127.730999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:59:40.820229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16772144187024853556,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-28T03:59:40.883704Z","caller":"traceutil/trace.go:171","msg":"trace[1390186968] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"702.751889ms","start":"2026-08-28T03:59:40.180917Z","end":"2026-08-28T03:59:40.883669Z","steps":["trace[1390186968] 'process raft request' (duration: 687.723448ms)","trace[1390186968] 'compare' (duration: 14.738298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T03:59:40.883839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T03:59:40.180903Z","time spent":"702.874382ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T03:59:40.888558Z","caller":"traceutil/trace.go:171","msg":"trace[1303517276] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"568.782062ms","start":"2026-08-28T03:59:40.319748Z","end":"2026-08-28T03:59:40.88853Z","steps":["trace[1303517276] 'read index received' (duration: 548.890528ms)","trace[1303517276] 'applied index is now lower than readState.Index' (duration: 19.890274ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T03:59:40.888854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.042586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T03:59:40.888952Z","caller":"traceutil/trace.go:171","msg":"trace[1222290869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"569.228937ms","start":"2026-08-28T03:59:40.319703Z","end":"2026-08-28T03:59:40.888932Z","steps":["trace[1222290869] 'agreement among raft nodes before linearized reading' (duration: 569.012475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:59:40.88904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T03:59:40.319686Z","time spent":"569.337068ms","remote":"127.0.0.1:34024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-28T03:59:40.889268Z","caller":"traceutil/trace.go:171","msg":"trace[1273929354] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"170.037266ms","start":"2026-08-28T03:59:40.71869Z","end":"2026-08-28T03:59:40.888727Z","steps":["trace[1273929354] 'process raft request' (duration: 169.710152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:59:40.891595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.451745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-28T03:59:40.891655Z","caller":"traceutil/trace.go:171","msg":"trace[1329534283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"475.546985ms","start":"2026-08-28T03:59:40.416091Z","end":"2026-08-28T03:59:40.891638Z","steps":["trace[1329534283] 'agreement among raft nodes before linearized reading' (duration: 475.363133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T03:59:40.89174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T03:59:40.416072Z","time spent":"475.655327ms","remote":"127.0.0.1:34254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-28T03:59:43.385832Z","caller":"traceutil/trace.go:171","msg":"trace[900498535] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"153.246077ms","start":"2026-08-28T03:59:43.232563Z","end":"2026-08-28T03:59:43.385809Z","steps":["trace[900498535] 'process raft request' (duration: 151.765071ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T03:59:56.134571Z","caller":"traceutil/trace.go:171","msg":"trace[476148592] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"190.585269ms","start":"2026-08-28T03:59:55.943949Z","end":"2026-08-28T03:59:56.134534Z","steps":["trace[476148592] 'process raft request' (duration: 190.300701ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:00:27.450714Z","caller":"traceutil/trace.go:171","msg":"trace[1228726780] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"267.760082ms","start":"2026-08-28T04:00:27.18293Z","end":"2026-08-28T04:00:27.45069Z","steps":["trace[1228726780] 'read index received' (duration: 267.49266ms)","trace[1228726780] 'applied index is now lower than readState.Index' (duration: 266.332µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:00:27.450761Z","caller":"traceutil/trace.go:171","msg":"trace[330936737] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"276.373313ms","start":"2026-08-28T04:00:27.174351Z","end":"2026-08-28T04:00:27.450724Z","steps":["trace[330936737] 'process raft request' (duration: 276.185395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:00:27.45095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.808244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T04:00:27.450979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.033174ms","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-28T04:00:27.451021Z","caller":"traceutil/trace.go:171","msg":"trace[143481691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"129.92653ms","start":"2026-08-28T04:00:27.321075Z","end":"2026-08-28T04:00:27.451001Z","steps":["trace[143481691] 'agreement among raft nodes before linearized reading' (duration: 129.807645ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:00:27.451026Z","caller":"traceutil/trace.go:171","msg":"trace[99016971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"268.151349ms","start":"2026-08-28T04:00:27.182861Z","end":"2026-08-28T04:00:27.451012Z","steps":["trace[99016971] 'agreement among raft nodes before linearized reading' (duration: 267.96921ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:04.689731Z","caller":"traceutil/trace.go:171","msg":"trace[1982303643] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"133.614724ms","start":"2026-08-28T04:01:04.55608Z","end":"2026-08-28T04:01:04.689695Z","steps":["trace[1982303643] 'process raft request' (duration: 121.708913ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:15.63444Z","caller":"traceutil/trace.go:171","msg":"trace[1869844143] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"290.511618ms","start":"2026-08-28T04:01:15.343901Z","end":"2026-08-28T04:01:15.634413Z","steps":["trace[1869844143] 'process raft request' (duration: 290.354293ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:15.804493Z","caller":"traceutil/trace.go:171","msg":"trace[469541496] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1901; }","duration":"447.452397ms","start":"2026-08-28T04:01:15.357019Z","end":"2026-08-28T04:01:15.804471Z","steps":["trace[469541496] 'read index received' (duration: 277.290858ms)","trace[469541496] 'applied index is now lower than readState.Index' (duration: 170.160119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:01:15.804713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.324989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:01:15.804766Z","caller":"traceutil/trace.go:171","msg":"trace[330395721] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1830; }","duration":"153.400851ms","start":"2026-08-28T04:01:15.65135Z","end":"2026-08-28T04:01:15.804751Z","steps":["trace[330395721] 'agreement among raft nodes before linearized reading' (duration: 153.280728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:15.804778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.795108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T04:01:15.804768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.730295ms","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-28T04:01:15.80482Z","caller":"traceutil/trace.go:171","msg":"trace[1985033224] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1830; }","duration":"324.910021ms","start":"2026-08-28T04:01:15.479897Z","end":"2026-08-28T04:01:15.804807Z","steps":["trace[1985033224] 'agreement among raft nodes before linearized reading' (duration: 324.705665ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:15.804838Z","caller":"traceutil/trace.go:171","msg":"trace[939023475] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1830; }","duration":"447.850179ms","start":"2026-08-28T04:01:15.356973Z","end":"2026-08-28T04:01:15.804824Z","steps":["trace[939023475] 'agreement among raft nodes before linearized reading' (duration: 447.619531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:15.804855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:01:15.47985Z","time spent":"324.995584ms","remote":"127.0.0.1:52804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-28T04:01:15.804875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:01:15.356959Z","time spent":"447.907411ms","remote":"127.0.0.1:48084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-28T04:01:15.804563Z","caller":"traceutil/trace.go:171","msg":"trace[729115445] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"459.533374ms","start":"2026-08-28T04:01:15.344999Z","end":"2026-08-28T04:01:15.804532Z","steps":["trace[729115445] 'process raft request' (duration: 459.324657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:15.80536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.136544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f9c34fd8-a548-4009-b37e-2910e068cc56\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-28T04:01:15.805479Z","caller":"traceutil/trace.go:171","msg":"trace[706653887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f9c34fd8-a548-4009-b37e-2910e068cc56; range_end:; response_count:1; response_revision:1830; }","duration":"185.276028ms","start":"2026-08-28T04:01:15.620181Z","end":"2026-08-28T04:01:15.805457Z","steps":["trace[706653887] 'agreement among raft nodes before linearized reading' (duration: 184.715931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:15.805417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:01:15.344977Z","time spent":"460.179593ms","remote":"127.0.0.1:34254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:01:15.805899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.842144ms","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-28T04:01:15.806101Z","caller":"traceutil/trace.go:171","msg":"trace[383490647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"183.723552ms","start":"2026-08-28T04:01:15.622307Z","end":"2026-08-28T04:01:15.80603Z","steps":["trace[383490647] 'agreement among raft nodes before linearized reading' (duration: 182.451723ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:16.055189Z","caller":"traceutil/trace.go:171","msg":"trace[228072171] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"122.650259ms","start":"2026-08-28T04:01:15.93249Z","end":"2026-08-28T04:01:16.05514Z","steps":["trace[228072171] 'process raft request' (duration: 121.758241ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:29.267345Z","caller":"traceutil/trace.go:171","msg":"trace[1355812045] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"183.805089ms","start":"2026-08-28T04:01:29.083516Z","end":"2026-08-28T04:01:29.267321Z","steps":["trace[1355812045] 'process raft request' (duration: 183.725837ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:29.267738Z","caller":"traceutil/trace.go:171","msg":"trace[1036435341] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"184.160879ms","start":"2026-08-28T04:01:29.083392Z","end":"2026-08-28T04:01:29.267553Z","steps":["trace[1036435341] 'process raft request' (duration: 183.672755ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:50.041237Z","caller":"traceutil/trace.go:171","msg":"trace[1378603036] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"124.705865ms","start":"2026-08-28T04:01:49.916503Z","end":"2026-08-28T04:01:50.041209Z","steps":["trace[1378603036] 'process raft request' (duration: 124.350776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:50.283533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.079307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:01:50.28382Z","caller":"traceutil/trace.go:171","msg":"trace[463870491] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"203.823768ms","start":"2026-08-28T04:01:50.079974Z","end":"2026-08-28T04:01:50.283797Z","steps":["trace[463870491] 'read index received' (duration: 86.432093ms)","trace[463870491] 'applied index is now lower than readState.Index' (duration: 117.390105ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:01:50.283933Z","caller":"traceutil/trace.go:171","msg":"trace[1510076601] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"237.045354ms","start":"2026-08-28T04:01:50.04687Z","end":"2026-08-28T04:01:50.283915Z","steps":["trace[1510076601] 'process raft request' (duration: 119.482365ms)","trace[1510076601] 'compare' (duration: 116.946244ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:01:50.284134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.144786ms","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":"warn","ts":"2026-08-28T04:01:50.284158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.538767ms","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-28T04:01:50.284273Z","caller":"traceutil/trace.go:171","msg":"trace[2029600587] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2077; }","duration":"202.66351ms","start":"2026-08-28T04:01:50.081567Z","end":"2026-08-28T04:01:50.28423Z","steps":["trace[2029600587] 'agreement among raft nodes before linearized reading' (duration: 202.409784ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:50.28435Z","caller":"traceutil/trace.go:171","msg":"trace[1234378738] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"237.420013ms","start":"2026-08-28T04:01:50.046913Z","end":"2026-08-28T04:01:50.284333Z","steps":["trace[1234378738] 'process raft request' (duration: 236.791918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:50.28442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.558013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:01:50.284471Z","caller":"traceutil/trace.go:171","msg":"trace[1762872313] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2077; }","duration":"164.647935ms","start":"2026-08-28T04:01:50.119809Z","end":"2026-08-28T04:01:50.284457Z","steps":["trace[1762872313] 'agreement among raft nodes before linearized reading' (duration: 164.559783ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:50.284197Z","caller":"traceutil/trace.go:171","msg":"trace[2050848521] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2077; }","duration":"204.246778ms","start":"2026-08-28T04:01:50.07993Z","end":"2026-08-28T04:01:50.284177Z","steps":["trace[2050848521] 'agreement among raft nodes before linearized reading' (duration: 204.014493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:01:50.284529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.305497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-28T04:01:50.284568Z","caller":"traceutil/trace.go:171","msg":"trace[1317021443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2077; }","duration":"199.370139ms","start":"2026-08-28T04:01:50.085186Z","end":"2026-08-28T04:01:50.284557Z","steps":["trace[1317021443] 'agreement among raft nodes before linearized reading' (duration: 199.260626ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:59.58335Z","caller":"traceutil/trace.go:171","msg":"trace[273463165] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"202.467125ms","start":"2026-08-28T04:02:59.380854Z","end":"2026-08-28T04:02:59.583321Z","steps":["trace[273463165] 'process raft request' (duration: 202.254001ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:02.606092Z","caller":"traceutil/trace.go:171","msg":"trace[1080385532] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"137.150265ms","start":"2026-08-28T04:08:02.468917Z","end":"2026-08-28T04:08:02.606067Z","steps":["trace[1080385532] 'read index received' (duration: 72.71507ms)","trace[1080385532] 'applied index is now lower than readState.Index' (duration: 64.434215ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:08:02.606372Z","caller":"traceutil/trace.go:171","msg":"trace[537422845] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"158.105479ms","start":"2026-08-28T04:08:02.44825Z","end":"2026-08-28T04:08:02.606355Z","steps":["trace[537422845] 'process raft request' (duration: 93.456769ms)","trace[537422845] 'compare' (duration: 64.059715ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:08:02.606647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.71175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-28T04:08:02.60668Z","caller":"traceutil/trace.go:171","msg":"trace[65117349] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4771; }","duration":"137.777521ms","start":"2026-08-28T04:08:02.468891Z","end":"2026-08-28T04:08:02.606668Z","steps":["trace[65117349] 'agreement among raft nodes before linearized reading' (duration: 137.650947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:02.606722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.483323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-08-28T04:08:02.606792Z","caller":"traceutil/trace.go:171","msg":"trace[344346082] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4771; }","duration":"132.573917ms","start":"2026-08-28T04:08:02.474201Z","end":"2026-08-28T04:08:02.606775Z","steps":["trace[344346082] 'agreement among raft nodes before linearized reading' (duration: 132.380851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:02.607016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.381859ms","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-28T04:08:02.607044Z","caller":"traceutil/trace.go:171","msg":"trace[835085359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4771; }","duration":"125.44142ms","start":"2026-08-28T04:08:02.481595Z","end":"2026-08-28T04:08:02.607037Z","steps":["trace[835085359] 'agreement among raft nodes before linearized reading' (duration: 125.353148ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:11.659032Z","caller":"traceutil/trace.go:171","msg":"trace[1335927561] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"139.696753ms","start":"2026-08-28T04:08:11.519315Z","end":"2026-08-28T04:08:11.659012Z","steps":["trace[1335927561] 'read index received' (duration: 45.141204ms)","trace[1335927561] 'applied index is now lower than readState.Index' (duration: 94.554769ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:08:11.659253Z","caller":"traceutil/trace.go:171","msg":"trace[1529345981] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"177.90126ms","start":"2026-08-28T04:08:11.481326Z","end":"2026-08-28T04:08:11.659228Z","steps":["trace[1529345981] 'process raft request' (duration: 83.204818ms)","trace[1529345981] 'compare' (duration: 94.376814ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:08:11.659439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.912598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d773de6d-938a-493a-80cd-0aecbaa9c866\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-28T04:08:11.659469Z","caller":"traceutil/trace.go:171","msg":"trace[900753961] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d773de6d-938a-493a-80cd-0aecbaa9c866; range_end:; response_count:1; response_revision:4916; }","duration":"140.225226ms","start":"2026-08-28T04:08:11.519236Z","end":"2026-08-28T04:08:11.659461Z","steps":["trace[900753961] 'agreement among raft nodes before linearized reading' (duration: 139.850207ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:53.308985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"info","ts":"2026-08-28T04:08:53.384001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"73.813211ms","hash":1037886914,"current-db-size-bytes":52396032,"current-db-size":"52 MB","current-db-size-in-use-bytes":28577792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-28T04:08:53.384072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1037886914,"revision":2843,"compact-revision":-1} {"level":"info","ts":"2026-08-28T04:13:53.320043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5416} {"level":"info","ts":"2026-08-28T04:13:53.4058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5416,"took":"83.540975ms","hash":1239626959,"current-db-size-bytes":52396032,"current-db-size":"52 MB","current-db-size-in-use-bytes":18583552,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-28T04:13:53.405911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1239626959,"revision":5416,"compact-revision":2843}