{"level":"warn","ts":"2026-04-11T09:20:18.788423Z","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-11T09:20:18.788533Z","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-11T09:20:18.788695Z","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-11T09:20:18.788713Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-11T09:20:18.788734Z","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-11T09:20:18.790138Z","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-11T09:20:18.790213Z","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-11T09:20:18.794767Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.358248ms"} {"level":"info","ts":"2026-04-11T09:20:18.801928Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-04-11T09:20:18.802009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T09:20:18.802031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-04-11T09:20:18.802043Z","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-11T09:20:18.802056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-04-11T09:20:18.802094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-04-11T09:20:18.815111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T09:20:18.816557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T09:20:18.818044Z","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-11T09:20:18.820025Z","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-11T09:20:18.820185Z","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-11T09:20:18.820345Z","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-11T09:20:18.820389Z","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-11T09:20:18.820397Z","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-11T09:20:18.822086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-04-11T09:20:18.822875Z","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-11T09:20:18.825359Z","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-11T09:20:18.825562Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-11T09:20:18.82559Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-11T09:20:18.825966Z","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-11T09:20:18.82604Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T09:20:19.50308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T09:20:19.503172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T09:20:19.503238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-04-11T09:20:19.503265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-04-11T09:20:19.503278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-11T09:20:19.503297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-04-11T09:20:19.503321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-11T09:20:19.527969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T09:20:19.533298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T09:20:19.533305Z","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-11T09:20:19.533347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T09:20:19.533772Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T09:20:19.533943Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T09:20:19.534265Z","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-11T09:20:19.534455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T09:20:19.53451Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T09:20:19.536951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-04-11T09:20:19.537314Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T09:20:55.607531Z","caller":"traceutil/trace.go:171","msg":"trace[1384472014] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"195.645634ms","start":"2026-04-11T09:20:55.411859Z","end":"2026-04-11T09:20:55.607504Z","steps":["trace[1384472014] 'read index received' (duration: 194.916641ms)","trace[1384472014] 'applied index is now lower than readState.Index' (duration: 727.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:20:55.607651Z","caller":"traceutil/trace.go:171","msg":"trace[84534061] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"223.67128ms","start":"2026-04-11T09:20:55.383945Z","end":"2026-04-11T09:20:55.607616Z","steps":["trace[84534061] 'process raft request' (duration: 223.018778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:20:55.607821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.937539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7rf5t\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-11T09:20:55.607884Z","caller":"traceutil/trace.go:171","msg":"trace[785291044] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7rf5t; range_end:; response_count:1; response_revision:616; }","duration":"196.066542ms","start":"2026-04-11T09:20:55.411808Z","end":"2026-04-11T09:20:55.607874Z","steps":["trace[785291044] 'agreement among raft nodes before linearized reading' (duration: 195.895389ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:20:55.880246Z","caller":"traceutil/trace.go:171","msg":"trace[150701216] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"255.792193ms","start":"2026-04-11T09:20:55.624413Z","end":"2026-04-11T09:20:55.880205Z","steps":["trace[150701216] 'process raft request' (duration: 255.580618ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:20:55.88573Z","caller":"traceutil/trace.go:171","msg":"trace[2044934072] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"178.216735ms","start":"2026-04-11T09:20:55.707482Z","end":"2026-04-11T09:20:55.885699Z","steps":["trace[2044934072] 'read index received' (duration: 172.581429ms)","trace[2044934072] 'applied index is now lower than readState.Index' (duration: 5.633786ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:20:55.885803Z","caller":"traceutil/trace.go:171","msg":"trace[498096846] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"218.174317ms","start":"2026-04-11T09:20:55.66761Z","end":"2026-04-11T09:20:55.885784Z","steps":["trace[498096846] 'process raft request' (duration: 217.965292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:20:55.885879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.377109ms","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-11T09:20:55.885908Z","caller":"traceutil/trace.go:171","msg":"trace[700427359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"178.458401ms","start":"2026-04-11T09:20:55.707441Z","end":"2026-04-11T09:20:55.885899Z","steps":["trace[700427359] 'agreement among raft nodes before linearized reading' (duration: 178.355669ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:20:56.411263Z","caller":"traceutil/trace.go:171","msg":"trace[1726609596] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"461.758641ms","start":"2026-04-11T09:20:55.949419Z","end":"2026-04-11T09:20:56.411178Z","steps":["trace[1726609596] 'process raft request' (duration: 411.335694ms)","trace[1726609596] 'compare' (duration: 50.097501ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:20:56.411342Z","caller":"traceutil/trace.go:171","msg":"trace[1066423903] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"243.903329ms","start":"2026-04-11T09:20:56.167352Z","end":"2026-04-11T09:20:56.411256Z","steps":["trace[1066423903] 'read index received' (duration: 193.513443ms)","trace[1066423903] 'applied index is now lower than readState.Index' (duration: 50.386146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T09:20:56.411613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.232106ms","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-11T09:20:56.411736Z","caller":"traceutil/trace.go:171","msg":"trace[253987307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"244.403849ms","start":"2026-04-11T09:20:56.167294Z","end":"2026-04-11T09:20:56.411698Z","steps":["trace[253987307] 'agreement among raft nodes before linearized reading' (duration: 244.069662ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:20:56.412182Z","caller":"traceutil/trace.go:171","msg":"trace[770578120] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"134.73467ms","start":"2026-04-11T09:20:56.277413Z","end":"2026-04-11T09:20:56.412148Z","steps":["trace[770578120] 'process raft request' (duration: 133.981306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:20:56.412315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:20:55.949392Z","time spent":"462.033926ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T09:21:12.593521Z","caller":"traceutil/trace.go:171","msg":"trace[631352974] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"103.864201ms","start":"2026-04-11T09:21:12.489632Z","end":"2026-04-11T09:21:12.593496Z","steps":["trace[631352974] 'read index received' (duration: 103.680327ms)","trace[631352974] 'applied index is now lower than readState.Index' (duration: 182.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:21:12.593633Z","caller":"traceutil/trace.go:171","msg":"trace[843741680] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"147.258595ms","start":"2026-04-11T09:21:12.446327Z","end":"2026-04-11T09:21:12.593586Z","steps":["trace[843741680] 'process raft request' (duration: 147.008871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:21:12.593759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.123535ms","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-11T09:21:12.593806Z","caller":"traceutil/trace.go:171","msg":"trace[1051620746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"104.241097ms","start":"2026-04-11T09:21:12.489553Z","end":"2026-04-11T09:21:12.593795Z","steps":["trace[1051620746] 'agreement among raft nodes before linearized reading' (duration: 104.091175ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:21:12.765994Z","caller":"traceutil/trace.go:171","msg":"trace[1650200016] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"166.19748ms","start":"2026-04-11T09:21:12.599762Z","end":"2026-04-11T09:21:12.765959Z","steps":["trace[1650200016] 'process raft request' (duration: 165.746452ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:21:12.766018Z","caller":"traceutil/trace.go:171","msg":"trace[1955034331] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"162.319578ms","start":"2026-04-11T09:21:12.603665Z","end":"2026-04-11T09:21:12.765984Z","steps":["trace[1955034331] 'process raft request' (duration: 162.235916ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:21:12.766128Z","caller":"traceutil/trace.go:171","msg":"trace[165781784] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"164.676142ms","start":"2026-04-11T09:21:12.601393Z","end":"2026-04-11T09:21:12.766069Z","steps":["trace[165781784] 'process raft request' (duration: 164.438258ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:21:41.185506Z","caller":"traceutil/trace.go:171","msg":"trace[1126785573] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"268.908519ms","start":"2026-04-11T09:21:40.916562Z","end":"2026-04-11T09:21:41.185471Z","steps":["trace[1126785573] 'read index received' (duration: 268.569083ms)","trace[1126785573] 'applied index is now lower than readState.Index' (duration: 337.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:21:41.185606Z","caller":"traceutil/trace.go:171","msg":"trace[175870166] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"270.767454ms","start":"2026-04-11T09:21:40.914798Z","end":"2026-04-11T09:21:41.185566Z","steps":["trace[175870166] 'process raft request' (duration: 270.464338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:21:41.185822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.230405ms","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-04-11T09:21:41.185905Z","caller":"traceutil/trace.go:171","msg":"trace[367803926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"269.367587ms","start":"2026-04-11T09:21:40.916518Z","end":"2026-04-11T09:21:41.185885Z","steps":["trace[367803926] 'agreement among raft nodes before linearized reading' (duration: 269.106982ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:22:27.056086Z","caller":"traceutil/trace.go:171","msg":"trace[1719906325] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"161.833446ms","start":"2026-04-11T09:22:26.894224Z","end":"2026-04-11T09:22:27.056057Z","steps":["trace[1719906325] 'read index received' (duration: 161.670423ms)","trace[1719906325] 'applied index is now lower than readState.Index' (duration: 161.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:22:27.056192Z","caller":"traceutil/trace.go:171","msg":"trace[1701375114] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"180.436626ms","start":"2026-04-11T09:22:26.875697Z","end":"2026-04-11T09:22:27.056134Z","steps":["trace[1701375114] 'process raft request' (duration: 180.17417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:22:27.056418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.166993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-11T09:22:27.056499Z","caller":"traceutil/trace.go:171","msg":"trace[1506163202] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"162.319136ms","start":"2026-04-11T09:22:26.89416Z","end":"2026-04-11T09:22:27.056479Z","steps":["trace[1506163202] 'agreement among raft nodes before linearized reading' (duration: 162.0297ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:22:27.089738Z","caller":"traceutil/trace.go:171","msg":"trace[113167796] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"130.917091ms","start":"2026-04-11T09:22:26.958787Z","end":"2026-04-11T09:22:27.089704Z","steps":["trace[113167796] 'process raft request' (duration: 130.646786ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:22:27.089993Z","caller":"traceutil/trace.go:171","msg":"trace[1570112468] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"113.506496ms","start":"2026-04-11T09:22:26.976465Z","end":"2026-04-11T09:22:27.089971Z","steps":["trace[1570112468] 'process raft request' (duration: 113.143918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:22:27.090149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.65765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9461da9-21c4-4012-8057-d3bae1fe8ae1\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-11T09:22:27.090238Z","caller":"traceutil/trace.go:171","msg":"trace[221881523] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9461da9-21c4-4012-8057-d3bae1fe8ae1; range_end:; response_count:1; response_revision:1846; }","duration":"181.773362ms","start":"2026-04-11T09:22:26.908444Z","end":"2026-04-11T09:22:27.090217Z","steps":["trace[221881523] 'agreement among raft nodes before linearized reading' (duration: 181.510487ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:22:27.561551Z","caller":"traceutil/trace.go:171","msg":"trace[1131064373] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"272.484835ms","start":"2026-04-11T09:22:27.289029Z","end":"2026-04-11T09:22:27.561514Z","steps":["trace[1131064373] 'process raft request' (duration: 234.863987ms)","trace[1131064373] 'compare' (duration: 37.302942ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:22:27.561539Z","caller":"traceutil/trace.go:171","msg":"trace[52049762] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"149.751357ms","start":"2026-04-11T09:22:27.411747Z","end":"2026-04-11T09:22:27.561499Z","steps":["trace[52049762] 'read index received' (duration: 112.070577ms)","trace[52049762] 'applied index is now lower than readState.Index' (duration: 37.67884ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T09:22:27.561897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.126474ms","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-11T09:22:27.561967Z","caller":"traceutil/trace.go:171","msg":"trace[1444287540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1848; }","duration":"150.266167ms","start":"2026-04-11T09:22:27.411684Z","end":"2026-04-11T09:22:27.56195Z","steps":["trace[1444287540] 'agreement among raft nodes before linearized reading' (duration: 149.94152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:22:27.562414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.674249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T09:22:27.562462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.474272ms","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-04-11T09:22:27.562772Z","caller":"traceutil/trace.go:171","msg":"trace[913324093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1848; }","duration":"137.565574ms","start":"2026-04-11T09:22:27.424949Z","end":"2026-04-11T09:22:27.562514Z","steps":["trace[913324093] 'agreement among raft nodes before linearized reading' (duration: 137.34931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:22:27.562887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.506105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-11T09:22:27.562988Z","caller":"traceutil/trace.go:171","msg":"trace[1527824116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1848; }","duration":"120.651188ms","start":"2026-04-11T09:22:27.442301Z","end":"2026-04-11T09:22:27.562952Z","steps":["trace[1527824116] 'agreement among raft nodes before linearized reading' (duration: 120.069947ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:22:27.562792Z","caller":"traceutil/trace.go:171","msg":"trace[1419739291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"114.881193ms","start":"2026-04-11T09:22:27.447618Z","end":"2026-04-11T09:22:27.562499Z","steps":["trace[1419739291] 'agreement among raft nodes before linearized reading' (duration: 114.68361ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:22:40.897518Z","caller":"traceutil/trace.go:171","msg":"trace[1011489645] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"366.82007ms","start":"2026-04-11T09:22:40.530675Z","end":"2026-04-11T09:22:40.897495Z","steps":["trace[1011489645] 'read index received' (duration: 366.626366ms)","trace[1011489645] 'applied index is now lower than readState.Index' (duration: 192.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:22:40.897604Z","caller":"traceutil/trace.go:171","msg":"trace[450429887] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"381.753767ms","start":"2026-04-11T09:22:40.51581Z","end":"2026-04-11T09:22:40.897564Z","steps":["trace[450429887] 'process raft request' (duration: 381.544283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:22:40.897742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.050765ms","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-11T09:22:40.897778Z","caller":"traceutil/trace.go:171","msg":"trace[543878610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1962; }","duration":"367.135898ms","start":"2026-04-11T09:22:40.530632Z","end":"2026-04-11T09:22:40.897768Z","steps":["trace[543878610] 'agreement among raft nodes before linearized reading' (duration: 366.972173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:22:40.897818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:22:40.530612Z","time spent":"367.198978ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T09:22:40.897813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:22:40.51579Z","time spent":"381.923321ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T09:23:17.342484Z","caller":"traceutil/trace.go:171","msg":"trace[823282644] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"191.154004ms","start":"2026-04-11T09:23:17.151291Z","end":"2026-04-11T09:23:17.342445Z","steps":["trace[823282644] 'process raft request' (duration: 147.898775ms)","trace[823282644] 'compare' (duration: 43.074286ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:23:18.051322Z","caller":"traceutil/trace.go:171","msg":"trace[1078680933] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"169.191272ms","start":"2026-04-11T09:23:17.882074Z","end":"2026-04-11T09:23:18.051266Z","steps":["trace[1078680933] 'process raft request' (duration: 151.20851ms)","trace[1078680933] 'compare' (duration: 17.666256ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:23:18.052463Z","caller":"traceutil/trace.go:171","msg":"trace[1029366992] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"167.889408ms","start":"2026-04-11T09:23:17.884534Z","end":"2026-04-11T09:23:18.052423Z","steps":["trace[1029366992] 'process raft request' (duration: 167.56108ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:23:18.052427Z","caller":"traceutil/trace.go:171","msg":"trace[759649676] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"168.725125ms","start":"2026-04-11T09:23:17.883658Z","end":"2026-04-11T09:23:18.052383Z","steps":["trace[759649676] 'process raft request' (duration: 167.953969ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:23:23.394942Z","caller":"traceutil/trace.go:171","msg":"trace[1757602524] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"226.692213ms","start":"2026-04-11T09:23:23.168213Z","end":"2026-04-11T09:23:23.394905Z","steps":["trace[1757602524] 'process raft request' (duration: 226.443308ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:24:13.707416Z","caller":"traceutil/trace.go:171","msg":"trace[42471144] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"124.295187ms","start":"2026-04-11T09:24:13.583093Z","end":"2026-04-11T09:24:13.707388Z","steps":["trace[42471144] 'process raft request' (duration: 124.087223ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:24:13.709161Z","caller":"traceutil/trace.go:171","msg":"trace[312634918] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"125.405416ms","start":"2026-04-11T09:24:13.583734Z","end":"2026-04-11T09:24:13.709139Z","steps":["trace[312634918] 'process raft request' (duration: 124.995088ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:27:28.765796Z","caller":"traceutil/trace.go:171","msg":"trace[286627784] linearizableReadLoop","detail":"{readStateIndex:4045; appliedIndex:4044; }","duration":"147.074386ms","start":"2026-04-11T09:27:28.618689Z","end":"2026-04-11T09:27:28.765763Z","steps":["trace[286627784] 'read index received' (duration: 146.77337ms)","trace[286627784] 'applied index is now lower than readState.Index' (duration: 299.456µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:27:28.765846Z","caller":"traceutil/trace.go:171","msg":"trace[885994577] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"174.915073ms","start":"2026-04-11T09:27:28.590892Z","end":"2026-04-11T09:27:28.765807Z","steps":["trace[885994577] 'process raft request' (duration: 174.610797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:27:28.766163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.427704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-11T09:27:28.766304Z","caller":"traceutil/trace.go:171","msg":"trace[2038690710] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3900; }","duration":"147.648649ms","start":"2026-04-11T09:27:28.618623Z","end":"2026-04-11T09:27:28.766271Z","steps":["trace[2038690710] 'agreement among raft nodes before linearized reading' (duration: 147.27421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:28:58.659123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.276005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T09:28:58.659241Z","caller":"traceutil/trace.go:171","msg":"trace[1721911160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5115; }","duration":"133.436208ms","start":"2026-04-11T09:28:58.525781Z","end":"2026-04-11T09:28:58.659217Z","steps":["trace[1721911160] 'range keys from in-memory index tree' (duration: 133.192843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:28:58.659258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.844003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-781b4016-8a64-40c9-b5cd-67e4b52e4224\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-11T09:28:58.659343Z","caller":"traceutil/trace.go:171","msg":"trace[272854750] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-781b4016-8a64-40c9-b5cd-67e4b52e4224; range_end:; response_count:1; response_revision:5115; }","duration":"216.995786ms","start":"2026-04-11T09:28:58.442327Z","end":"2026-04-11T09:28:58.659323Z","steps":["trace[272854750] 'range keys from in-memory index tree' (duration: 216.63574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:28:58.65939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.385594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-11T09:28:58.659494Z","caller":"traceutil/trace.go:171","msg":"trace[1705727356] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5115; }","duration":"124.551407ms","start":"2026-04-11T09:28:58.53492Z","end":"2026-04-11T09:28:58.659472Z","steps":["trace[1705727356] 'range keys from in-memory index tree' (duration: 124.133959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:28:58.659922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.511246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777689"} {"level":"info","ts":"2026-04-11T09:28:58.660007Z","caller":"traceutil/trace.go:171","msg":"trace[87136129] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5115; }","duration":"148.659608ms","start":"2026-04-11T09:28:58.51133Z","end":"2026-04-11T09:28:58.65999Z","steps":["trace[87136129] 'range keys from in-memory index tree' (duration: 147.787382ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:06.103188Z","caller":"traceutil/trace.go:171","msg":"trace[870404565] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"144.077996ms","start":"2026-04-11T09:29:05.95906Z","end":"2026-04-11T09:29:06.103138Z","steps":["trace[870404565] 'process raft request' (duration: 132.259215ms)","trace[870404565] 'compare' (duration: 11.542996ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:29:06.103337Z","caller":"traceutil/trace.go:171","msg":"trace[1415824099] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"127.16807ms","start":"2026-04-11T09:29:05.976135Z","end":"2026-04-11T09:29:06.103303Z","steps":["trace[1415824099] 'process raft request' (duration: 126.898185ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:06.305069Z","caller":"traceutil/trace.go:171","msg":"trace[609154784] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"106.670201ms","start":"2026-04-11T09:29:06.198342Z","end":"2026-04-11T09:29:06.305012Z","steps":["trace[609154784] 'process raft request' (duration: 106.532898ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:06.305047Z","caller":"traceutil/trace.go:171","msg":"trace[1987012232] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"106.64378ms","start":"2026-04-11T09:29:06.19834Z","end":"2026-04-11T09:29:06.304984Z","steps":["trace[1987012232] 'process raft request' (duration: 106.340324ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:06.305241Z","caller":"traceutil/trace.go:171","msg":"trace[64338235] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"103.521976ms","start":"2026-04-11T09:29:06.201704Z","end":"2026-04-11T09:29:06.305226Z","steps":["trace[64338235] 'process raft request' (duration: 103.282771ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:06.305261Z","caller":"traceutil/trace.go:171","msg":"trace[291373943] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"106.403815ms","start":"2026-04-11T09:29:06.198818Z","end":"2026-04-11T09:29:06.305221Z","steps":["trace[291373943] 'process raft request' (duration: 106.102259ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:06.305445Z","caller":"traceutil/trace.go:171","msg":"trace[1420012149] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"103.19915ms","start":"2026-04-11T09:29:06.202238Z","end":"2026-04-11T09:29:06.305438Z","steps":["trace[1420012149] 'process raft request' (duration: 102.903544ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:09.407649Z","caller":"traceutil/trace.go:171","msg":"trace[779044121] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"170.002204ms","start":"2026-04-11T09:29:09.23761Z","end":"2026-04-11T09:29:09.407612Z","steps":["trace[779044121] 'read index received' (duration: 169.641327ms)","trace[779044121] 'applied index is now lower than readState.Index' (duration: 358.897µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T09:29:09.407916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.285982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T09:29:09.408035Z","caller":"traceutil/trace.go:171","msg":"trace[677503898] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5337; }","duration":"170.465984ms","start":"2026-04-11T09:29:09.23755Z","end":"2026-04-11T09:29:09.408016Z","steps":["trace[677503898] 'agreement among raft nodes before linearized reading' (duration: 170.292741ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:09.407763Z","caller":"traceutil/trace.go:171","msg":"trace[318316796] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"174.077188ms","start":"2026-04-11T09:29:09.23364Z","end":"2026-04-11T09:29:09.407717Z","steps":["trace[318316796] 'process raft request' (duration: 173.64388ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:09.456635Z","caller":"traceutil/trace.go:171","msg":"trace[563224904] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"146.858282ms","start":"2026-04-11T09:29:09.309744Z","end":"2026-04-11T09:29:09.456602Z","steps":["trace[563224904] 'process raft request' (duration: 146.627878ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:18.359606Z","caller":"traceutil/trace.go:171","msg":"trace[409170292] linearizableReadLoop","detail":"{readStateIndex:5713; appliedIndex:5712; }","duration":"132.472606ms","start":"2026-04-11T09:29:18.227107Z","end":"2026-04-11T09:29:18.35958Z","steps":["trace[409170292] 'read index received' (duration: 132.203231ms)","trace[409170292] 'applied index is now lower than readState.Index' (duration: 268.275µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:29:18.359706Z","caller":"traceutil/trace.go:171","msg":"trace[1852446252] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"257.603793ms","start":"2026-04-11T09:29:18.102065Z","end":"2026-04-11T09:29:18.359669Z","steps":["trace[1852446252] 'process raft request' (duration: 257.318348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:29:18.35989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.769742ms","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-04-11T09:29:18.35994Z","caller":"traceutil/trace.go:171","msg":"trace[779238799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5515; }","duration":"132.976816ms","start":"2026-04-11T09:29:18.226951Z","end":"2026-04-11T09:29:18.359928Z","steps":["trace[779238799] 'agreement among raft nodes before linearized reading' (duration: 132.794633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:29:18.574489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.453195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T09:29:18.574754Z","caller":"traceutil/trace.go:171","msg":"trace[1485144013] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"210.789516ms","start":"2026-04-11T09:29:18.363948Z","end":"2026-04-11T09:29:18.574737Z","steps":["trace[1485144013] 'process raft request' (duration: 210.646273ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:29:18.574749Z","caller":"traceutil/trace.go:171","msg":"trace[483567723] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"210.792387ms","start":"2026-04-11T09:29:18.36391Z","end":"2026-04-11T09:29:18.574703Z","steps":["trace[483567723] 'process raft request' (duration: 80.927473ms)","trace[483567723] 'compare' (duration: 129.322903ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:29:18.57501Z","caller":"traceutil/trace.go:171","msg":"trace[1019970942] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"208.294395ms","start":"2026-04-11T09:29:18.366664Z","end":"2026-04-11T09:29:18.574959Z","steps":["trace[1019970942] 'process raft request' (duration: 208.026499ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:30:19.567219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3029} {"level":"info","ts":"2026-04-11T09:30:19.657475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3029,"took":"88.274283ms","hash":2472361,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":31084544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-11T09:30:19.657571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2472361,"revision":3029,"compact-revision":-1} {"level":"info","ts":"2026-04-11T09:35:19.573525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-04-11T09:35:19.666608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"91.185122ms","hash":3714708863,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":21458944,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-11T09:35:19.666683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3714708863,"revision":6091,"compact-revision":3029} {"level":"info","ts":"2026-04-11T09:35:33.973018Z","caller":"traceutil/trace.go:171","msg":"trace[1112222413] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8680; }","duration":"100.223011ms","start":"2026-04-11T09:35:33.87277Z","end":"2026-04-11T09:35:33.972993Z","steps":["trace[1112222413] 'read index received' (duration: 99.971876ms)","trace[1112222413] 'applied index is now lower than readState.Index' (duration: 249.675µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T09:35:33.973227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.444076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v62nv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-11T09:35:33.973256Z","caller":"traceutil/trace.go:171","msg":"trace[1876578796] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v62nv; range_end:; response_count:1; response_revision:8388; }","duration":"100.496887ms","start":"2026-04-11T09:35:33.872751Z","end":"2026-04-11T09:35:33.973248Z","steps":["trace[1876578796] 'agreement among raft nodes before linearized reading' (duration: 100.341144ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:35:33.9733Z","caller":"traceutil/trace.go:171","msg":"trace[2028490232] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"122.753529ms","start":"2026-04-11T09:35:33.850515Z","end":"2026-04-11T09:35:33.973269Z","steps":["trace[2028490232] 'process raft request' (duration: 122.31042ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:35:46.996674Z","caller":"traceutil/trace.go:171","msg":"trace[1938707025] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"235.904319ms","start":"2026-04-11T09:35:46.760708Z","end":"2026-04-11T09:35:46.996612Z","steps":["trace[1938707025] 'process raft request' (duration: 219.412136ms)","trace[1938707025] 'compare' (duration: 15.994872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T09:36:15.679221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.69847ms","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-11T09:36:15.679514Z","caller":"traceutil/trace.go:171","msg":"trace[1121933276] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8663; }","duration":"427.003906ms","start":"2026-04-11T09:36:15.252487Z","end":"2026-04-11T09:36:15.679491Z","steps":["trace[1121933276] 'range keys from in-memory index tree' (duration: 426.686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:36:15.679658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.261812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T09:36:15.680746Z","caller":"traceutil/trace.go:171","msg":"trace[393133451] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"491.269172ms","start":"2026-04-11T09:36:15.189407Z","end":"2026-04-11T09:36:15.680676Z","steps":["trace[393133451] 'process raft request' (duration: 259.842966ms)","trace[393133451] 'compare' (duration: 230.079187ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:36:15.680792Z","caller":"traceutil/trace.go:171","msg":"trace[1360684396] linearizableReadLoop","detail":"{readStateIndex:8967; appliedIndex:8966; }","duration":"466.912596ms","start":"2026-04-11T09:36:15.213845Z","end":"2026-04-11T09:36:15.680758Z","steps":["trace[1360684396] 'read index received' (duration: 235.422219ms)","trace[1360684396] 'applied index is now lower than readState.Index' (duration: 231.487297ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:36:15.680919Z","caller":"traceutil/trace.go:171","msg":"trace[1972716247] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"447.613935ms","start":"2026-04-11T09:36:15.233276Z","end":"2026-04-11T09:36:15.680889Z","steps":["trace[1972716247] 'process raft request' (duration: 447.433561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:36:15.680998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.269419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T09:36:15.681007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:36:15.189392Z","time spent":"491.474177ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T09:36:15.681048Z","caller":"traceutil/trace.go:171","msg":"trace[1540663335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8665; }","duration":"448.380791ms","start":"2026-04-11T09:36:15.232652Z","end":"2026-04-11T09:36:15.681033Z","steps":["trace[1540663335] 'agreement among raft nodes before linearized reading' (duration: 448.286289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:36:15.681074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.213972ms","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-04-11T09:36:15.681108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:36:15.233256Z","time spent":"447.725238ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T09:36:15.681155Z","caller":"traceutil/trace.go:171","msg":"trace[835409983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8665; }","duration":"467.337575ms","start":"2026-04-11T09:36:15.213801Z","end":"2026-04-11T09:36:15.681139Z","steps":["trace[835409983] 'agreement among raft nodes before linearized reading' (duration: 467.030629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:36:15.681197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:36:15.213789Z","time spent":"467.398136ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-11T09:36:15.681096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:36:15.232626Z","time spent":"448.458862ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-11T09:36:15.68136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.398179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-11T09:36:15.681414Z","caller":"traceutil/trace.go:171","msg":"trace[497515387] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8665; }","duration":"342.494771ms","start":"2026-04-11T09:36:15.338905Z","end":"2026-04-11T09:36:15.681399Z","steps":["trace[497515387] 'agreement among raft nodes before linearized reading' (duration: 342.291677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:36:15.681449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T09:36:15.338852Z","time spent":"342.590924ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-04-11T09:36:19.284162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.193895ms","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-04-11T09:36:19.284264Z","caller":"traceutil/trace.go:171","msg":"trace[1707933933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8682; }","duration":"119.3872ms","start":"2026-04-11T09:36:19.164854Z","end":"2026-04-11T09:36:19.284242Z","steps":["trace[1707933933] 'range keys from in-memory index tree' (duration: 118.850669ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:36:28.497041Z","caller":"traceutil/trace.go:171","msg":"trace[1456810506] linearizableReadLoop","detail":"{readStateIndex:9083; appliedIndex:9082; }","duration":"104.074912ms","start":"2026-04-11T09:36:28.392938Z","end":"2026-04-11T09:36:28.497013Z","steps":["trace[1456810506] 'read index received' (duration: 103.923439ms)","trace[1456810506] 'applied index is now lower than readState.Index' (duration: 150.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T09:36:28.497312Z","caller":"traceutil/trace.go:171","msg":"trace[1430241679] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"105.054792ms","start":"2026-04-11T09:36:28.392248Z","end":"2026-04-11T09:36:28.497303Z","steps":["trace[1430241679] 'process raft request' (duration: 104.606833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T09:36:28.497782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.825777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228781"} {"level":"info","ts":"2026-04-11T09:36:28.497854Z","caller":"traceutil/trace.go:171","msg":"trace[1075578951] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8773; }","duration":"104.96282ms","start":"2026-04-11T09:36:28.392876Z","end":"2026-04-11T09:36:28.497838Z","steps":["trace[1075578951] 'agreement among raft nodes before linearized reading' (duration: 104.48859ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:37:21.184819Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002583.wal"} {"level":"info","ts":"2026-04-11T09:37:47.662646Z","caller":"traceutil/trace.go:171","msg":"trace[2077862975] transaction","detail":"{read_only:false; response_revision:9537; number_of_response:1; }","duration":"197.91976ms","start":"2026-04-11T09:37:47.464687Z","end":"2026-04-11T09:37:47.662607Z","steps":["trace[2077862975] 'process raft request' (duration: 197.517222ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T09:38:02.656356Z","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-11T09:38:02.662972Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T09:38:02.663082Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T09:40:19.579885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8177} {"level":"info","ts":"2026-04-11T09:40:19.646686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8177,"took":"64.156258ms","hash":1204285730,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":28188672,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-11T09:40:19.646765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1204285730,"revision":8177,"compact-revision":6091} {"level":"info","ts":"2026-04-11T09:45:19.588686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10542} {"level":"info","ts":"2026-04-11T09:45:19.670929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10542,"took":"79.556705ms","hash":1832071227,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":22024192,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-11T09:45:19.671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1832071227,"revision":10542,"compact-revision":8177}