{"level":"warn","ts":"2026-04-09T22:25:53.31057Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:25:53.310648Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-04-09T22:25:53.310708Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:25:53.31072Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-09T22:25:53.310738Z","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-04-09T22:25:53.311367Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-04-09T22:25:53.311445Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-04-09T22:25:53.315876Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.222857ms"} {"level":"info","ts":"2026-04-09T22:25:53.323571Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-04-09T22:25:53.32371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:25:53.323753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-04-09T22:25:53.32379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:25:53.323824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-04-09T22:25:53.323896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-04-09T22:25:53.330865Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:25:53.332413Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:25:53.333797Z","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-04-09T22:25:53.336099Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:25:53.33633Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:25:53.336528Z","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-04-09T22:25:53.336687Z","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-04-09T22:25:53.336718Z","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-04-09T22:25:53.338486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-04-09T22:25:53.338634Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-09T22:25:53.341285Z","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-04-09T22:25:53.341441Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-09T22:25:53.341475Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-09T22:25:53.341556Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:25:53.34161Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:25:53.525015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:25:53.525099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:25:53.525132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-04-09T22:25:53.525171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:25:53.52519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-09T22:25:53.525215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-04-09T22:25:53.525245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-09T22:25:53.527378Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:25:53.528025Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:25:53.528032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:25:53.528097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:25:53.52834Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:25:53.528462Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:25:53.528458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:25:53.528585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:25:53.528644Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:25:53.531883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T22:25:53.532136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-04-09T22:26:24.137386Z","caller":"traceutil/trace.go:171","msg":"trace[1863437251] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"104.084083ms","start":"2026-04-09T22:26:24.032932Z","end":"2026-04-09T22:26:24.137016Z","steps":["trace[1863437251] 'process raft request' (duration: 103.868437ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:26:29.907273Z","caller":"traceutil/trace.go:171","msg":"trace[577257826] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"105.04899ms","start":"2026-04-09T22:26:29.802189Z","end":"2026-04-09T22:26:29.907238Z","steps":["trace[577257826] 'process raft request' (duration: 104.417752ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:26:30.024766Z","caller":"traceutil/trace.go:171","msg":"trace[1308766555] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"111.43523ms","start":"2026-04-09T22:26:29.91328Z","end":"2026-04-09T22:26:30.024715Z","steps":["trace[1308766555] 'process raft request' (duration: 91.342414ms)","trace[1308766555] 'compare' (duration: 19.856749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:26:30.345959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.213576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dt4fd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T22:26:30.346087Z","caller":"traceutil/trace.go:171","msg":"trace[1208399703] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dt4fd; range_end:; response_count:1; response_revision:576; }","duration":"208.382291ms","start":"2026-04-09T22:26:30.13768Z","end":"2026-04-09T22:26:30.346062Z","steps":["trace[1208399703] 'range keys from in-memory index tree' (duration: 208.071922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:26:30.346021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.983725ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:26:30.346171Z","caller":"traceutil/trace.go:171","msg":"trace[1247621937] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"101.17142ms","start":"2026-04-09T22:26:30.244974Z","end":"2026-04-09T22:26:30.346146Z","steps":["trace[1247621937] 'range keys from in-memory index tree' (duration: 100.949994ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:26:30.808525Z","caller":"traceutil/trace.go:171","msg":"trace[158881030] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"447.554628ms","start":"2026-04-09T22:26:30.360941Z","end":"2026-04-09T22:26:30.808496Z","steps":["trace[158881030] 'process raft request' (duration: 447.319922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:26:30.809323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:26:30.360897Z","time spent":"447.721463ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:26:30.826582Z","caller":"traceutil/trace.go:171","msg":"trace[1633972357] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"300.761113ms","start":"2026-04-09T22:26:30.525778Z","end":"2026-04-09T22:26:30.826539Z","steps":["trace[1633972357] 'read index received' (duration: 282.53024ms)","trace[1633972357] 'applied index is now lower than readState.Index' (duration: 18.229993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:26:30.826714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.922287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:26:30.826745Z","caller":"traceutil/trace.go:171","msg":"trace[928170764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"300.99495ms","start":"2026-04-09T22:26:30.525741Z","end":"2026-04-09T22:26:30.826736Z","steps":["trace[928170764] 'agreement among raft nodes before linearized reading' (duration: 300.929918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:26:30.826772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:26:30.525726Z","time spent":"301.03984ms","remote":"127.0.0.1:48130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T22:26:30.826728Z","caller":"traceutil/trace.go:171","msg":"trace[541638069] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"398.671911ms","start":"2026-04-09T22:26:30.427946Z","end":"2026-04-09T22:26:30.826618Z","steps":["trace[541638069] 'process raft request' (duration: 392.699223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:26:30.826888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.738423ms","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-04-09T22:26:30.827013Z","caller":"traceutil/trace.go:171","msg":"trace[1131881973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"223.925349ms","start":"2026-04-09T22:26:30.603066Z","end":"2026-04-09T22:26:30.826991Z","steps":["trace[1131881973] 'agreement among raft nodes before linearized reading' (duration: 223.675532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:26:30.827226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:26:30.427914Z","time spent":"399.062891ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:26:30.827806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.294429ms","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-04-09T22:26:30.827895Z","caller":"traceutil/trace.go:171","msg":"trace[1675629467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"257.408021ms","start":"2026-04-09T22:26:30.570465Z","end":"2026-04-09T22:26:30.827873Z","steps":["trace[1675629467] 'agreement among raft nodes before linearized reading' (duration: 256.239128ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:26:32.600427Z","caller":"traceutil/trace.go:171","msg":"trace[565109348] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"140.373215ms","start":"2026-04-09T22:26:32.460031Z","end":"2026-04-09T22:26:32.600404Z","steps":["trace[565109348] 'process raft request' (duration: 140.239471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:58.237923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.748198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T22:27:58.238096Z","caller":"traceutil/trace.go:171","msg":"trace[33655773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1796; }","duration":"220.008075ms","start":"2026-04-09T22:27:58.018058Z","end":"2026-04-09T22:27:58.238066Z","steps":["trace[33655773] 'range keys from in-memory index tree' (duration: 219.341067ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:13.621873Z","caller":"traceutil/trace.go:171","msg":"trace[547366380] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"152.230638ms","start":"2026-04-09T22:28:13.469597Z","end":"2026-04-09T22:28:13.621828Z","steps":["trace[547366380] 'read index received' (duration: 151.895758ms)","trace[547366380] 'applied index is now lower than readState.Index' (duration: 333.76µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:28:13.622038Z","caller":"traceutil/trace.go:171","msg":"trace[433332056] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"159.468292ms","start":"2026-04-09T22:28:13.462538Z","end":"2026-04-09T22:28:13.622007Z","steps":["trace[433332056] 'process raft request' (duration: 159.037499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:13.622174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.561818ms","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-04-09T22:28:13.622222Z","caller":"traceutil/trace.go:171","msg":"trace[751334664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1922; }","duration":"152.689061ms","start":"2026-04-09T22:28:13.469521Z","end":"2026-04-09T22:28:13.62221Z","steps":["trace[751334664] 'agreement among raft nodes before linearized reading' (duration: 152.477745ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.245942Z","caller":"traceutil/trace.go:171","msg":"trace[1946532241] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2892; }","duration":"171.305714ms","start":"2026-04-09T22:30:15.074611Z","end":"2026-04-09T22:30:15.245916Z","steps":["trace[1946532241] 'read index received' (duration: 171.100819ms)","trace[1946532241] 'applied index is now lower than readState.Index' (duration: 203.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:15.246031Z","caller":"traceutil/trace.go:171","msg":"trace[1492695567] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"231.28633ms","start":"2026-04-09T22:30:15.014714Z","end":"2026-04-09T22:30:15.246Z","steps":["trace[1492695567] 'process raft request' (duration: 230.987271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.246246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.614763ms","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-04-09T22:30:15.246313Z","caller":"traceutil/trace.go:171","msg":"trace[105287513] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2788; }","duration":"171.700305ms","start":"2026-04-09T22:30:15.074592Z","end":"2026-04-09T22:30:15.246293Z","steps":["trace[105287513] 'agreement among raft nodes before linearized reading' (duration: 171.458018ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:56.104179Z","caller":"traceutil/trace.go:171","msg":"trace[1490396505] transaction","detail":"{read_only:false; response_revision:3836; number_of_response:1; }","duration":"133.320446ms","start":"2026-04-09T22:32:55.970797Z","end":"2026-04-09T22:32:56.104118Z","steps":["trace[1490396505] 'process raft request' (duration: 132.159004ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:21.024731Z","caller":"traceutil/trace.go:171","msg":"trace[366343428] linearizableReadLoop","detail":"{readStateIndex:5211; appliedIndex:5210; }","duration":"127.982809ms","start":"2026-04-09T22:34:20.896719Z","end":"2026-04-09T22:34:21.024701Z","steps":["trace[366343428] 'read index received' (duration: 127.729943ms)","trace[366343428] 'applied index is now lower than readState.Index' (duration: 252.186µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:34:21.025089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.297077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:34:21.0252Z","caller":"traceutil/trace.go:171","msg":"trace[769453878] range","detail":"{range_begin:/registry/clusterrolebindings/vector; range_end:; response_count:0; response_revision:5021; }","duration":"128.479191ms","start":"2026-04-09T22:34:20.896699Z","end":"2026-04-09T22:34:21.025178Z","steps":["trace[769453878] 'agreement among raft nodes before linearized reading' (duration: 128.238965ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:21.02519Z","caller":"traceutil/trace.go:171","msg":"trace[1152721318] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"130.345209ms","start":"2026-04-09T22:34:20.894802Z","end":"2026-04-09T22:34:21.025147Z","steps":["trace[1152721318] 'process raft request' (duration: 129.746084ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:21.315861Z","caller":"traceutil/trace.go:171","msg":"trace[1422775668] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"118.578309ms","start":"2026-04-09T22:34:21.197239Z","end":"2026-04-09T22:34:21.315817Z","steps":["trace[1422775668] 'process raft request' (duration: 24.084638ms)","trace[1422775668] 'compare' (duration: 94.158022ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:21.315926Z","caller":"traceutil/trace.go:171","msg":"trace[232120207] linearizableReadLoop","detail":"{readStateIndex:5219; appliedIndex:5217; }","duration":"107.596116ms","start":"2026-04-09T22:34:21.208292Z","end":"2026-04-09T22:34:21.315888Z","steps":["trace[232120207] 'read index received' (duration: 13.052054ms)","trace[232120207] 'applied index is now lower than readState.Index' (duration: 94.542602ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:21.315974Z","caller":"traceutil/trace.go:171","msg":"trace[576242313] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"118.230479ms","start":"2026-04-09T22:34:21.19771Z","end":"2026-04-09T22:34:21.315941Z","steps":["trace[576242313] 'process raft request' (duration: 118.004063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:21.316247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.921594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z8tsl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T22:34:21.316322Z","caller":"traceutil/trace.go:171","msg":"trace[421540835] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z8tsl; range_end:; response_count:1; response_revision:5029; }","duration":"108.044498ms","start":"2026-04-09T22:34:21.20826Z","end":"2026-04-09T22:34:21.316305Z","steps":["trace[421540835] 'agreement among raft nodes before linearized reading' (duration: 107.804852ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:29.048223Z","caller":"traceutil/trace.go:171","msg":"trace[529093967] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"115.585391ms","start":"2026-04-09T22:34:28.932617Z","end":"2026-04-09T22:34:29.048202Z","steps":["trace[529093967] 'process raft request' (duration: 115.54584ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:29.048454Z","caller":"traceutil/trace.go:171","msg":"trace[815144461] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"116.648658ms","start":"2026-04-09T22:34:28.931796Z","end":"2026-04-09T22:34:29.048445Z","steps":["trace[815144461] 'process raft request' (duration: 116.32572ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:29.048608Z","caller":"traceutil/trace.go:171","msg":"trace[1202548276] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"118.29208ms","start":"2026-04-09T22:34:28.930282Z","end":"2026-04-09T22:34:29.048574Z","steps":["trace[1202548276] 'process raft request' (duration: 117.732676ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:32.595425Z","caller":"traceutil/trace.go:171","msg":"trace[1265394516] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"264.334091ms","start":"2026-04-09T22:34:32.33102Z","end":"2026-04-09T22:34:32.595354Z","steps":["trace[1265394516] 'process raft request' (duration: 194.845411ms)","trace[1265394516] 'compare' (duration: 69.307145ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:32.60761Z","caller":"traceutil/trace.go:171","msg":"trace[977586782] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"116.278909ms","start":"2026-04-09T22:34:32.491303Z","end":"2026-04-09T22:34:32.607582Z","steps":["trace[977586782] 'process raft request' (duration: 116.020282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:41.343295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.515481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8865e441-80d1-47fd-b4b7-2b6c16040c54\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T22:34:41.343383Z","caller":"traceutil/trace.go:171","msg":"trace[1308479863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8865e441-80d1-47fd-b4b7-2b6c16040c54; range_end:; response_count:1; response_revision:5434; }","duration":"327.655465ms","start":"2026-04-09T22:34:41.015706Z","end":"2026-04-09T22:34:41.343362Z","steps":["trace[1308479863] 'range keys from in-memory index tree' (duration: 327.261944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:41.343422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:34:41.015682Z","time spent":"327.733198ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8865e441-80d1-47fd-b4b7-2b6c16040c54\" "} {"level":"warn","ts":"2026-04-09T22:34:41.343728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.404122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8865e441-80d1-47fd-b4b7-2b6c16040c54\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T22:34:41.343744Z","caller":"traceutil/trace.go:171","msg":"trace[541128743] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8865e441-80d1-47fd-b4b7-2b6c16040c54; range_end:; response_count:1; response_revision:5434; }","duration":"390.463014ms","start":"2026-04-09T22:34:40.953275Z","end":"2026-04-09T22:34:41.343738Z","steps":["trace[541128743] 'range keys from in-memory index tree' (duration: 390.277178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:41.343757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:34:40.953239Z","time spent":"390.515445ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8865e441-80d1-47fd-b4b7-2b6c16040c54\" "} {"level":"warn","ts":"2026-04-09T22:34:41.343796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.228335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:34:41.343857Z","caller":"traceutil/trace.go:171","msg":"trace[76719424] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5434; }","duration":"301.333037ms","start":"2026-04-09T22:34:41.042506Z","end":"2026-04-09T22:34:41.343839Z","steps":["trace[76719424] 'count revisions from in-memory index tree' (duration: 301.114621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:41.343884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:34:41.042482Z","time spent":"301.393039ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:34:41.343888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.436304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T22:34:41.343997Z","caller":"traceutil/trace.go:171","msg":"trace[1275274433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5434; }","duration":"134.64699ms","start":"2026-04-09T22:34:41.209329Z","end":"2026-04-09T22:34:41.343976Z","steps":["trace[1275274433] 'range keys from in-memory index tree' (duration: 134.201028ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:35:54.136579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3004} {"level":"info","ts":"2026-04-09T22:35:54.235821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3004,"took":"97.249563ms","hash":1385525114,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T22:35:54.235885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1385525114,"revision":3004,"compact-revision":-1} {"level":"info","ts":"2026-04-09T22:40:52.861689Z","caller":"traceutil/trace.go:171","msg":"trace[1727704460] linearizableReadLoop","detail":"{readStateIndex:8583; appliedIndex:8582; }","duration":"103.831649ms","start":"2026-04-09T22:40:52.757836Z","end":"2026-04-09T22:40:52.861668Z","steps":["trace[1727704460] 'read index received' (duration: 103.699255ms)","trace[1727704460] 'applied index is now lower than readState.Index' (duration: 131.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:40:52.861739Z","caller":"traceutil/trace.go:171","msg":"trace[466077999] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"107.850132ms","start":"2026-04-09T22:40:52.753858Z","end":"2026-04-09T22:40:52.861708Z","steps":["trace[466077999] 'process raft request' (duration: 107.677057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:40:52.861871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.018533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:40:52.86194Z","caller":"traceutil/trace.go:171","msg":"trace[1513445308] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8291; }","duration":"104.143328ms","start":"2026-04-09T22:40:52.757782Z","end":"2026-04-09T22:40:52.861925Z","steps":["trace[1513445308] 'agreement among raft nodes before linearized reading' (duration: 103.973433ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:40:53.058964Z","caller":"traceutil/trace.go:171","msg":"trace[1604403531] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"211.830016ms","start":"2026-04-09T22:40:52.847106Z","end":"2026-04-09T22:40:53.058936Z","steps":["trace[1604403531] 'process raft request' (duration: 210.834387ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:40:53.059385Z","caller":"traceutil/trace.go:171","msg":"trace[1958425525] transaction","detail":"{read_only:false; response_revision:8293; number_of_response:1; }","duration":"206.025581ms","start":"2026-04-09T22:40:52.853342Z","end":"2026-04-09T22:40:53.059367Z","steps":["trace[1958425525] 'process raft request' (duration: 205.472745ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:40:53.05925Z","caller":"traceutil/trace.go:171","msg":"trace[1827423872] linearizableReadLoop","detail":"{readStateIndex:8585; appliedIndex:8583; }","duration":"197.481489ms","start":"2026-04-09T22:40:52.861746Z","end":"2026-04-09T22:40:53.059227Z","steps":["trace[1827423872] 'read index received' (duration: 196.212542ms)","trace[1827423872] 'applied index is now lower than readState.Index' (duration: 1.267947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:40:53.059743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.117335ms","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-04-09T22:40:53.059834Z","caller":"traceutil/trace.go:171","msg":"trace[1299207773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8293; }","duration":"228.272079ms","start":"2026-04-09T22:40:52.831543Z","end":"2026-04-09T22:40:53.059815Z","steps":["trace[1299207773] 'agreement among raft nodes before linearized reading' (duration: 227.94375ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:40:54.143036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6109} {"level":"info","ts":"2026-04-09T22:40:54.237151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6109,"took":"92.011954ms","hash":2382655019,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-09T22:40:54.237204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2382655019,"revision":6109,"compact-revision":3004} {"level":"warn","ts":"2026-04-09T22:41:07.117622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.761497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:41:07.117716Z","caller":"traceutil/trace.go:171","msg":"trace[1300492470] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8366; }","duration":"103.920231ms","start":"2026-04-09T22:41:07.013769Z","end":"2026-04-09T22:41:07.11769Z","steps":["trace[1300492470] 'range keys from in-memory index tree' (duration: 103.663894ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:34.17957Z","caller":"traceutil/trace.go:171","msg":"trace[1373791392] linearizableReadLoop","detail":"{readStateIndex:8868; appliedIndex:8867; }","duration":"155.656078ms","start":"2026-04-09T22:41:34.023888Z","end":"2026-04-09T22:41:34.179544Z","steps":["trace[1373791392] 'read index received' (duration: 155.458662ms)","trace[1373791392] 'applied index is now lower than readState.Index' (duration: 196.555µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:41:34.179908Z","caller":"traceutil/trace.go:171","msg":"trace[1368073555] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"270.870841ms","start":"2026-04-09T22:41:33.909026Z","end":"2026-04-09T22:41:34.179896Z","steps":["trace[1368073555] 'process raft request' (duration: 270.378447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:34.180206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.308386ms","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-04-09T22:41:34.180256Z","caller":"traceutil/trace.go:171","msg":"trace[1865776529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8565; }","duration":"156.405829ms","start":"2026-04-09T22:41:34.023839Z","end":"2026-04-09T22:41:34.180245Z","steps":["trace[1865776529] 'agreement among raft nodes before linearized reading' (duration: 156.261785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:34.180459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.126377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z8tsl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T22:41:34.180488Z","caller":"traceutil/trace.go:171","msg":"trace[1271034547] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z8tsl; range_end:; response_count:1; response_revision:8565; }","duration":"143.177478ms","start":"2026-04-09T22:41:34.037303Z","end":"2026-04-09T22:41:34.18048Z","steps":["trace[1271034547] 'agreement among raft nodes before linearized reading' (duration: 143.092125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:34.18073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.11458ms","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-04-09T22:41:34.180768Z","caller":"traceutil/trace.go:171","msg":"trace[624198740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8565; }","duration":"136.189632ms","start":"2026-04-09T22:41:34.04457Z","end":"2026-04-09T22:41:34.18076Z","steps":["trace[624198740] 'agreement among raft nodes before linearized reading' (duration: 136.09676ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:34.36915Z","caller":"traceutil/trace.go:171","msg":"trace[614017734] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"183.309874ms","start":"2026-04-09T22:41:34.185817Z","end":"2026-04-09T22:41:34.369127Z","steps":["trace[614017734] 'process raft request' (duration: 141.50063ms)","trace[614017734] 'compare' (duration: 41.484435ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:41:34.369358Z","caller":"traceutil/trace.go:171","msg":"trace[864366414] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"183.1795ms","start":"2026-04-09T22:41:34.186169Z","end":"2026-04-09T22:41:34.369348Z","steps":["trace[864366414] 'process raft request' (duration: 182.912743ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:34.369589Z","caller":"traceutil/trace.go:171","msg":"trace[1066794125] linearizableReadLoop","detail":"{readStateIndex:8870; appliedIndex:8868; }","duration":"183.358775ms","start":"2026-04-09T22:41:34.186206Z","end":"2026-04-09T22:41:34.369564Z","steps":["trace[1066794125] 'read index received' (duration: 141.12465ms)","trace[1066794125] 'applied index is now lower than readState.Index' (duration: 42.232965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:41:34.370202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.987472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228873"} {"level":"info","ts":"2026-04-09T22:41:34.370269Z","caller":"traceutil/trace.go:171","msg":"trace[283831047] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8568; }","duration":"184.100765ms","start":"2026-04-09T22:41:34.186151Z","end":"2026-04-09T22:41:34.370252Z","steps":["trace[283831047] 'agreement among raft nodes before linearized reading' (duration: 183.775356ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:37.344997Z","caller":"traceutil/trace.go:171","msg":"trace[2011469326] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"104.396059ms","start":"2026-04-09T22:41:37.240571Z","end":"2026-04-09T22:41:37.344967Z","steps":["trace[2011469326] 'process raft request' (duration: 104.214864ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:47.250544Z","caller":"traceutil/trace.go:171","msg":"trace[1114396183] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"102.6285ms","start":"2026-04-09T22:41:47.147886Z","end":"2026-04-09T22:41:47.250515Z","steps":["trace[1114396183] 'process raft request' (duration: 102.409394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:47.519895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.593719ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-09T22:41:47.520221Z","caller":"traceutil/trace.go:171","msg":"trace[1908888922] linearizableReadLoop","detail":"{readStateIndex:8990; appliedIndex:8987; }","duration":"105.47493ms","start":"2026-04-09T22:41:47.414726Z","end":"2026-04-09T22:41:47.520201Z","steps":["trace[1908888922] 'read index received' (duration: 102.767714ms)","trace[1908888922] 'applied index is now lower than readState.Index' (duration: 2.706376ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:41:47.520309Z","caller":"traceutil/trace.go:171","msg":"trace[1419023247] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"166.282876ms","start":"2026-04-09T22:41:47.353987Z","end":"2026-04-09T22:41:47.52027Z","steps":["trace[1419023247] 'process raft request' (duration: 166.025238ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:47.520335Z","caller":"traceutil/trace.go:171","msg":"trace[669255227] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"141.600274ms","start":"2026-04-09T22:41:47.378702Z","end":"2026-04-09T22:41:47.520302Z","steps":["trace[669255227] 'process raft request' (duration: 141.45634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:47.520393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.666795ms","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-04-09T22:41:47.520432Z","caller":"traceutil/trace.go:171","msg":"trace[603158707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8679; }","duration":"105.755287ms","start":"2026-04-09T22:41:47.414666Z","end":"2026-04-09T22:41:47.520422Z","steps":["trace[603158707] 'agreement among raft nodes before linearized reading' (duration: 105.596363ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:47.88118Z","caller":"traceutil/trace.go:171","msg":"trace[606234688] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"114.168053ms","start":"2026-04-09T22:41:47.766985Z","end":"2026-04-09T22:41:47.881153Z","steps":["trace[606234688] 'process raft request' (duration: 107.410314ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:48.555555Z","caller":"traceutil/trace.go:171","msg":"trace[943083670] linearizableReadLoop","detail":"{readStateIndex:8998; appliedIndex:8997; }","duration":"135.688927ms","start":"2026-04-09T22:41:48.419849Z","end":"2026-04-09T22:41:48.555537Z","steps":["trace[943083670] 'read index received' (duration: 135.563803ms)","trace[943083670] 'applied index is now lower than readState.Index' (duration: 124.344µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:41:48.55579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.923083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:41:48.555822Z","caller":"traceutil/trace.go:171","msg":"trace[1220173895] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8687; }","duration":"136.011445ms","start":"2026-04-09T22:41:48.419802Z","end":"2026-04-09T22:41:48.555814Z","steps":["trace[1220173895] 'agreement among raft nodes before linearized reading' (duration: 135.944504ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:48.555794Z","caller":"traceutil/trace.go:171","msg":"trace[1369858204] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"277.928459ms","start":"2026-04-09T22:41:48.277831Z","end":"2026-04-09T22:41:48.555759Z","steps":["trace[1369858204] 'process raft request' (duration: 277.581019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:48.571276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.356031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T22:41:48.571362Z","caller":"traceutil/trace.go:171","msg":"trace[1933444274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8688; }","duration":"139.487115ms","start":"2026-04-09T22:41:48.431855Z","end":"2026-04-09T22:41:48.571342Z","steps":["trace[1933444274] 'agreement among raft nodes before linearized reading' (duration: 139.267939ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:41:48.571341Z","caller":"traceutil/trace.go:171","msg":"trace[772732535] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"151.287515ms","start":"2026-04-09T22:41:48.420005Z","end":"2026-04-09T22:41:48.571292Z","steps":["trace[772732535] 'process raft request' (duration: 150.941626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:48.571458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.924238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z8tsl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T22:41:48.571516Z","caller":"traceutil/trace.go:171","msg":"trace[1001370301] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z8tsl; range_end:; response_count:1; response_revision:8688; }","duration":"129.02047ms","start":"2026-04-09T22:41:48.442479Z","end":"2026-04-09T22:41:48.5715Z","steps":["trace[1001370301] 'agreement among raft nodes before linearized reading' (duration: 128.843935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:41:48.571597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.281502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-09T22:41:48.571836Z","caller":"traceutil/trace.go:171","msg":"trace[1187672321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8688; }","duration":"137.628822ms","start":"2026-04-09T22:41:48.434187Z","end":"2026-04-09T22:41:48.571816Z","steps":["trace[1187672321] 'agreement among raft nodes before linearized reading' (duration: 137.171049ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:22.252802Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247a.wal"} {"level":"info","ts":"2026-04-09T22:43:32.951417Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e10bb51ed7578852","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T22:43:32.956767Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T22:43:32.956846Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T22:45:54.150044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-04-09T22:45:54.225496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"73.115315ms","hash":365698285,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":26046464,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-09T22:45:54.225556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":365698285,"revision":8302,"compact-revision":6109} {"level":"info","ts":"2026-04-09T22:50:54.156621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10697} {"level":"info","ts":"2026-04-09T22:50:54.228622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10697,"took":"70.419616ms","hash":3990358007,"current-db-size-bytes":57958400,"current-db-size":"58 MB","current-db-size-in-use-bytes":20672512,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-09T22:50:54.228669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3990358007,"revision":10697,"compact-revision":8302}