{"level":"warn","ts":"2026-10-15T11:14:15.252613Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:14:15.252728Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T11:14:15.252805Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:14:15.25282Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-15T11:14:15.25284Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:14:15.253757Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-10-15T11:14:15.253876Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T11:14:15.261361Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.937543ms"} {"level":"info","ts":"2026-10-15T11:14:15.268047Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-10-15T11:14:15.26813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T11:14:15.268152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-10-15T11:14:15.26816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T11:14:15.268166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-10-15T11:14:15.268201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-10-15T11:14:15.274626Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T11:14:15.276918Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T11:14:15.278719Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T11:14:15.281385Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T11:14:15.28164Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T11:14:15.28173Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:14:15.282001Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:14:15.282012Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:14:15.284131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-10-15T11:14:15.284902Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-15T11:14:15.286401Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:14:15.286451Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-15T11:14:15.286494Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-15T11:14:15.286665Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T11:14:15.286697Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T11:14:15.869428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T11:14:15.869565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T11:14:15.869629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-10-15T11:14:15.869655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-10-15T11:14:15.869673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-15T11:14:15.86969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-10-15T11:14:15.869704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-15T11:14:15.870973Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:14:15.872725Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T11:14:15.873032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:14:15.873629Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:14:15.87392Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T11:14:15.873947Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T11:14:15.876492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T11:14:15.886724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-10-15T11:14:15.887616Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:14:15.887986Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:14:15.888095Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:14:24.934094Z","caller":"traceutil/trace.go:171","msg":"trace[2110581489] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"234.881834ms","start":"2026-10-15T11:14:24.699177Z","end":"2026-10-15T11:14:24.934059Z","steps":["trace[2110581489] 'process raft request' (duration: 171.044715ms)","trace[2110581489] 'compare' (duration: 63.647215ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:14:31.052807Z","caller":"traceutil/trace.go:171","msg":"trace[289555304] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"310.925036ms","start":"2026-10-15T11:14:30.741848Z","end":"2026-10-15T11:14:31.052773Z","steps":["trace[289555304] 'process raft request' (duration: 310.731102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:14:31.05371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:14:30.741833Z","time spent":"311.171832ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:15:01.2308Z","caller":"traceutil/trace.go:171","msg":"trace[246130026] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"256.90638ms","start":"2026-10-15T11:15:00.973856Z","end":"2026-10-15T11:15:01.230762Z","steps":["trace[246130026] 'process raft request' (duration: 238.348406ms)","trace[246130026] 'compare' (duration: 18.412441ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:15:01.236044Z","caller":"traceutil/trace.go:171","msg":"trace[1807746822] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"262.081296ms","start":"2026-10-15T11:15:00.973931Z","end":"2026-10-15T11:15:01.236012Z","steps":["trace[1807746822] 'process raft request' (duration: 261.712428ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.23706Z","caller":"traceutil/trace.go:171","msg":"trace[513421735] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"250.279692ms","start":"2026-10-15T11:15:00.986748Z","end":"2026-10-15T11:15:01.237027Z","steps":["trace[513421735] 'process raft request' (duration: 249.109056ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.239966Z","caller":"traceutil/trace.go:171","msg":"trace[333852254] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"249.156626ms","start":"2026-10-15T11:15:00.990766Z","end":"2026-10-15T11:15:01.239923Z","steps":["trace[333852254] 'process raft request' (duration: 248.904621ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.241564Z","caller":"traceutil/trace.go:171","msg":"trace[1809545212] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"250.720942ms","start":"2026-10-15T11:15:00.990815Z","end":"2026-10-15T11:15:01.241536Z","steps":["trace[1809545212] 'process raft request' (duration: 249.882983ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.242333Z","caller":"traceutil/trace.go:171","msg":"trace[181402773] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"244.230586ms","start":"2026-10-15T11:15:00.99807Z","end":"2026-10-15T11:15:01.242301Z","steps":["trace[181402773] 'process raft request' (duration: 242.743234ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.244507Z","caller":"traceutil/trace.go:171","msg":"trace[324842036] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"246.345674ms","start":"2026-10-15T11:15:00.998138Z","end":"2026-10-15T11:15:01.244484Z","steps":["trace[324842036] 'process raft request' (duration: 244.038732ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.24501Z","caller":"traceutil/trace.go:171","msg":"trace[1013059312] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"241.246789ms","start":"2026-10-15T11:15:01.00373Z","end":"2026-10-15T11:15:01.244977Z","steps":["trace[1013059312] 'process raft request' (duration: 240.691857ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.245406Z","caller":"traceutil/trace.go:171","msg":"trace[1484491548] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"128.635469ms","start":"2026-10-15T11:15:01.116751Z","end":"2026-10-15T11:15:01.245387Z","steps":["trace[1484491548] 'process raft request' (duration: 128.387034ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:01.245559Z","caller":"traceutil/trace.go:171","msg":"trace[2018062699] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"235.119902ms","start":"2026-10-15T11:15:01.010415Z","end":"2026-10-15T11:15:01.245535Z","steps":["trace[2018062699] 'process raft request' (duration: 234.357635ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:09.352651Z","caller":"traceutil/trace.go:171","msg":"trace[1674681368] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"394.380736ms","start":"2026-10-15T11:15:08.958242Z","end":"2026-10-15T11:15:09.352623Z","steps":["trace[1674681368] 'process raft request' (duration: 390.773925ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:09.352631Z","caller":"traceutil/trace.go:171","msg":"trace[979078619] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"196.486427ms","start":"2026-10-15T11:15:09.156079Z","end":"2026-10-15T11:15:09.352565Z","steps":["trace[979078619] 'read index received' (duration: 190.444112ms)","trace[979078619] 'applied index is now lower than readState.Index' (duration: 6.041025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:15:09.352826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:08.958217Z","time spent":"394.520839ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:15:09.352925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.842006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T11:15:09.353094Z","caller":"traceutil/trace.go:171","msg":"trace[961323830] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:659; }","duration":"197.050491ms","start":"2026-10-15T11:15:09.156026Z","end":"2026-10-15T11:15:09.353076Z","steps":["trace[961323830] 'agreement among raft nodes before linearized reading' (duration: 196.668211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:15:09.655787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.846176ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-10-15T11:15:09.655904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:09.354564Z","time spent":"301.334065ms","remote":"127.0.0.1:40224","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-15T11:15:09.710096Z","caller":"traceutil/trace.go:171","msg":"trace[1435401770] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"352.179482ms","start":"2026-10-15T11:15:09.357895Z","end":"2026-10-15T11:15:09.710075Z","steps":["trace[1435401770] 'process raft request' (duration: 351.950677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:15:09.710216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:09.35787Z","time spent":"352.276455ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:15:09.710431Z","caller":"traceutil/trace.go:171","msg":"trace[1125446894] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"271.447975ms","start":"2026-10-15T11:15:09.438954Z","end":"2026-10-15T11:15:09.710402Z","steps":["trace[1125446894] 'process raft request' (duration: 271.354663ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:09.710672Z","caller":"traceutil/trace.go:171","msg":"trace[1282852671] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"337.38961ms","start":"2026-10-15T11:15:09.373225Z","end":"2026-10-15T11:15:09.710615Z","steps":["trace[1282852671] 'process raft request' (duration: 336.789057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:15:09.710815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:09.373186Z","time spent":"337.541424ms","remote":"127.0.0.1:40406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:15:10.605746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.474057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:15:10.605988Z","caller":"traceutil/trace.go:171","msg":"trace[908100802] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"717.09586ms","start":"2026-10-15T11:15:09.888866Z","end":"2026-10-15T11:15:10.605962Z","steps":["trace[908100802] 'read index received' (duration: 268.441808ms)","trace[908100802] 'applied index is now lower than readState.Index' (duration: 448.652591ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:15:10.606081Z","caller":"traceutil/trace.go:171","msg":"trace[1458284057] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"870.36692ms","start":"2026-10-15T11:15:09.735702Z","end":"2026-10-15T11:15:10.606069Z","steps":["trace[1458284057] 'process raft request' (duration: 421.497774ms)","trace[1458284057] 'compare' (duration: 448.1589ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:15:10.606138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:09.735688Z","time spent":"870.420982ms","remote":"127.0.0.1:40406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T11:15:10.606378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.493038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T11:15:10.60641Z","caller":"traceutil/trace.go:171","msg":"trace[1308577954] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"869.133232ms","start":"2026-10-15T11:15:09.737251Z","end":"2026-10-15T11:15:10.606384Z","steps":["trace[1308577954] 'process raft request' (duration: 868.59212ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:15:10.606463Z","caller":"traceutil/trace.go:171","msg":"trace[576662714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"717.619831ms","start":"2026-10-15T11:15:09.88882Z","end":"2026-10-15T11:15:10.60644Z","steps":["trace[576662714] 'agreement among raft nodes before linearized reading' (duration: 717.266493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:15:10.606492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:09.737232Z","time spent":"869.216824ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:15:10.606514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:09.888799Z","time spent":"717.704323ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T11:15:10.606475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.271574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:15:10.606631Z","caller":"traceutil/trace.go:171","msg":"trace[563025902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"335.463659ms","start":"2026-10-15T11:15:10.271136Z","end":"2026-10-15T11:15:10.6066Z","steps":["trace[563025902] 'agreement among raft nodes before linearized reading' (duration: 335.256724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:15:10.606735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:15:10.271116Z","time spent":"335.604101ms","remote":"127.0.0.1:40188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T11:16:26.949817Z","caller":"traceutil/trace.go:171","msg":"trace[1950254128] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"180.104995ms","start":"2026-10-15T11:16:26.769691Z","end":"2026-10-15T11:16:26.949796Z","steps":["trace[1950254128] 'process raft request' (duration: 179.976203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:52.610662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.955361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:16:52.610774Z","caller":"traceutil/trace.go:171","msg":"trace[269936499] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"166.292535ms","start":"2026-10-15T11:16:52.444462Z","end":"2026-10-15T11:16:52.610754Z","steps":["trace[269936499] 'process raft request' (duration: 36.138099ms)","trace[269936499] 'compare' (duration: 129.833479ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:16:52.973561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.06026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:16:52.973787Z","caller":"traceutil/trace.go:171","msg":"trace[982864307] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1993; }","duration":"520.341838ms","start":"2026-10-15T11:16:52.453415Z","end":"2026-10-15T11:16:52.973757Z","steps":["trace[982864307] 'read index received' (duration: 27.138388ms)","trace[982864307] 'applied index is now lower than readState.Index' (duration: 493.201ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:16:52.973837Z","caller":"traceutil/trace.go:171","msg":"trace[1715648102] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"528.817618ms","start":"2026-10-15T11:16:52.44499Z","end":"2026-10-15T11:16:52.973808Z","steps":["trace[1715648102] 'process raft request' (duration: 337.368459ms)","trace[1715648102] 'compare' (duration: 190.927768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:16:52.974151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.722696ms","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-10-15T11:16:52.974217Z","caller":"traceutil/trace.go:171","msg":"trace[1285320177] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1920; }","duration":"520.807879ms","start":"2026-10-15T11:16:52.45339Z","end":"2026-10-15T11:16:52.974198Z","steps":["trace[1285320177] 'agreement among raft nodes before linearized reading' (duration: 520.497242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:52.974261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:52.453382Z","time spent":"520.86679ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-15T11:16:52.974449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:52.444974Z","time spent":"529.161115ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:16:52.974829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.684558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57fb7674-eb3b-4dec-8f3f-ea5560842507\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T11:16:52.97518Z","caller":"traceutil/trace.go:171","msg":"trace[508625037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57fb7674-eb3b-4dec-8f3f-ea5560842507; range_end:; response_count:1; response_revision:1920; }","duration":"256.080597ms","start":"2026-10-15T11:16:52.719074Z","end":"2026-10-15T11:16:52.975154Z","steps":["trace[508625037] 'agreement among raft nodes before linearized reading' (duration: 255.32268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:52.974865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.509796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57fb7674-eb3b-4dec-8f3f-ea5560842507\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T11:16:52.975444Z","caller":"traceutil/trace.go:171","msg":"trace[1686045766] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57fb7674-eb3b-4dec-8f3f-ea5560842507; range_end:; response_count:1; response_revision:1920; }","duration":"140.206221ms","start":"2026-10-15T11:16:52.835086Z","end":"2026-10-15T11:16:52.975293Z","steps":["trace[1686045766] 'agreement among raft nodes before linearized reading' (duration: 139.296661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:53.514772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.192472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:16:53.514956Z","caller":"traceutil/trace.go:171","msg":"trace[1619027551] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"314.395774ms","start":"2026-10-15T11:16:53.200531Z","end":"2026-10-15T11:16:53.514926Z","steps":["trace[1619027551] 'process raft request' (duration: 27.944966ms)","trace[1619027551] 'compare' (duration: 286.009318ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:16:53.515049Z","caller":"traceutil/trace.go:171","msg":"trace[44158933] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"300.048872ms","start":"2026-10-15T11:16:53.214972Z","end":"2026-10-15T11:16:53.515021Z","steps":["trace[44158933] 'read index received' (duration: 13.521692ms)","trace[44158933] 'applied index is now lower than readState.Index' (duration: 286.52455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:16:53.515091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:53.200493Z","time spent":"314.524357ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:16:53.515089Z","caller":"traceutil/trace.go:171","msg":"trace[1440255105] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"291.603803ms","start":"2026-10-15T11:16:53.223461Z","end":"2026-10-15T11:16:53.515065Z","steps":["trace[1440255105] 'process raft request' (duration: 291.48458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:53.515347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.351499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T11:16:53.515384Z","caller":"traceutil/trace.go:171","msg":"trace[76813109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1924; }","duration":"300.490541ms","start":"2026-10-15T11:16:53.214884Z","end":"2026-10-15T11:16:53.515374Z","steps":["trace[76813109] 'agreement among raft nodes before linearized reading' (duration: 300.274457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:53.515417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:53.21486Z","time spent":"300.546473ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T11:16:53.515576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.209383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T11:16:53.51562Z","caller":"traceutil/trace.go:171","msg":"trace[1588639799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1924; }","duration":"282.274854ms","start":"2026-10-15T11:16:53.233335Z","end":"2026-10-15T11:16:53.515609Z","steps":["trace[1588639799] 'agreement among raft nodes before linearized reading' (duration: 282.143211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:53.515584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.405045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:16:53.515694Z","caller":"traceutil/trace.go:171","msg":"trace[236348036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1924; }","duration":"244.535289ms","start":"2026-10-15T11:16:53.271118Z","end":"2026-10-15T11:16:53.515654Z","steps":["trace[236348036] 'agreement among raft nodes before linearized reading' (duration: 244.378735ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:01.691497Z","caller":"traceutil/trace.go:171","msg":"trace[1173130499] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"127.550188ms","start":"2026-10-15T11:17:01.56392Z","end":"2026-10-15T11:17:01.691471Z","steps":["trace[1173130499] 'read index received' (duration: 127.343934ms)","trace[1173130499] 'applied index is now lower than readState.Index' (duration: 205.364µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:17:01.691566Z","caller":"traceutil/trace.go:171","msg":"trace[624094987] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"149.359607ms","start":"2026-10-15T11:17:01.542175Z","end":"2026-10-15T11:17:01.691535Z","steps":["trace[624094987] 'process raft request' (duration: 149.141782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:01.691733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.413815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T11:17:01.691754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.808204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T11:17:01.691852Z","caller":"traceutil/trace.go:171","msg":"trace[1486180049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2006; }","duration":"127.973767ms","start":"2026-10-15T11:17:01.563859Z","end":"2026-10-15T11:17:01.691833Z","steps":["trace[1486180049] 'agreement among raft nodes before linearized reading' (duration: 127.734572ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:01.691775Z","caller":"traceutil/trace.go:171","msg":"trace[475482531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2006; }","duration":"115.515518ms","start":"2026-10-15T11:17:01.576249Z","end":"2026-10-15T11:17:01.691765Z","steps":["trace[475482531] 'agreement among raft nodes before linearized reading' (duration: 115.350174ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:43.429775Z","caller":"traceutil/trace.go:171","msg":"trace[2117852100] linearizableReadLoop","detail":"{readStateIndex:2335; appliedIndex:2334; }","duration":"156.473608ms","start":"2026-10-15T11:17:43.273264Z","end":"2026-10-15T11:17:43.429738Z","steps":["trace[2117852100] 'read index received' (duration: 156.169911ms)","trace[2117852100] 'applied index is now lower than readState.Index' (duration: 302.217µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:17:43.429934Z","caller":"traceutil/trace.go:171","msg":"trace[95883832] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"166.059747ms","start":"2026-10-15T11:17:43.263753Z","end":"2026-10-15T11:17:43.429812Z","steps":["trace[95883832] 'process raft request' (duration: 165.794791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:43.429999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.731863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:17:43.430097Z","caller":"traceutil/trace.go:171","msg":"trace[1365287689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2249; }","duration":"156.901747ms","start":"2026-10-15T11:17:43.273175Z","end":"2026-10-15T11:17:43.430077Z","steps":["trace[1365287689] 'agreement among raft nodes before linearized reading' (duration: 156.747554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:43.430166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.873351ms","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-10-15T11:17:43.430221Z","caller":"traceutil/trace.go:171","msg":"trace[1236047841] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2249; }","duration":"104.000004ms","start":"2026-10-15T11:17:43.326207Z","end":"2026-10-15T11:17:43.430207Z","steps":["trace[1236047841] 'agreement among raft nodes before linearized reading' (duration: 103.759819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:43.70277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.942166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:17:43.702877Z","caller":"traceutil/trace.go:171","msg":"trace[526787] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"264.888863ms","start":"2026-10-15T11:17:43.437969Z","end":"2026-10-15T11:17:43.702858Z","steps":["trace[526787] 'process raft request' (duration: 101.774233ms)","trace[526787] 'compare' (duration: 162.609158ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:17:43.951773Z","caller":"traceutil/trace.go:171","msg":"trace[1229292245] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"101.891955ms","start":"2026-10-15T11:17:43.849849Z","end":"2026-10-15T11:17:43.951741Z","steps":["trace[1229292245] 'process raft request' (duration: 101.546337ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:06.034047Z","caller":"traceutil/trace.go:171","msg":"trace[1073108281] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"163.249184ms","start":"2026-10-15T11:19:05.870764Z","end":"2026-10-15T11:19:06.034013Z","steps":["trace[1073108281] 'process raft request' (duration: 163.006829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:06.636783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.492514ms","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-10-15T11:19:06.636856Z","caller":"traceutil/trace.go:171","msg":"trace[2031535190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2890; }","duration":"460.581766ms","start":"2026-10-15T11:19:06.176259Z","end":"2026-10-15T11:19:06.636841Z","steps":["trace[2031535190] 'range keys from in-memory index tree' (duration: 460.32445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:06.636908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:06.176202Z","time spent":"460.677137ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T11:19:06.637114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.011794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:19:06.637175Z","caller":"traceutil/trace.go:171","msg":"trace[2027416991] linearizableReadLoop","detail":"{readStateIndex:3002; appliedIndex:3001; }","duration":"227.372227ms","start":"2026-10-15T11:19:06.409795Z","end":"2026-10-15T11:19:06.637167Z","steps":["trace[2027416991] 'read index received' (duration: 65.631µs)","trace[2027416991] 'applied index is now lower than readState.Index' (duration: 227.305746ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:06.637257Z","caller":"traceutil/trace.go:171","msg":"trace[504838767] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"355.492831ms","start":"2026-10-15T11:19:06.281709Z","end":"2026-10-15T11:19:06.637202Z","steps":["trace[504838767] 'process raft request' (duration: 98.324283ms)","trace[504838767] 'compare' (duration: 256.914672ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:06.637354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.575812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T11:19:06.637388Z","caller":"traceutil/trace.go:171","msg":"trace[1670665476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2891; }","duration":"227.671704ms","start":"2026-10-15T11:19:06.40971Z","end":"2026-10-15T11:19:06.637382Z","steps":["trace[1670665476] 'agreement among raft nodes before linearized reading' (duration: 227.538281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:06.637479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:06.281648Z","time spent":"355.704016ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:19:06.727971Z","caller":"traceutil/trace.go:171","msg":"trace[1754271176] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"236.511553ms","start":"2026-10-15T11:19:06.491424Z","end":"2026-10-15T11:19:06.727935Z","steps":["trace[1754271176] 'process raft request' (duration: 236.164245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:06.728051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.858803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:19:06.728085Z","caller":"traceutil/trace.go:171","msg":"trace[1980021641] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2892; }","duration":"117.981705ms","start":"2026-10-15T11:19:06.610095Z","end":"2026-10-15T11:19:06.728077Z","steps":["trace[1980021641] 'agreement among raft nodes before linearized reading' (duration: 117.847812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:06.72814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.036535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:19:06.728348Z","caller":"traceutil/trace.go:171","msg":"trace[1371498721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2892; }","duration":"317.277751ms","start":"2026-10-15T11:19:06.41104Z","end":"2026-10-15T11:19:06.728317Z","steps":["trace[1371498721] 'agreement among raft nodes before linearized reading' (duration: 316.756639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:06.728635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:06.411028Z","time spent":"317.587577ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T11:19:06.728243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.203416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T11:19:06.728906Z","caller":"traceutil/trace.go:171","msg":"trace[478097742] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2892; }","duration":"100.881511ms","start":"2026-10-15T11:19:06.627945Z","end":"2026-10-15T11:19:06.728827Z","steps":["trace[478097742] 'agreement among raft nodes before linearized reading' (duration: 99.883799ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:12.178051Z","caller":"traceutil/trace.go:171","msg":"trace[2086775255] transaction","detail":"{read_only:false; response_revision:2924; number_of_response:1; }","duration":"115.257984ms","start":"2026-10-15T11:19:12.062761Z","end":"2026-10-15T11:19:12.178019Z","steps":["trace[2086775255] 'process raft request' (duration: 115.040849ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:18.087434Z","caller":"traceutil/trace.go:171","msg":"trace[2009050056] transaction","detail":"{read_only:false; response_revision:2956; number_of_response:1; }","duration":"106.842914ms","start":"2026-10-15T11:19:17.980567Z","end":"2026-10-15T11:19:18.087409Z","steps":["trace[2009050056] 'process raft request' (duration: 106.649629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:18.31405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.369137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:19:18.314595Z","caller":"traceutil/trace.go:171","msg":"trace[1518101818] transaction","detail":"{read_only:false; response_revision:2957; number_of_response:1; }","duration":"326.767492ms","start":"2026-10-15T11:19:17.987457Z","end":"2026-10-15T11:19:18.314224Z","steps":["trace[1518101818] 'process raft request' (duration: 215.12013ms)","trace[1518101818] 'compare' (duration: 111.036317ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:18.314828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:17.987431Z","time spent":"327.272114ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:23:51.427231Z","caller":"traceutil/trace.go:171","msg":"trace[23101874] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"374.587063ms","start":"2026-10-15T11:23:51.052615Z","end":"2026-10-15T11:23:51.427202Z","steps":["trace[23101874] 'read index received' (duration: 283.816392ms)","trace[23101874] 'applied index is now lower than readState.Index' (duration: 90.765271ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:23:51.427329Z","caller":"traceutil/trace.go:171","msg":"trace[1249323721] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"411.11259ms","start":"2026-10-15T11:23:51.016179Z","end":"2026-10-15T11:23:51.427292Z","steps":["trace[1249323721] 'process raft request' (duration: 320.257237ms)","trace[1249323721] 'compare' (duration: 90.594387ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:23:51.427432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.809047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-15T11:23:51.427477Z","caller":"traceutil/trace.go:171","msg":"trace[1055349383] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5325; }","duration":"374.896619ms","start":"2026-10-15T11:23:51.052571Z","end":"2026-10-15T11:23:51.427468Z","steps":["trace[1055349383] 'agreement among raft nodes before linearized reading' (duration: 374.763866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:51.427502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:23:51.052554Z","time spent":"374.94085ms","remote":"127.0.0.1:40416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":917,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-10-15T11:23:51.4275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:23:51.016166Z","time spent":"411.243402ms","remote":"127.0.0.1:40406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:23:51.427507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.805726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-15T11:23:51.427784Z","caller":"traceutil/trace.go:171","msg":"trace[449516407] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5325; }","duration":"282.086213ms","start":"2026-10-15T11:23:51.145656Z","end":"2026-10-15T11:23:51.427742Z","steps":["trace[449516407] 'agreement among raft nodes before linearized reading' (duration: 281.743275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:51.427994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.027139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786924"} {"level":"info","ts":"2026-10-15T11:23:51.428058Z","caller":"traceutil/trace.go:171","msg":"trace[690005092] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5325; }","duration":"260.137901ms","start":"2026-10-15T11:23:51.167903Z","end":"2026-10-15T11:23:51.428041Z","steps":["trace[690005092] 'agreement among raft nodes before linearized reading' (duration: 259.632469ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:51.937043Z","caller":"traceutil/trace.go:171","msg":"trace[648809910] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"143.095873ms","start":"2026-10-15T11:23:51.793929Z","end":"2026-10-15T11:23:51.937025Z","steps":["trace[648809910] 'process raft request' (duration: 142.994661ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:51.988369Z","caller":"traceutil/trace.go:171","msg":"trace[302543733] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"175.293194ms","start":"2026-10-15T11:23:51.813036Z","end":"2026-10-15T11:23:51.98833Z","steps":["trace[302543733] 'process raft request' (duration: 175.188382ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:51.988393Z","caller":"traceutil/trace.go:171","msg":"trace[827398994] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"194.411562ms","start":"2026-10-15T11:23:51.793929Z","end":"2026-10-15T11:23:51.98834Z","steps":["trace[827398994] 'process raft request' (duration: 194.098574ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:23:51.988551Z","caller":"traceutil/trace.go:171","msg":"trace[1790191260] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"175.398166ms","start":"2026-10-15T11:23:51.81312Z","end":"2026-10-15T11:23:51.988519Z","steps":["trace[1790191260] 'process raft request' (duration: 175.154641ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:01.338756Z","caller":"traceutil/trace.go:171","msg":"trace[202695095] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5711; }","duration":"129.545189ms","start":"2026-10-15T11:24:01.209181Z","end":"2026-10-15T11:24:01.338726Z","steps":["trace[202695095] 'read index received' (duration: 24.438797ms)","trace[202695095] 'applied index is now lower than readState.Index' (duration: 105.105552ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:24:01.33912Z","caller":"traceutil/trace.go:171","msg":"trace[1587275245] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"120.664302ms","start":"2026-10-15T11:24:01.218433Z","end":"2026-10-15T11:24:01.339098Z","steps":["trace[1587275245] 'process raft request' (duration: 120.243592ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:01.339171Z","caller":"traceutil/trace.go:171","msg":"trace[125649845] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"131.627326ms","start":"2026-10-15T11:24:01.207517Z","end":"2026-10-15T11:24:01.339144Z","steps":["trace[125649845] 'process raft request' (duration: 131.049582ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:01.339283Z","caller":"traceutil/trace.go:171","msg":"trace[132259082] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"131.760539ms","start":"2026-10-15T11:24:01.207485Z","end":"2026-10-15T11:24:01.339246Z","steps":["trace[132259082] 'process raft request' (duration: 128.672919ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:01.339912Z","caller":"traceutil/trace.go:171","msg":"trace[1926082000] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"131.273607ms","start":"2026-10-15T11:24:01.207734Z","end":"2026-10-15T11:24:01.339008Z","steps":["trace[1926082000] 'process raft request' (duration: 130.897769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:24:01.339812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.605184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-15T11:24:01.3402Z","caller":"traceutil/trace.go:171","msg":"trace[780165595] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5508; }","duration":"131.002592ms","start":"2026-10-15T11:24:01.209112Z","end":"2026-10-15T11:24:01.340115Z","steps":["trace[780165595] 'agreement among raft nodes before linearized reading' (duration: 130.187974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:24:05.25742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.351221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-tw5sq\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-10-15T11:24:05.257554Z","caller":"traceutil/trace.go:171","msg":"trace[1184258419] range","detail":"{range_begin:/registry/pods/monitoring/vector-tw5sq; range_end:; response_count:1; response_revision:5568; }","duration":"115.464363ms","start":"2026-10-15T11:24:05.142013Z","end":"2026-10-15T11:24:05.257477Z","steps":["trace[1184258419] 'range keys from in-memory index tree' (duration: 115.201928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:24:05.257555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.884971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T11:24:05.257657Z","caller":"traceutil/trace.go:171","msg":"trace[284135291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5568; }","duration":"153.105796ms","start":"2026-10-15T11:24:05.104531Z","end":"2026-10-15T11:24:05.257637Z","steps":["trace[284135291] 'range keys from in-memory index tree' (duration: 152.673386ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:05.369558Z","caller":"traceutil/trace.go:171","msg":"trace[646389213] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"101.277086ms","start":"2026-10-15T11:24:05.268242Z","end":"2026-10-15T11:24:05.369519Z","steps":["trace[646389213] 'process raft request' (duration: 90.451413ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:13.341784Z","caller":"traceutil/trace.go:171","msg":"trace[1086581723] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"103.551878ms","start":"2026-10-15T11:24:13.238208Z","end":"2026-10-15T11:24:13.34176Z","steps":["trace[1086581723] 'process raft request' (duration: 103.066707ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:15.635152Z","caller":"traceutil/trace.go:171","msg":"trace[299289390] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"168.626933ms","start":"2026-10-15T11:24:15.466496Z","end":"2026-10-15T11:24:15.635123Z","steps":["trace[299289390] 'process raft request' (duration: 168.411368ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:15.92889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2944} {"level":"info","ts":"2026-10-15T11:24:16.041235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2944,"took":"110.927693ms","hash":2367859807,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:24:16.041309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2367859807,"revision":2944,"compact-revision":-1} {"level":"info","ts":"2026-10-15T11:24:26.531747Z","caller":"traceutil/trace.go:171","msg":"trace[231128938] transaction","detail":"{read_only:false; response_revision:5899; number_of_response:1; }","duration":"107.410484ms","start":"2026-10-15T11:24:26.424308Z","end":"2026-10-15T11:24:26.531718Z","steps":["trace[231128938] 'process raft request' (duration: 107.24409ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:26.567304Z","caller":"traceutil/trace.go:171","msg":"trace[1635196990] linearizableReadLoop","detail":"{readStateIndex:6119; appliedIndex:6117; }","duration":"115.407223ms","start":"2026-10-15T11:24:26.451877Z","end":"2026-10-15T11:24:26.567284Z","steps":["trace[1635196990] 'read index received' (duration: 79.694783ms)","trace[1635196990] 'applied index is now lower than readState.Index' (duration: 35.71157ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:24:26.567366Z","caller":"traceutil/trace.go:171","msg":"trace[1219050733] transaction","detail":"{read_only:false; response_revision:5900; number_of_response:1; }","duration":"135.728357ms","start":"2026-10-15T11:24:26.431607Z","end":"2026-10-15T11:24:26.567335Z","steps":["trace[1219050733] 'process raft request' (duration: 135.555273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:24:26.567477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.581086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T11:24:26.567521Z","caller":"traceutil/trace.go:171","msg":"trace[276897437] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5900; }","duration":"115.671019ms","start":"2026-10-15T11:24:26.451834Z","end":"2026-10-15T11:24:26.567505Z","steps":["trace[276897437] 'agreement among raft nodes before linearized reading' (duration: 115.532186ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:26.933245Z","caller":"traceutil/trace.go:171","msg":"trace[431132875] transaction","detail":"{read_only:false; response_revision:5908; number_of_response:1; }","duration":"224.803371ms","start":"2026-10-15T11:24:26.708405Z","end":"2026-10-15T11:24:26.933209Z","steps":["trace[431132875] 'process raft request' (duration: 199.340261ms)","trace[431132875] 'compare' (duration: 25.315486ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:05.514568Z","caller":"traceutil/trace.go:171","msg":"trace[1831030334] transaction","detail":"{read_only:false; response_revision:6249; number_of_response:1; }","duration":"129.534864ms","start":"2026-10-15T11:25:05.385006Z","end":"2026-10-15T11:25:05.514541Z","steps":["trace[1831030334] 'process raft request' (duration: 129.297469ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:29:15.936696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5755} {"level":"info","ts":"2026-10-15T11:29:16.031793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5755,"took":"92.891823ms","hash":2893676328,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":25669632,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T11:29:16.031936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2893676328,"revision":5755,"compact-revision":2944}