{"level":"warn","ts":"2026-02-25T10:02:10.710504Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:02:10.710667Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-02-25T10:02:10.7108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:02:10.710838Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-25T10:02:10.710874Z","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-02-25T10:02:10.712218Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-25T10:02:10.712402Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-02-25T10:02:10.71527Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.436548ms"} {"level":"info","ts":"2026-02-25T10:02:10.722926Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-25T10:02:10.72303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:02:10.723058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-25T10:02:10.723078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:02:10.72309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-25T10:02:10.723129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-25T10:02:10.728952Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:02:10.730977Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:02:10.732227Z","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-02-25T10:02:10.735418Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:02:10.735555Z","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-02-25T10:02:10.735718Z","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-02-25T10:02:10.735656Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:02:10.735739Z","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-02-25T10:02:10.73753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-25T10:02:10.73803Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-25T10:02:10.742767Z","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-02-25T10:02:10.742867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-25T10:02:10.742924Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-25T10:02:10.743025Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:02:10.743057Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:02:11.623714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:02:11.623802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:02:11.623831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-25T10:02:11.62385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:02:11.623862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-25T10:02:11.623879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-25T10:02:11.623892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-25T10:02:11.625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:02:11.62501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:02:11.625032Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:11.62507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:02:11.625404Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:02:11.625516Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:02:11.626243Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:11.626368Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:11.626406Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:11.62833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T10:02:11.62935Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-02-25T10:02:20.863936Z","caller":"traceutil/trace.go:171","msg":"trace[218671059] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"129.009527ms","start":"2026-02-25T10:02:20.734882Z","end":"2026-02-25T10:02:20.863891Z","steps":["trace[218671059] 'process raft request' (duration: 121.918857ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:20.864259Z","caller":"traceutil/trace.go:171","msg":"trace[422177262] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"126.305324ms","start":"2026-02-25T10:02:20.737933Z","end":"2026-02-25T10:02:20.864238Z","steps":["trace[422177262] 'process raft request' (duration: 125.882996ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:29.770713Z","caller":"traceutil/trace.go:171","msg":"trace[147661598] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"337.154494ms","start":"2026-02-25T10:02:29.433524Z","end":"2026-02-25T10:02:29.770678Z","steps":["trace[147661598] 'process raft request' (duration: 336.978881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:29.771878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:29.433504Z","time spent":"337.679915ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:02:30.284999Z","caller":"traceutil/trace.go:171","msg":"trace[465743765] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"850.396829ms","start":"2026-02-25T10:02:29.434567Z","end":"2026-02-25T10:02:30.284964Z","steps":["trace[465743765] 'process raft request' (duration: 798.710597ms)","trace[465743765] 'compare' (duration: 51.496908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:02:30.285141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:29.43455Z","time spent":"850.520781ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:02:30.284985Z","caller":"traceutil/trace.go:171","msg":"trace[312606675] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:417; }","duration":"760.893005ms","start":"2026-02-25T10:02:29.524061Z","end":"2026-02-25T10:02:30.284954Z","steps":["trace[312606675] 'read index received' (duration: 246.448207ms)","trace[312606675] 'applied index is now lower than readState.Index' (duration: 514.443428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:02:30.285232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.14875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:02:30.28537Z","caller":"traceutil/trace.go:171","msg":"trace[282579853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"761.351674ms","start":"2026-02-25T10:02:29.523996Z","end":"2026-02-25T10:02:30.285348Z","steps":["trace[282579853] 'agreement among raft nodes before linearized reading' (duration: 761.052268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:30.28542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:29.523966Z","time spent":"761.442125ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:02:30.288801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.715453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:02:30.288869Z","caller":"traceutil/trace.go:171","msg":"trace[1284326436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"562.821226ms","start":"2026-02-25T10:02:29.726028Z","end":"2026-02-25T10:02:30.288849Z","steps":["trace[1284326436] 'agreement among raft nodes before linearized reading' (duration: 562.709304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:30.2889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:29.726007Z","time spent":"562.888707ms","remote":"127.0.0.1:44120","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-02-25T10:02:36.293534Z","caller":"traceutil/trace.go:171","msg":"trace[1329380651] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"488.396187ms","start":"2026-02-25T10:02:35.805107Z","end":"2026-02-25T10:02:36.293504Z","steps":["trace[1329380651] 'process raft request' (duration: 488.207913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:36.293764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:35.80507Z","time spent":"488.562739ms","remote":"127.0.0.1:44432","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-02-25T10:02:36.854503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.105238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:02:36.854583Z","caller":"traceutil/trace.go:171","msg":"trace[1711088401] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"534.017479ms","start":"2026-02-25T10:02:36.320555Z","end":"2026-02-25T10:02:36.854572Z","steps":["trace[1711088401] 'read index received' (duration: 401.703518ms)","trace[1711088401] 'applied index is now lower than readState.Index' (duration: 132.312911ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:02:36.854626Z","caller":"traceutil/trace.go:171","msg":"trace[1546256941] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"540.373314ms","start":"2026-02-25T10:02:36.314247Z","end":"2026-02-25T10:02:36.85462Z","steps":["trace[1546256941] 'process raft request' (duration: 407.986851ms)","trace[1546256941] 'compare' (duration: 132.000026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:02:36.85466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:36.314214Z","time spent":"540.428236ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:02:36.854791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.218823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:02:36.854915Z","caller":"traceutil/trace.go:171","msg":"trace[782997434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"534.374277ms","start":"2026-02-25T10:02:36.32051Z","end":"2026-02-25T10:02:36.854884Z","steps":["trace[782997434] 'agreement among raft nodes before linearized reading' (duration: 534.205374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:36.854985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:36.320474Z","time spent":"534.494359ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:02:37.35542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058319483610797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:02:37.692684Z","caller":"traceutil/trace.go:171","msg":"trace[1712544162] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.184232377s","start":"2026-02-25T10:02:36.508429Z","end":"2026-02-25T10:02:37.692661Z","steps":["trace[1712544162] 'process raft request' (duration: 1.184108534s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:37.692813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:36.508409Z","time spent":"1.184340748s","remote":"127.0.0.1:44432","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-02-25T10:02:37.694295Z","caller":"traceutil/trace.go:171","msg":"trace[1116584072] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"839.677239ms","start":"2026-02-25T10:02:36.854596Z","end":"2026-02-25T10:02:37.694273Z","steps":["trace[1116584072] 'read index received' (duration: 837.938774ms)","trace[1116584072] 'applied index is now lower than readState.Index' (duration: 1.737265ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:02:37.694379Z","caller":"traceutil/trace.go:171","msg":"trace[1932337232] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"832.823604ms","start":"2026-02-25T10:02:36.861548Z","end":"2026-02-25T10:02:37.694372Z","steps":["trace[1932337232] 'process raft request' (duration: 832.63162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:37.694461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:36.861528Z","time spent":"832.872245ms","remote":"127.0.0.1:44432","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-02-25T10:02:37.694495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.041069ms","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-02-25T10:02:37.694566Z","caller":"traceutil/trace.go:171","msg":"trace[1419304252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"344.162342ms","start":"2026-02-25T10:02:37.350388Z","end":"2026-02-25T10:02:37.69455Z","steps":["trace[1419304252] 'agreement among raft nodes before linearized reading' (duration: 344.03848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:37.694618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:37.35037Z","time spent":"344.240223ms","remote":"127.0.0.1:44432","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-02-25T10:02:37.694695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354499972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:02:37.694727Z","caller":"traceutil/trace.go:171","msg":"trace[1245050752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"1.354545992s","start":"2026-02-25T10:02:36.340172Z","end":"2026-02-25T10:02:37.694718Z","steps":["trace[1245050752] 'agreement among raft nodes before linearized reading' (duration: 1.354501761s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:37.69475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:36.340156Z","time spent":"1.354589564s","remote":"127.0.0.1:44120","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-02-25T10:02:42.497612Z","caller":"traceutil/trace.go:171","msg":"trace[671700290] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"175.242346ms","start":"2026-02-25T10:02:42.322334Z","end":"2026-02-25T10:02:42.497576Z","steps":["trace[671700290] 'process raft request' (duration: 175.067991ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:42.864783Z","caller":"traceutil/trace.go:171","msg":"trace[1408086390] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"208.85582ms","start":"2026-02-25T10:02:42.655892Z","end":"2026-02-25T10:02:42.864748Z","steps":["trace[1408086390] 'process raft request' (duration: 208.676657ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:49.349531Z","caller":"traceutil/trace.go:171","msg":"trace[776703406] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"435.924706ms","start":"2026-02-25T10:02:48.913587Z","end":"2026-02-25T10:02:49.349512Z","steps":["trace[776703406] 'process raft request' (duration: 435.676522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:49.349671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:48.913567Z","time spent":"436.023649ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:02:49.972072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058319483610986,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:02:49.983662Z","caller":"traceutil/trace.go:171","msg":"trace[1431487266] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"620.076289ms","start":"2026-02-25T10:02:49.363553Z","end":"2026-02-25T10:02:49.983629Z","steps":["trace[1431487266] 'process raft request' (duration: 619.897536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:49.983818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:49.363542Z","time spent":"620.195131ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:02:50.033596Z","caller":"traceutil/trace.go:171","msg":"trace[1743814066] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"561.877458ms","start":"2026-02-25T10:02:49.471693Z","end":"2026-02-25T10:02:50.033571Z","steps":["trace[1743814066] 'read index received' (duration: 511.713196ms)","trace[1743814066] 'applied index is now lower than readState.Index' (duration: 50.162632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:02:50.033791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.076222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:02:50.03388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.924707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:02:50.033906Z","caller":"traceutil/trace.go:171","msg":"trace[818367192] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:512; }","duration":"562.204334ms","start":"2026-02-25T10:02:49.471672Z","end":"2026-02-25T10:02:50.033877Z","steps":["trace[818367192] 'agreement among raft nodes before linearized reading' (duration: 561.99849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:50.033923Z","caller":"traceutil/trace.go:171","msg":"trace[617634581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"268.01933ms","start":"2026-02-25T10:02:49.765889Z","end":"2026-02-25T10:02:50.033908Z","steps":["trace[617634581] 'agreement among raft nodes before linearized reading' (duration: 267.838856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:50.033936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.240399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:02:50.033973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:49.471585Z","time spent":"562.375487ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T10:02:50.034029Z","caller":"traceutil/trace.go:171","msg":"trace[1266860181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"229.346471ms","start":"2026-02-25T10:02:49.804661Z","end":"2026-02-25T10:02:50.034008Z","steps":["trace[1266860181] 'agreement among raft nodes before linearized reading' (duration: 229.092967ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:50.431397Z","caller":"traceutil/trace.go:171","msg":"trace[737511256] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"392.213716ms","start":"2026-02-25T10:02:50.039138Z","end":"2026-02-25T10:02:50.431352Z","steps":["trace[737511256] 'process raft request' (duration: 391.968432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:50.431634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:50.039101Z","time spent":"392.41422ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:02:50.46849Z","caller":"traceutil/trace.go:171","msg":"trace[312851931] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"429.322205ms","start":"2026-02-25T10:02:50.039145Z","end":"2026-02-25T10:02:50.468467Z","steps":["trace[312851931] 'process raft request' (duration: 429.151442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:50.468662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:50.039119Z","time spent":"429.446367ms","remote":"127.0.0.1:44432","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-02-25T10:02:51.750122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.210993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:02:51.750208Z","caller":"traceutil/trace.go:171","msg":"trace[1888407586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"414.359226ms","start":"2026-02-25T10:02:51.33583Z","end":"2026-02-25T10:02:51.75019Z","steps":["trace[1888407586] 'range keys from in-memory index tree' (duration: 414.057031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:51.750254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:51.335791Z","time spent":"414.453719ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:02:51.750349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.050267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:02:51.750474Z","caller":"traceutil/trace.go:171","msg":"trace[924773335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"396.355274ms","start":"2026-02-25T10:02:51.354094Z","end":"2026-02-25T10:02:51.750449Z","steps":["trace[924773335] 'range keys from in-memory index tree' (duration: 395.893204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:51.750579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:51.354084Z","time spent":"396.478446ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:02:52.190594Z","caller":"traceutil/trace.go:171","msg":"trace[1307428186] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"435.45209ms","start":"2026-02-25T10:02:51.755121Z","end":"2026-02-25T10:02:52.190573Z","steps":["trace[1307428186] 'process raft request' (duration: 435.319757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:52.191121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:51.755091Z","time spent":"435.564881ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:02:52.945362Z","caller":"traceutil/trace.go:171","msg":"trace[1797115345] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"131.338049ms","start":"2026-02-25T10:02:52.813969Z","end":"2026-02-25T10:02:52.945307Z","steps":["trace[1797115345] 'process raft request' (duration: 131.180207ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:52.945392Z","caller":"traceutil/trace.go:171","msg":"trace[177800501] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"126.393297ms","start":"2026-02-25T10:02:52.818966Z","end":"2026-02-25T10:02:52.945359Z","steps":["trace[177800501] 'process raft request' (duration: 126.232025ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:52.945394Z","caller":"traceutil/trace.go:171","msg":"trace[112013312] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"131.448611ms","start":"2026-02-25T10:02:52.813908Z","end":"2026-02-25T10:02:52.945357Z","steps":["trace[112013312] 'process raft request' (duration: 128.503827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:52.945521Z","caller":"traceutil/trace.go:171","msg":"trace[1566350596] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"112.502759ms","start":"2026-02-25T10:02:52.832989Z","end":"2026-02-25T10:02:52.945491Z","steps":["trace[1566350596] 'process raft request' (duration: 112.291925ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:02:53.707266Z","caller":"traceutil/trace.go:171","msg":"trace[1001658308] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"317.881514ms","start":"2026-02-25T10:02:53.389358Z","end":"2026-02-25T10:02:53.707239Z","steps":["trace[1001658308] 'process raft request' (duration: 317.579039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:02:53.707442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:02:53.38932Z","time spent":"318.034828ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211590,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:02:54.553434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.502327ms","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-02-25T10:02:54.553525Z","caller":"traceutil/trace.go:171","msg":"trace[1393697272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"108.650308ms","start":"2026-02-25T10:02:54.444854Z","end":"2026-02-25T10:02:54.553505Z","steps":["trace[1393697272] 'range keys from in-memory index tree' (duration: 108.394494ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:06.772435Z","caller":"traceutil/trace.go:171","msg":"trace[916977370] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"107.925075ms","start":"2026-02-25T10:03:06.664474Z","end":"2026-02-25T10:03:06.772399Z","steps":["trace[916977370] 'process raft request' (duration: 106.458787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:11.181219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058319483611612,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:03:11.465809Z","caller":"traceutil/trace.go:171","msg":"trace[387259746] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"785.610133ms","start":"2026-02-25T10:03:10.680175Z","end":"2026-02-25T10:03:11.465786Z","steps":["trace[387259746] 'read index received' (duration: 785.45511ms)","trace[387259746] 'applied index is now lower than readState.Index' (duration: 153.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:11.465883Z","caller":"traceutil/trace.go:171","msg":"trace[599168427] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"789.105538ms","start":"2026-02-25T10:03:10.676746Z","end":"2026-02-25T10:03:11.465851Z","steps":["trace[599168427] 'process raft request' (duration: 788.897954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:11.466026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:10.67672Z","time spent":"789.23614ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:11.466013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.260439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:03:11.466036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.898589ms","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-02-25T10:03:11.466082Z","caller":"traceutil/trace.go:171","msg":"trace[119221855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"680.99133ms","start":"2026-02-25T10:03:10.78508Z","end":"2026-02-25T10:03:11.466072Z","steps":["trace[119221855] 'agreement among raft nodes before linearized reading' (duration: 680.833177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:11.46609Z","caller":"traceutil/trace.go:171","msg":"trace[466689150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"130.376161ms","start":"2026-02-25T10:03:11.335693Z","end":"2026-02-25T10:03:11.46607Z","steps":["trace[466689150] 'agreement among raft nodes before linearized reading' (duration: 130.265289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:11.466121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:10.785059Z","time spent":"681.047971ms","remote":"127.0.0.1:44432","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-02-25T10:03:11.466208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.995371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:03:11.466358Z","caller":"traceutil/trace.go:171","msg":"trace[953467340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"786.182594ms","start":"2026-02-25T10:03:10.680151Z","end":"2026-02-25T10:03:11.466333Z","steps":["trace[953467340] 'agreement among raft nodes before linearized reading' (duration: 785.836408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:11.466425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:10.680137Z","time spent":"786.265166ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:03:13.653452Z","caller":"traceutil/trace.go:171","msg":"trace[2070084815] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"176.819505ms","start":"2026-02-25T10:03:13.476609Z","end":"2026-02-25T10:03:13.653428Z","steps":["trace[2070084815] 'read index received' (duration: 176.55395ms)","trace[2070084815] 'applied index is now lower than readState.Index' (duration: 264.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:13.653516Z","caller":"traceutil/trace.go:171","msg":"trace[570012802] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"177.643139ms","start":"2026-02-25T10:03:13.475836Z","end":"2026-02-25T10:03:13.653479Z","steps":["trace[570012802] 'process raft request' (duration: 177.413785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:13.653675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.046449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:03:13.653713Z","caller":"traceutil/trace.go:171","msg":"trace[851280174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"177.11913ms","start":"2026-02-25T10:03:13.476582Z","end":"2026-02-25T10:03:13.653702Z","steps":["trace[851280174] 'agreement among raft nodes before linearized reading' (duration: 176.978467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:13.653732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.659511ms","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-02-25T10:03:13.653781Z","caller":"traceutil/trace.go:171","msg":"trace[1764106187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"176.726473ms","start":"2026-02-25T10:03:13.47704Z","end":"2026-02-25T10:03:13.653767Z","steps":["trace[1764106187] 'agreement among raft nodes before linearized reading' (duration: 176.612711ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:33.511741Z","caller":"traceutil/trace.go:171","msg":"trace[1485221600] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"169.762844ms","start":"2026-02-25T10:03:33.341949Z","end":"2026-02-25T10:03:33.511712Z","steps":["trace[1485221600] 'process raft request' (duration: 114.804202ms)","trace[1485221600] 'compare' (duration: 54.826979ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:50.467989Z","caller":"traceutil/trace.go:171","msg":"trace[1098928562] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"158.25908ms","start":"2026-02-25T10:03:50.30969Z","end":"2026-02-25T10:03:50.467949Z","steps":["trace[1098928562] 'process raft request' (duration: 118.896945ms)","trace[1098928562] 'compare' (duration: 39.109971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:32.635232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058319483613195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:04:33.020742Z","caller":"traceutil/trace.go:171","msg":"trace[780507921] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"915.657055ms","start":"2026-02-25T10:04:32.105064Z","end":"2026-02-25T10:04:33.020721Z","steps":["trace[780507921] 'process raft request' (duration: 913.670917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.020867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.105044Z","time spent":"915.763737ms","remote":"127.0.0.1:44198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:33.020716Z","caller":"traceutil/trace.go:171","msg":"trace[327581803] linearizableReadLoop","detail":"{readStateIndex:1663; appliedIndex:1662; }","duration":"886.529737ms","start":"2026-02-25T10:04:32.134151Z","end":"2026-02-25T10:04:33.020681Z","steps":["trace[327581803] 'read index received' (duration: 884.65562ms)","trace[327581803] 'applied index is now lower than readState.Index' (duration: 1.871897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:33.021044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.859883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T10:04:33.021094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.718928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:04:33.021123Z","caller":"traceutil/trace.go:171","msg":"trace[1311012898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1594; }","duration":"183.78865ms","start":"2026-02-25T10:04:32.837326Z","end":"2026-02-25T10:04:33.021115Z","steps":["trace[1311012898] 'agreement among raft nodes before linearized reading' (duration: 183.713968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:33.021135Z","caller":"traceutil/trace.go:171","msg":"trace[745654580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1594; }","duration":"887.023426ms","start":"2026-02-25T10:04:32.134095Z","end":"2026-02-25T10:04:33.021119Z","steps":["trace[745654580] 'agreement among raft nodes before linearized reading' (duration: 886.68945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.021138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.04137ms","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-02-25T10:04:33.021188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.134074Z","time spent":"887.100467ms","remote":"127.0.0.1:44432","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-02-25T10:04:33.021216Z","caller":"traceutil/trace.go:171","msg":"trace[1800665498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1594; }","duration":"869.150882ms","start":"2026-02-25T10:04:32.152046Z","end":"2026-02-25T10:04:33.021197Z","steps":["trace[1800665498] 'agreement among raft nodes before linearized reading' (duration: 868.928698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.02126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.152035Z","time spent":"869.216503ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:04:33.021384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.440495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:33.021417Z","caller":"traceutil/trace.go:171","msg":"trace[1526613365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1594; }","duration":"685.509415ms","start":"2026-02-25T10:04:32.335898Z","end":"2026-02-25T10:04:33.021408Z","steps":["trace[1526613365] 'agreement among raft nodes before linearized reading' (duration: 685.467425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.02112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.61705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T10:04:33.021442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.335875Z","time spent":"685.561147ms","remote":"127.0.0.1:44120","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-02-25T10:04:33.021458Z","caller":"traceutil/trace.go:171","msg":"trace[31885215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1594; }","duration":"506.990236ms","start":"2026-02-25T10:04:32.514461Z","end":"2026-02-25T10:04:33.021451Z","steps":["trace[31885215] 'agreement among raft nodes before linearized reading' (duration: 506.537128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.021479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.514444Z","time spent":"507.029338ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:04:33.021819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.224297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"warn","ts":"2026-02-25T10:04:33.021813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.842052ms","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-02-25T10:04:33.021884Z","caller":"traceutil/trace.go:171","msg":"trace[1461074460] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1594; }","duration":"462.349879ms","start":"2026-02-25T10:04:32.559518Z","end":"2026-02-25T10:04:33.021867Z","steps":["trace[1461074460] 'agreement among raft nodes before linearized reading' (duration: 461.674496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.022015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.559485Z","time spent":"462.446911ms","remote":"127.0.0.1:56982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3898,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T10:04:33.022025Z","caller":"traceutil/trace.go:171","msg":"trace[424258555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1594; }","duration":"836.999115ms","start":"2026-02-25T10:04:32.184889Z","end":"2026-02-25T10:04:33.021888Z","steps":["trace[424258555] 'agreement among raft nodes before linearized reading' (duration: 836.546616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.02225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.184867Z","time spent":"837.208158ms","remote":"127.0.0.1:44432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:12:11.656265Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2144} {"level":"info","ts":"2026-02-25T10:12:11.721676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2144,"took":"64.169105ms","hash":1125741321,"current-db-size-bytes":14852096,"current-db-size":"15 MB","current-db-size-in-use-bytes":4415488,"current-db-size-in-use":"4.4 MB"} {"level":"info","ts":"2026-02-25T10:12:11.721825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1125741321,"revision":2144,"compact-revision":-1} {"level":"info","ts":"2026-02-25T10:17:11.663206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3226} {"level":"info","ts":"2026-02-25T10:17:11.683647Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3226,"took":"19.375379ms","hash":3674455868,"current-db-size-bytes":14852096,"current-db-size":"15 MB","current-db-size-in-use-bytes":4845568,"current-db-size-in-use":"4.8 MB"} {"level":"info","ts":"2026-02-25T10:17:11.683765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3674455868,"revision":3226,"compact-revision":2144}