{"level":"warn","ts":"2026-08-26T00:17:32.362469Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:17:32.362594Z","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-08-26T00:17:32.363244Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:17:32.363262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-08-26T00:17:32.363302Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:17:32.364847Z","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-08-26T00:17:32.364983Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.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-08-26T00:17:32.36974Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.566375ms"} {"level":"info","ts":"2026-08-26T00:17:32.379174Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-08-26T00:17:32.379257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:17:32.379283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-08-26T00:17:32.3793Z","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-08-26T00:17:32.379313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-08-26T00:17:32.37936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-08-26T00:17:32.39167Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:17:32.393406Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:17:32.394962Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-26T00:17:32.397339Z","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-08-26T00:17:32.397585Z","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-08-26T00:17:32.398324Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:17:32.398473Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:17:32.3985Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:17:32.399079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-08-26T00:17:32.399869Z","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-08-26T00:17:32.405157Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:17:32.405336Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-08-26T00:17:32.405432Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-08-26T00:17:32.405752Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:17:32.405689Z","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-08-26T00:17:32.680605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:17:32.680705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:17:32.68078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-08-26T00:17:32.680814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:17:32.680831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-08-26T00:17:32.680854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-08-26T00:17:32.680874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-08-26T00:17:32.681938Z","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-08-26T00:17:32.682099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:17:32.682254Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:17:32.682313Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:17:32.68238Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:17:32.682061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:17:32.685479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-08-26T00:17:32.685816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T00:17:32.686864Z","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-08-26T00:17:32.686988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:17:32.687032Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:18:09.153466Z","caller":"traceutil/trace.go:171","msg":"trace[1601389975] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"106.875706ms","start":"2026-08-26T00:18:09.046559Z","end":"2026-08-26T00:18:09.153435Z","steps":["trace[1601389975] 'process raft request' (duration: 102.913806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:15.909836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.330506ms","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-08-26T00:18:15.90999Z","caller":"traceutil/trace.go:171","msg":"trace[1332995597] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:638; }","duration":"179.532828ms","start":"2026-08-26T00:18:15.730431Z","end":"2026-08-26T00:18:15.909963Z","steps":["trace[1332995597] 'range keys from in-memory index tree' (duration: 179.228385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:15.910198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.184644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-26T00:18:15.910436Z","caller":"traceutil/trace.go:171","msg":"trace[721496537] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"175.411267ms","start":"2026-08-26T00:18:15.734879Z","end":"2026-08-26T00:18:15.91029Z","steps":["trace[721496537] 'process raft request' (duration: 167.570378ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:15.910527Z","caller":"traceutil/trace.go:171","msg":"trace[1653547103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"333.437895ms","start":"2026-08-26T00:18:15.576944Z","end":"2026-08-26T00:18:15.910382Z","steps":["trace[1653547103] 'range keys from in-memory index tree' (duration: 332.701551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:15.910576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:18:15.576917Z","time spent":"333.642776ms","remote":"127.0.0.1:48888","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-08-26T00:18:30.989764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.088376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:18:30.989867Z","caller":"traceutil/trace.go:171","msg":"trace[523717743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"109.262482ms","start":"2026-08-26T00:18:30.880585Z","end":"2026-08-26T00:18:30.989848Z","steps":["trace[523717743] 'range keys from in-memory index tree' (duration: 108.750775ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:19:45.050628Z","caller":"traceutil/trace.go:171","msg":"trace[202958055] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"308.403754ms","start":"2026-08-26T00:19:44.742203Z","end":"2026-08-26T00:19:45.050607Z","steps":["trace[202958055] 'process raft request' (duration: 308.280043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:45.051039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:19:44.742182Z","time spent":"308.512786ms","remote":"127.0.0.1:48888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:19:45.060978Z","caller":"traceutil/trace.go:171","msg":"trace[1941772279] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1919; }","duration":"208.758978ms","start":"2026-08-26T00:19:44.852189Z","end":"2026-08-26T00:19:45.060948Z","steps":["trace[1941772279] 'read index received' (duration: 198.2836ms)","trace[1941772279] 'applied index is now lower than readState.Index' (duration: 10.473728ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:19:45.061053Z","caller":"traceutil/trace.go:171","msg":"trace[180209538] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"221.31005ms","start":"2026-08-26T00:19:44.839712Z","end":"2026-08-26T00:19:45.061022Z","steps":["trace[180209538] 'process raft request' (duration: 221.103586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:45.061326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.910777ms","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-08-26T00:19:45.061386Z","caller":"traceutil/trace.go:171","msg":"trace[1585166900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1853; }","duration":"189.012308ms","start":"2026-08-26T00:19:44.872361Z","end":"2026-08-26T00:19:45.061374Z","steps":["trace[1585166900] 'agreement among raft nodes before linearized reading' (duration: 188.793605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:45.061196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.994192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45f5c470-aacf-4c7e-95fd-7d6e481555c7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-26T00:19:45.062373Z","caller":"traceutil/trace.go:171","msg":"trace[216296653] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-45f5c470-aacf-4c7e-95fd-7d6e481555c7; range_end:; response_count:1; response_revision:1853; }","duration":"210.221651ms","start":"2026-08-26T00:19:44.852136Z","end":"2026-08-26T00:19:45.062358Z","steps":["trace[216296653] 'agreement among raft nodes before linearized reading' (duration: 208.915622ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:19:45.225105Z","caller":"traceutil/trace.go:171","msg":"trace[1192796741] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"155.896254ms","start":"2026-08-26T00:19:45.069162Z","end":"2026-08-26T00:19:45.225058Z","steps":["trace[1192796741] 'process raft request' (duration: 82.770229ms)","trace[1192796741] 'compare' (duration: 72.793689ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:20:25.148862Z","caller":"traceutil/trace.go:171","msg":"trace[32959334] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"122.49992ms","start":"2026-08-26T00:20:25.026302Z","end":"2026-08-26T00:20:25.148801Z","steps":["trace[32959334] 'process raft request' (duration: 122.310628ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:20:25.470602Z","caller":"traceutil/trace.go:171","msg":"trace[941235759] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"162.788273ms","start":"2026-08-26T00:20:25.307781Z","end":"2026-08-26T00:20:25.470569Z","steps":["trace[941235759] 'read index received' (duration: 158.414532ms)","trace[941235759] 'applied index is now lower than readState.Index' (duration: 4.371491ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:20:25.470649Z","caller":"traceutil/trace.go:171","msg":"trace[1757419070] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"255.816347ms","start":"2026-08-26T00:20:25.214778Z","end":"2026-08-26T00:20:25.470594Z","steps":["trace[1757419070] 'process raft request' (duration: 251.571967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:20:25.470872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.052056ms","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-08-26T00:20:25.470922Z","caller":"traceutil/trace.go:171","msg":"trace[1217717719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2127; }","duration":"163.196578ms","start":"2026-08-26T00:20:25.307714Z","end":"2026-08-26T00:20:25.47091Z","steps":["trace[1217717719] 'agreement among raft nodes before linearized reading' (duration: 162.987515ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:25:56.966208Z","caller":"traceutil/trace.go:171","msg":"trace[934419198] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4611; }","duration":"102.949811ms","start":"2026-08-26T00:25:56.86322Z","end":"2026-08-26T00:25:56.96617Z","steps":["trace[934419198] 'process raft request' (duration: 99.970584ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:01.952784Z","caller":"traceutil/trace.go:171","msg":"trace[282634052] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"101.913206ms","start":"2026-08-26T00:26:01.850851Z","end":"2026-08-26T00:26:01.952765Z","steps":["trace[282634052] 'process raft request' (duration: 101.767074ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:07.644933Z","caller":"traceutil/trace.go:171","msg":"trace[27143031] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"215.835642ms","start":"2026-08-26T00:26:07.429066Z","end":"2026-08-26T00:26:07.644902Z","steps":["trace[27143031] 'process raft request' (duration: 215.284434ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:08.120522Z","caller":"traceutil/trace.go:171","msg":"trace[1334440085] linearizableReadLoop","detail":"{readStateIndex:5006; appliedIndex:5005; }","duration":"221.266562ms","start":"2026-08-26T00:26:07.899214Z","end":"2026-08-26T00:26:08.120481Z","steps":["trace[1334440085] 'read index received' (duration: 221.052139ms)","trace[1334440085] 'applied index is now lower than readState.Index' (duration: 212.783µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:26:08.120695Z","caller":"traceutil/trace.go:171","msg":"trace[266397806] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"224.832977ms","start":"2026-08-26T00:26:07.895849Z","end":"2026-08-26T00:26:08.120682Z","steps":["trace[266397806] 'process raft request' (duration: 224.36552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:26:08.121117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.860391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-58cbb564d7-lbzbr.18cf33494568c136\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-08-26T00:26:08.121181Z","caller":"traceutil/trace.go:171","msg":"trace[694054799] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-58cbb564d7-lbzbr.18cf33494568c136; range_end:; response_count:1; response_revision:4826; }","duration":"221.997053ms","start":"2026-08-26T00:26:07.899168Z","end":"2026-08-26T00:26:08.121165Z","steps":["trace[694054799] 'agreement among raft nodes before linearized reading' (duration: 221.735529ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:08.154758Z","caller":"traceutil/trace.go:171","msg":"trace[394209578] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"177.897585ms","start":"2026-08-26T00:26:07.976814Z","end":"2026-08-26T00:26:08.154711Z","steps":["trace[394209578] 'process raft request' (duration: 177.5876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:26:08.154934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.238605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-26T00:26:08.155022Z","caller":"traceutil/trace.go:171","msg":"trace[184017107] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4827; }","duration":"179.342788ms","start":"2026-08-26T00:26:07.975656Z","end":"2026-08-26T00:26:08.154999Z","steps":["trace[184017107] 'agreement among raft nodes before linearized reading' (duration: 179.051823ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:16.294801Z","caller":"traceutil/trace.go:171","msg":"trace[332867505] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"110.33586ms","start":"2026-08-26T00:26:16.184442Z","end":"2026-08-26T00:26:16.294778Z","steps":["trace[332867505] 'process raft request' (duration: 103.438966ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:20.200213Z","caller":"traceutil/trace.go:171","msg":"trace[728029193] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"149.688372ms","start":"2026-08-26T00:26:20.050498Z","end":"2026-08-26T00:26:20.200186Z","steps":["trace[728029193] 'process raft request' (duration: 56.603614ms)","trace[728029193] 'compare' (duration: 92.838624ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:26:20.349462Z","caller":"traceutil/trace.go:171","msg":"trace[328378839] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"298.449371ms","start":"2026-08-26T00:26:20.05098Z","end":"2026-08-26T00:26:20.349429Z","steps":["trace[328378839] 'process raft request' (duration: 264.093569ms)","trace[328378839] 'compare' (duration: 33.974157ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:26:20.349874Z","caller":"traceutil/trace.go:171","msg":"trace[416416737] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"298.777507ms","start":"2026-08-26T00:26:20.05108Z","end":"2026-08-26T00:26:20.349858Z","steps":["trace[416416737] 'process raft request' (duration: 298.272069ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:20.349994Z","caller":"traceutil/trace.go:171","msg":"trace[1903666943] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"157.125754ms","start":"2026-08-26T00:26:20.192834Z","end":"2026-08-26T00:26:20.349959Z","steps":["trace[1903666943] 'process raft request' (duration: 157.049153ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:20.350115Z","caller":"traceutil/trace.go:171","msg":"trace[28236814] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"298.868929ms","start":"2026-08-26T00:26:20.051185Z","end":"2026-08-26T00:26:20.350054Z","steps":["trace[28236814] 'process raft request' (duration: 298.456833ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:20.350182Z","caller":"traceutil/trace.go:171","msg":"trace[102144121] linearizableReadLoop","detail":"{readStateIndex:5176; appliedIndex:5173; }","duration":"203.021348ms","start":"2026-08-26T00:26:20.147104Z","end":"2026-08-26T00:26:20.350126Z","steps":["trace[102144121] 'read index received' (duration: 167.953215ms)","trace[102144121] 'applied index is now lower than readState.Index' (duration: 35.062533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:26:20.350322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.195411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:26:20.351005Z","caller":"traceutil/trace.go:171","msg":"trace[547868439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4993; }","duration":"203.932181ms","start":"2026-08-26T00:26:20.147047Z","end":"2026-08-26T00:26:20.35098Z","steps":["trace[547868439] 'agreement among raft nodes before linearized reading' (duration: 203.190611ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:21.098439Z","caller":"traceutil/trace.go:171","msg":"trace[335964486] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"118.190519ms","start":"2026-08-26T00:26:20.98022Z","end":"2026-08-26T00:26:21.09841Z","steps":["trace[335964486] 'process raft request' (duration: 63.13913ms)","trace[335964486] 'compare' (duration: 54.769365ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:26:21.57805Z","caller":"traceutil/trace.go:171","msg":"trace[13664230] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"179.250748ms","start":"2026-08-26T00:26:21.398768Z","end":"2026-08-26T00:26:21.578018Z","steps":["trace[13664230] 'process raft request' (duration: 178.983444ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:27:04.14437Z","caller":"traceutil/trace.go:171","msg":"trace[1080280111] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"204.255899ms","start":"2026-08-26T00:27:03.940085Z","end":"2026-08-26T00:27:04.144341Z","steps":["trace[1080280111] 'process raft request' (duration: 203.888774ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:27:32.732761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2918} {"level":"info","ts":"2026-08-26T00:27:32.814547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2918,"took":"79.991214ms","hash":3399194015,"current-db-size-bytes":54059008,"current-db-size":"54 MB","current-db-size-in-use-bytes":29134848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-26T00:27:32.814599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3399194015,"revision":2918,"compact-revision":-1} {"level":"warn","ts":"2026-08-26T00:28:46.259693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4234122776788800028,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-26T00:28:46.2798Z","caller":"traceutil/trace.go:171","msg":"trace[103112697] linearizableReadLoop","detail":"{readStateIndex:6231; appliedIndex:6230; }","duration":"520.29531ms","start":"2026-08-26T00:28:45.759476Z","end":"2026-08-26T00:28:46.279771Z","steps":["trace[103112697] 'read index received' (duration: 517.06196ms)","trace[103112697] 'applied index is now lower than readState.Index' (duration: 3.23223ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:28:46.279886Z","caller":"traceutil/trace.go:171","msg":"trace[526336924] transaction","detail":"{read_only:false; response_revision:6015; number_of_response:1; }","duration":"672.527506ms","start":"2026-08-26T00:28:45.607293Z","end":"2026-08-26T00:28:46.279821Z","steps":["trace[526336924] 'process raft request' (duration: 669.328026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:28:46.280129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.644224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-08-26T00:28:46.280182Z","caller":"traceutil/trace.go:171","msg":"trace[234087530] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6015; }","duration":"520.764585ms","start":"2026-08-26T00:28:45.759406Z","end":"2026-08-26T00:28:46.28017Z","steps":["trace[234087530] 'agreement among raft nodes before linearized reading' (duration: 520.498271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:28:46.280226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:28:45.759381Z","time spent":"520.834826ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-08-26T00:28:46.28023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.666388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:28:46.280263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.982948ms","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-08-26T00:28:46.280297Z","caller":"traceutil/trace.go:171","msg":"trace[86635665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6015; }","duration":"133.787719ms","start":"2026-08-26T00:28:46.146488Z","end":"2026-08-26T00:28:46.280276Z","steps":["trace[86635665] 'agreement among raft nodes before linearized reading' (duration: 133.672857ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:28:46.280306Z","caller":"traceutil/trace.go:171","msg":"trace[1689020061] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:6015; }","duration":"517.07436ms","start":"2026-08-26T00:28:45.763218Z","end":"2026-08-26T00:28:46.280293Z","steps":["trace[1689020061] 'agreement among raft nodes before linearized reading' (duration: 516.926998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:28:46.280415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:28:45.76319Z","time spent":"517.202881ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.159\" "} {"level":"warn","ts":"2026-08-26T00:28:46.280505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:28:45.60726Z","time spent":"672.895509ms","remote":"127.0.0.1:48888","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-08-26T00:28:48.321124Z","caller":"traceutil/trace.go:171","msg":"trace[173052273] transaction","detail":"{read_only:false; response_revision:6026; number_of_response:1; }","duration":"152.071639ms","start":"2026-08-26T00:28:48.16902Z","end":"2026-08-26T00:28:48.321092Z","steps":["trace[173052273] 'process raft request' (duration: 151.791825ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:32:32.738914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5594} {"level":"info","ts":"2026-08-26T00:32:32.815826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5594,"took":"75.31337ms","hash":1273601105,"current-db-size-bytes":54059008,"current-db-size":"54 MB","current-db-size-in-use-bytes":18333696,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-26T00:32:32.815877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1273601105,"revision":5594,"compact-revision":2918}