{"level":"warn","ts":"2026-03-25T09:56:29.072086Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-25T09:56:29.072213Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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://199.204.45.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-03-25T09:56:29.072392Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-25T09:56:29.072411Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]}
{"level":"info","ts":"2026-03-25T09:56:29.072447Z","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-03-25T09:56:29.073698Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]}
{"level":"info","ts":"2026-03-25T09:56:29.073851Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-03-25T09:56:29.080432Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.178154ms"}
{"level":"info","ts":"2026-03-25T09:56:29.090613Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"}
{"level":"info","ts":"2026-03-25T09:56:29.090825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-25T09:56:29.090897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"}
{"level":"info","ts":"2026-03-25T09:56:29.090958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-25T09:56:29.091019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"}
{"level":"info","ts":"2026-03-25T09:56:29.0911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"}
{"level":"warn","ts":"2026-03-25T09:56:29.098415Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-25T09:56:29.100203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-25T09:56:29.101512Z","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-03-25T09:56:29.104951Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-25T09:56:29.106536Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-25T09:56:29.106793Z","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-03-25T09:56:29.106853Z","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-03-25T09:56:29.106866Z","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-03-25T09:56:29.108784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"}
{"level":"info","ts":"2026-03-25T09:56:29.109014Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]}
{"level":"info","ts":"2026-03-25T09:56:29.112992Z","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-03-25T09:56:29.113195Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"}
{"level":"info","ts":"2026-03-25T09:56:29.113256Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"}
{"level":"info","ts":"2026-03-25T09:56:29.114185Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-25T09:56:29.11425Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-25T09:56:29.891436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-25T09:56:29.891492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-25T09:56:29.891531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"}
{"level":"info","ts":"2026-03-25T09:56:29.89156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"}
{"level":"info","ts":"2026-03-25T09:56:29.891572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"}
{"level":"info","ts":"2026-03-25T09:56:29.891587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"}
{"level":"info","ts":"2026-03-25T09:56:29.891606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"}
{"level":"info","ts":"2026-03-25T09:56:29.892472Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-25T09:56:29.892469Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-25T09:56:29.892503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-25T09:56:29.892468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-25T09:56:29.893093Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-25T09:56:29.893151Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-25T09:56:29.893166Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-25T09:56:29.893262Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-25T09:56:29.893308Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-25T09:56:29.894962Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"}
{"level":"info","ts":"2026-03-25T09:56:29.896688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-25T09:56:38.808029Z","caller":"traceutil/trace.go:171","msg":"trace[2078848501] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"112.499062ms","start":"2026-03-25T09:56:38.695498Z","end":"2026-03-25T09:56:38.807997Z","steps":["trace[2078848501] 'process raft request'  (duration: 53.454455ms)","trace[2078848501] 'compare'  (duration: 58.709309ms)"],"step_count":2}
{"level":"info","ts":"2026-03-25T09:56:40.331341Z","caller":"traceutil/trace.go:171","msg":"trace[147458858] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"254.933488ms","start":"2026-03-25T09:56:40.076378Z","end":"2026-03-25T09:56:40.331312Z","steps":["trace[147458858] 'process raft request'  (duration: 253.944584ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:56:46.109942Z","caller":"traceutil/trace.go:171","msg":"trace[224081784] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"149.460181ms","start":"2026-03-25T09:56:45.960457Z","end":"2026-03-25T09:56:46.109917Z","steps":["trace[224081784] 'process raft request'  (duration: 149.314468ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:56:46.249654Z","caller":"traceutil/trace.go:171","msg":"trace[1347377425] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"129.674524ms","start":"2026-03-25T09:56:46.119952Z","end":"2026-03-25T09:56:46.249627Z","steps":["trace[1347377425] 'process raft request'  (duration: 64.034874ms)","trace[1347377425] 'compare'  (duration: 65.472086ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-25T09:57:08.417429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.540668ms","expected-duration":"100ms","prefix":"","request":"header:<ID:991527647741028174 > lease_revoke:<id:0dc29d246c9526a8>","response":"size:28"}
{"level":"info","ts":"2026-03-25T09:57:08.670105Z","caller":"traceutil/trace.go:171","msg":"trace[777246239] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"112.614368ms","start":"2026-03-25T09:57:08.557459Z","end":"2026-03-25T09:57:08.670074Z","steps":["trace[777246239] 'process raft request'  (duration: 112.423574ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:57:08.689964Z","caller":"traceutil/trace.go:171","msg":"trace[911151701] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"129.329177ms","start":"2026-03-25T09:57:08.560599Z","end":"2026-03-25T09:57:08.689928Z","steps":["trace[911151701] 'process raft request'  (duration: 129.199154ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:57:28.260846Z","caller":"traceutil/trace.go:171","msg":"trace[1655686157] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"480.939033ms","start":"2026-03-25T09:57:27.779884Z","end":"2026-03-25T09:57:28.260823Z","steps":["trace[1655686157] 'process raft request'  (duration: 480.80283ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:57:28.261296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T09:57:27.779873Z","time spent":"481.034145ms","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:672 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-25T09:57:28.270425Z","caller":"traceutil/trace.go:171","msg":"trace[1878668167] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"487.346124ms","start":"2026-03-25T09:57:27.783017Z","end":"2026-03-25T09:57:28.270363Z","steps":["trace[1878668167] 'process raft request'  (duration: 487.231322ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:57:28.270596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T09:57:27.78301Z","time spent":"487.490807ms","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:673 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-03-25T09:57:28.302695Z","caller":"traceutil/trace.go:171","msg":"trace[301551365] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"392.837057ms","start":"2026-03-25T09:57:27.909832Z","end":"2026-03-25T09:57:28.302669Z","steps":["trace[301551365] 'read index received'  (duration: 350.980232ms)","trace[301551365] 'applied index is now lower than readState.Index'  (duration: 41.855715ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-25T09:57:28.302879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.035541ms","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-03-25T09:57:28.302939Z","caller":"traceutil/trace.go:171","msg":"trace[1584885172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"393.110813ms","start":"2026-03-25T09:57:27.909787Z","end":"2026-03-25T09:57:28.302898Z","steps":["trace[1584885172] 'agreement among raft nodes before linearized reading'  (duration: 392.98434ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:57:28.302965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T09:57:27.909764Z","time spent":"393.194945ms","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-03-25T09:57:52.921151Z","caller":"traceutil/trace.go:171","msg":"trace[431699050] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"136.823164ms","start":"2026-03-25T09:57:52.784314Z","end":"2026-03-25T09:57:52.921137Z","steps":["trace[431699050] 'process raft request'  (duration: 136.741193ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:57:53.040687Z","caller":"traceutil/trace.go:171","msg":"trace[1137877777] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"126.838996ms","start":"2026-03-25T09:57:52.913824Z","end":"2026-03-25T09:57:53.040663Z","steps":["trace[1137877777] 'read index received'  (duration: 7.290304ms)","trace[1137877777] 'applied index is now lower than readState.Index'  (duration: 119.547462ms)"],"step_count":2}
{"level":"info","ts":"2026-03-25T09:57:53.040802Z","caller":"traceutil/trace.go:171","msg":"trace[832212700] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"129.08175ms","start":"2026-03-25T09:57:52.911693Z","end":"2026-03-25T09:57:53.040775Z","steps":["trace[832212700] 'process raft request'  (duration: 82.361165ms)","trace[832212700] 'compare'  (duration: 46.508289ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-25T09:57:53.041168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.324189ms","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-03-25T09:57:53.04131Z","caller":"traceutil/trace.go:171","msg":"trace[2143593526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"127.493483ms","start":"2026-03-25T09:57:52.913726Z","end":"2026-03-25T09:57:53.041219Z","steps":["trace[2143593526] 'agreement among raft nodes before linearized reading'  (duration: 127.109624ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:06.623898Z","caller":"traceutil/trace.go:171","msg":"trace[1950070515] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"149.660871ms","start":"2026-03-25T09:58:06.474215Z","end":"2026-03-25T09:58:06.623876Z","steps":["trace[1950070515] 'process raft request'  (duration: 149.521648ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:06.625146Z","caller":"traceutil/trace.go:171","msg":"trace[946434236] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"149.816226ms","start":"2026-03-25T09:58:06.475311Z","end":"2026-03-25T09:58:06.625127Z","steps":["trace[946434236] 'process raft request'  (duration: 149.639031ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:21.20107Z","caller":"traceutil/trace.go:171","msg":"trace[1267681497] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"165.5554ms","start":"2026-03-25T09:58:21.035488Z","end":"2026-03-25T09:58:21.201043Z","steps":["trace[1267681497] 'process raft request'  (duration: 165.355405ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:23.827479Z","caller":"traceutil/trace.go:171","msg":"trace[1229452898] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"227.834896ms","start":"2026-03-25T09:58:23.599625Z","end":"2026-03-25T09:58:23.82746Z","steps":["trace[1229452898] 'process raft request'  (duration: 227.721423ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:24.071469Z","caller":"traceutil/trace.go:171","msg":"trace[1691340046] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"453.294715ms","start":"2026-03-25T09:58:23.618153Z","end":"2026-03-25T09:58:24.071448Z","steps":["trace[1691340046] 'read index received'  (duration: 209.723464ms)","trace[1691340046] 'applied index is now lower than readState.Index'  (duration: 243.570601ms)"],"step_count":2}
{"level":"info","ts":"2026-03-25T09:58:24.071573Z","caller":"traceutil/trace.go:171","msg":"trace[782465515] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"461.990913ms","start":"2026-03-25T09:58:23.609573Z","end":"2026-03-25T09:58:24.071564Z","steps":["trace[782465515] 'process raft request'  (duration: 397.476293ms)","trace[782465515] 'compare'  (duration: 64.291604ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-25T09:58:24.071659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T09:58:23.609554Z","time spent":"462.039125ms","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:863 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-03-25T09:58:24.071723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.090073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"}
{"level":"info","ts":"2026-03-25T09:58:24.071756Z","caller":"traceutil/trace.go:171","msg":"trace[2013105372] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:906; }","duration":"241.151754ms","start":"2026-03-25T09:58:23.830593Z","end":"2026-03-25T09:58:24.071745Z","steps":["trace[2013105372] 'agreement among raft nodes before linearized reading'  (duration: 241.037751ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:58:24.071729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.555742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-25T09:58:24.071808Z","caller":"traceutil/trace.go:171","msg":"trace[943498104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"453.675855ms","start":"2026-03-25T09:58:23.618112Z","end":"2026-03-25T09:58:24.071787Z","steps":["trace[943498104] 'agreement among raft nodes before linearized reading'  (duration: 453.563992ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:58:24.071858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T09:58:23.618092Z","time spent":"453.758047ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-25T09:58:24.071871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.157782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-l7d4p\" ","response":"range_response_count:1 size:11061"}
{"level":"info","ts":"2026-03-25T09:58:24.071929Z","caller":"traceutil/trace.go:171","msg":"trace[1358843825] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-l7d4p; range_end:; response_count:1; response_revision:906; }","duration":"230.244145ms","start":"2026-03-25T09:58:23.841669Z","end":"2026-03-25T09:58:24.071913Z","steps":["trace[1358843825] 'agreement among raft nodes before linearized reading'  (duration: 230.077901ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:58:25.009651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.300506ms","expected-duration":"100ms","prefix":"","request":"header:<ID:991527647741029127 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:897 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-25T09:58:25.010194Z","caller":"traceutil/trace.go:171","msg":"trace[360735743] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"264.198634ms","start":"2026-03-25T09:58:24.745978Z","end":"2026-03-25T09:58:25.010176Z","steps":["trace[360735743] 'process raft request'  (duration: 264.155143ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:25.01032Z","caller":"traceutil/trace.go:171","msg":"trace[1005394398] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"273.1898ms","start":"2026-03-25T09:58:24.737123Z","end":"2026-03-25T09:58:25.010312Z","steps":["trace[1005394398] 'process raft request'  (duration: 117.099305ms)","trace[1005394398] 'compare'  (duration: 155.0711ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-25T09:58:25.578133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.538049ms","expected-duration":"100ms","prefix":"","request":"header:<ID:991527647741029135 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" mod_revision:0 > success:<request_put:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" value_size:406 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-25T09:58:25.578589Z","caller":"traceutil/trace.go:171","msg":"trace[1347596974] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"151.797381ms","start":"2026-03-25T09:58:25.426428Z","end":"2026-03-25T09:58:25.578225Z","steps":["trace[1347596974] 'process raft request'  (duration: 51.081418ms)","trace[1347596974] 'compare'  (duration: 100.246092ms)"],"step_count":2}
{"level":"info","ts":"2026-03-25T09:58:25.750101Z","caller":"traceutil/trace.go:171","msg":"trace[2070331388] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"194.298217ms","start":"2026-03-25T09:58:25.555769Z","end":"2026-03-25T09:58:25.750068Z","steps":["trace[2070331388] 'process raft request'  (duration: 130.650038ms)","trace[2070331388] 'compare'  (duration: 63.390953ms)"],"step_count":2}
{"level":"info","ts":"2026-03-25T09:58:25.750174Z","caller":"traceutil/trace.go:171","msg":"trace[1876198645] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"167.500767ms","start":"2026-03-25T09:58:25.582633Z","end":"2026-03-25T09:58:25.750134Z","steps":["trace[1876198645] 'read index received'  (duration: 103.867428ms)","trace[1876198645] 'applied index is now lower than readState.Index'  (duration: 63.627609ms)"],"step_count":2}
{"level":"info","ts":"2026-03-25T09:58:25.750181Z","caller":"traceutil/trace.go:171","msg":"trace[2003366560] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"167.410484ms","start":"2026-03-25T09:58:25.58274Z","end":"2026-03-25T09:58:25.750151Z","steps":["trace[2003366560] 'process raft request'  (duration: 167.279001ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:58:25.750453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.172644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-25T09:58:25.750519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.854665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"}
{"level":"info","ts":"2026-03-25T09:58:25.750539Z","caller":"traceutil/trace.go:171","msg":"trace[807538256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"132.309837ms","start":"2026-03-25T09:58:25.618208Z","end":"2026-03-25T09:58:25.750518Z","steps":["trace[807538256] 'agreement among raft nodes before linearized reading'  (duration: 132.153413ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:25.750574Z","caller":"traceutil/trace.go:171","msg":"trace[93599037] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:921; }","duration":"167.972538ms","start":"2026-03-25T09:58:25.582589Z","end":"2026-03-25T09:58:25.750561Z","steps":["trace[93599037] 'agreement among raft nodes before linearized reading'  (duration: 167.715642ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-25T09:58:26.15166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.155909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-25T09:58:26.15178Z","caller":"traceutil/trace.go:171","msg":"trace[2103000120] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:923; }","duration":"245.325473ms","start":"2026-03-25T09:58:25.906432Z","end":"2026-03-25T09:58:26.151758Z","steps":["trace[2103000120] 'count revisions from in-memory index tree'  (duration: 245.082248ms)"],"step_count":1}
{"level":"info","ts":"2026-03-25T09:58:38.117214Z","caller":"traceutil/trace.go:171","msg":"trace[1746408185] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"156.38328ms","start":"2026-03-25T09:58:37.960801Z","end":"2026-03-25T09:58:38.117184Z","steps":["trace[1746408185] 'process raft request'  (duration: 156.220316ms)"],"step_count":1}