{"level":"warn","ts":"2026-04-09T02:03:46.627243Z","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-09T02:03:46.627352Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-09T02:03:46.627426Z","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-09T02:03:46.627441Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-09T02:03:46.62746Z","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-09T02:03:46.628184Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-04-09T02:03:46.628257Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-09T02:03:46.631587Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.121939ms"} {"level":"info","ts":"2026-04-09T02:03:46.640549Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-04-09T02:03:46.640666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:03:46.640693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-04-09T02:03:46.64071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:03:46.64073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-04-09T02:03:46.640772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-04-09T02:03:46.647294Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:03:46.651426Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:03:46.653299Z","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-09T02:03:46.655302Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:03:46.655415Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:03:46.655509Z","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-09T02:03:46.655639Z","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-09T02:03:46.655663Z","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-09T02:03:46.658515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-04-09T02:03:46.660733Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-09T02:03:46.662235Z","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-09T02:03:46.662533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:03:46.662572Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:03:46.662788Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-09T02:03:46.662811Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-09T02:03:46.843799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:03:46.843875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:03:46.843902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-04-09T02:03:46.843917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:03:46.843923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-09T02:03:46.843934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-04-09T02:03:46.843943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-09T02:03:46.848842Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:46.849836Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:03:46.849975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:03:46.850071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:03:46.850356Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:46.850538Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:46.850585Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:46.852032Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:03:46.85209Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:03:46.853975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:03:46.866755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-04-09T02:03:51.087565Z","caller":"traceutil/trace.go:171","msg":"trace[241454415] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"153.637691ms","start":"2026-04-09T02:03:50.933901Z","end":"2026-04-09T02:03:51.087539Z","steps":["trace[241454415] 'read index received' (duration: 153.420404ms)","trace[241454415] 'applied index is now lower than readState.Index' (duration: 216.177µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:51.087902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.954389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:03:51.088027Z","caller":"traceutil/trace.go:171","msg":"trace[1631103095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:245; }","duration":"154.168386ms","start":"2026-04-09T02:03:50.933843Z","end":"2026-04-09T02:03:51.088011Z","steps":["trace[1631103095] 'agreement among raft nodes before linearized reading' (duration: 153.813456ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:51.088014Z","caller":"traceutil/trace.go:171","msg":"trace[1205812209] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"243.252046ms","start":"2026-04-09T02:03:50.844707Z","end":"2026-04-09T02:03:51.087959Z","steps":["trace[1205812209] 'process raft request' (duration: 242.689731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:51.463045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.431001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:03:51.463201Z","caller":"traceutil/trace.go:171","msg":"trace[1746729678] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"109.721233ms","start":"2026-04-09T02:03:51.353466Z","end":"2026-04-09T02:03:51.463187Z","steps":["trace[1746729678] 'read index received' (duration: 42.842µs)","trace[1746729678] 'applied index is now lower than readState.Index' (duration: 109.677591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:51.46329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.818425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:03:51.463322Z","caller":"traceutil/trace.go:171","msg":"trace[1893577186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:247; }","duration":"109.882727ms","start":"2026-04-09T02:03:51.353431Z","end":"2026-04-09T02:03:51.463314Z","steps":["trace[1893577186] 'agreement among raft nodes before linearized reading' (duration: 109.808655ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:51.463498Z","caller":"traceutil/trace.go:171","msg":"trace[1688838675] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"367.645159ms","start":"2026-04-09T02:03:51.09584Z","end":"2026-04-09T02:03:51.463485Z","steps":["trace[1688838675] 'process raft request' (duration: 367.297819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:51.463606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:51.095827Z","time spent":"367.753454ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:03:51.463519Z","caller":"traceutil/trace.go:171","msg":"trace[535487743] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"370.838731ms","start":"2026-04-09T02:03:51.092603Z","end":"2026-04-09T02:03:51.463442Z","steps":["trace[535487743] 'process raft request' (duration: 96.396781ms)","trace[535487743] 'compare' (duration: 273.300818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:51.464434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:51.092569Z","time spent":"371.728407ms","remote":"127.0.0.1:50794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:04:17.294303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.396388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:04:17.294471Z","caller":"traceutil/trace.go:171","msg":"trace[1237699366] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"146.316758ms","start":"2026-04-09T02:04:17.148113Z","end":"2026-04-09T02:04:17.29443Z","steps":["trace[1237699366] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:459; } (duration: 141.191172ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.429664Z","caller":"traceutil/trace.go:171","msg":"trace[1652586410] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"250.281486ms","start":"2026-04-09T02:04:17.179358Z","end":"2026-04-09T02:04:17.429639Z","steps":["trace[1652586410] 'process raft request' (duration: 249.881623ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441083Z","caller":"traceutil/trace.go:171","msg":"trace[1121995163] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"260.724941ms","start":"2026-04-09T02:04:17.180305Z","end":"2026-04-09T02:04:17.44103Z","steps":["trace[1121995163] 'process raft request' (duration: 260.469412ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441248Z","caller":"traceutil/trace.go:171","msg":"trace[83254835] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"241.578509ms","start":"2026-04-09T02:04:17.199652Z","end":"2026-04-09T02:04:17.441231Z","steps":["trace[83254835] 'process raft request' (duration: 241.334302ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441375Z","caller":"traceutil/trace.go:171","msg":"trace[977633433] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"260.740471ms","start":"2026-04-09T02:04:17.180563Z","end":"2026-04-09T02:04:17.441304Z","steps":["trace[977633433] 'process raft request' (duration: 260.33935ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441468Z","caller":"traceutil/trace.go:171","msg":"trace[2094176027] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"256.681146ms","start":"2026-04-09T02:04:17.184778Z","end":"2026-04-09T02:04:17.441459Z","steps":["trace[2094176027] 'process raft request' (duration: 256.166052ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441446Z","caller":"traceutil/trace.go:171","msg":"trace[61384663] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"241.519157ms","start":"2026-04-09T02:04:17.199858Z","end":"2026-04-09T02:04:17.441377Z","steps":["trace[61384663] 'process raft request' (duration: 241.26395ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441573Z","caller":"traceutil/trace.go:171","msg":"trace[1484889040] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"241.505196ms","start":"2026-04-09T02:04:17.200063Z","end":"2026-04-09T02:04:17.441568Z","steps":["trace[1484889040] 'process raft request' (duration: 241.097704ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.44157Z","caller":"traceutil/trace.go:171","msg":"trace[1595637150] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"211.511027ms","start":"2026-04-09T02:04:17.229999Z","end":"2026-04-09T02:04:17.44151Z","steps":["trace[1595637150] 'process raft request' (duration: 211.294171ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.44156Z","caller":"traceutil/trace.go:171","msg":"trace[1799048717] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"211.575778ms","start":"2026-04-09T02:04:17.229907Z","end":"2026-04-09T02:04:17.441483Z","steps":["trace[1799048717] 'process raft request' (duration: 211.30165ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441424Z","caller":"traceutil/trace.go:171","msg":"trace[374237841] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"241.682612ms","start":"2026-04-09T02:04:17.19971Z","end":"2026-04-09T02:04:17.441392Z","steps":["trace[374237841] 'process raft request' (duration: 241.312561ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:17.441542Z","caller":"traceutil/trace.go:171","msg":"trace[1253760596] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"183.75454ms","start":"2026-04-09T02:04:17.257779Z","end":"2026-04-09T02:04:17.441534Z","steps":["trace[1253760596] 'process raft request' (duration: 183.572274ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:23.57425Z","caller":"traceutil/trace.go:171","msg":"trace[91560788] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"202.938384ms","start":"2026-04-09T02:04:23.371276Z","end":"2026-04-09T02:04:23.574215Z","steps":["trace[91560788] 'read index received' (duration: 26.521823ms)","trace[91560788] 'applied index is now lower than readState.Index' (duration: 176.415061ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:23.574337Z","caller":"traceutil/trace.go:171","msg":"trace[706226625] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"245.039627ms","start":"2026-04-09T02:04:23.329252Z","end":"2026-04-09T02:04:23.574291Z","steps":["trace[706226625] 'process raft request' (duration: 244.204694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:23.574501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.200401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:23.574615Z","caller":"traceutil/trace.go:171","msg":"trace[872049445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"203.384906ms","start":"2026-04-09T02:04:23.371205Z","end":"2026-04-09T02:04:23.57459Z","steps":["trace[872049445] 'agreement among raft nodes before linearized reading' (duration: 203.16131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:24.188802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:23.805861Z","time spent":"382.930514ms","remote":"127.0.0.1:54582","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-09T02:05:44.230236Z","caller":"traceutil/trace.go:171","msg":"trace[1111198867] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"110.747038ms","start":"2026-04-09T02:05:44.119459Z","end":"2026-04-09T02:05:44.230206Z","steps":["trace[1111198867] 'read index received' (duration: 110.567153ms)","trace[1111198867] 'applied index is now lower than readState.Index' (duration: 178.795µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:44.230381Z","caller":"traceutil/trace.go:171","msg":"trace[61353803] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"235.600451ms","start":"2026-04-09T02:05:43.99477Z","end":"2026-04-09T02:05:44.23037Z","steps":["trace[61353803] 'process raft request' (duration: 235.278802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:44.230637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.16019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T02:05:44.230678Z","caller":"traceutil/trace.go:171","msg":"trace[115034140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"111.261603ms","start":"2026-04-09T02:05:44.119407Z","end":"2026-04-09T02:05:44.230669Z","steps":["trace[115034140] 'agreement among raft nodes before linearized reading' (duration: 111.099117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:44.23095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.664358ms","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-09T02:05:44.231044Z","caller":"traceutil/trace.go:171","msg":"trace[1018026981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"100.813572ms","start":"2026-04-09T02:05:44.130209Z","end":"2026-04-09T02:05:44.231023Z","steps":["trace[1018026981] 'agreement among raft nodes before linearized reading' (duration: 100.577415ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:13.37507Z","caller":"traceutil/trace.go:171","msg":"trace[368526860] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"273.147911ms","start":"2026-04-09T02:06:13.101884Z","end":"2026-04-09T02:06:13.375032Z","steps":["trace[368526860] 'process raft request' (duration: 272.884474ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:13.520426Z","caller":"traceutil/trace.go:171","msg":"trace[1628696803] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"141.07989ms","start":"2026-04-09T02:06:13.379308Z","end":"2026-04-09T02:06:13.520387Z","steps":["trace[1628696803] 'process raft request' (duration: 118.382792ms)","trace[1628696803] 'compare' (duration: 22.548244ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:13.542994Z","caller":"traceutil/trace.go:171","msg":"trace[621747714] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"162.170354ms","start":"2026-04-09T02:06:13.380794Z","end":"2026-04-09T02:06:13.542964Z","steps":["trace[621747714] 'process raft request' (duration: 162.01544ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:13.543152Z","caller":"traceutil/trace.go:171","msg":"trace[751393636] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"157.765601ms","start":"2026-04-09T02:06:13.385355Z","end":"2026-04-09T02:06:13.543121Z","steps":["trace[751393636] 'process raft request' (duration: 157.565166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:14.049559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.856286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:14.049668Z","caller":"traceutil/trace.go:171","msg":"trace[1946923508] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1262; }","duration":"115.022591ms","start":"2026-04-09T02:06:13.934627Z","end":"2026-04-09T02:06:14.049649Z","steps":["trace[1946923508] 'range keys from in-memory index tree' (duration: 114.754363ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:14.231561Z","caller":"traceutil/trace.go:171","msg":"trace[477202114] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"177.771095ms","start":"2026-04-09T02:06:14.053768Z","end":"2026-04-09T02:06:14.231539Z","steps":["trace[477202114] 'process raft request' (duration: 177.619521ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:14.248658Z","caller":"traceutil/trace.go:171","msg":"trace[1540259327] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1309; }","duration":"146.834449ms","start":"2026-04-09T02:06:14.101801Z","end":"2026-04-09T02:06:14.248636Z","steps":["trace[1540259327] 'read index received' (duration: 129.588522ms)","trace[1540259327] 'applied index is now lower than readState.Index' (duration: 17.245017ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:14.248746Z","caller":"traceutil/trace.go:171","msg":"trace[357414642] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"174.769001ms","start":"2026-04-09T02:06:14.073954Z","end":"2026-04-09T02:06:14.248723Z","steps":["trace[357414642] 'process raft request' (duration: 174.611287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:14.248907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.095626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T02:06:14.248964Z","caller":"traceutil/trace.go:171","msg":"trace[1740005140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"147.189409ms","start":"2026-04-09T02:06:14.101764Z","end":"2026-04-09T02:06:14.248953Z","steps":["trace[1740005140] 'agreement among raft nodes before linearized reading' (duration: 147.000284ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:14.249182Z","caller":"traceutil/trace.go:171","msg":"trace[1055267346] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"194.58618ms","start":"2026-04-09T02:06:14.054111Z","end":"2026-04-09T02:06:14.248697Z","steps":["trace[1055267346] 'process raft request' (duration: 194.323303ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:28.903152Z","caller":"traceutil/trace.go:171","msg":"trace[1152060297] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"376.310894ms","start":"2026-04-09T02:06:28.526813Z","end":"2026-04-09T02:06:28.903124Z","steps":["trace[1152060297] 'read index received' (duration: 376.075418ms)","trace[1152060297] 'applied index is now lower than readState.Index' (duration: 234.646µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:28.903235Z","caller":"traceutil/trace.go:171","msg":"trace[345068601] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"393.582771ms","start":"2026-04-09T02:06:28.509606Z","end":"2026-04-09T02:06:28.903189Z","steps":["trace[345068601] 'process raft request' (duration: 393.366115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:28.90349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.656943ms","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-09T02:06:28.903539Z","caller":"traceutil/trace.go:171","msg":"trace[1681489579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1362; }","duration":"376.753356ms","start":"2026-04-09T02:06:28.526772Z","end":"2026-04-09T02:06:28.903525Z","steps":["trace[1681489579] 'agreement among raft nodes before linearized reading' (duration: 376.500669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:28.903658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:28.526757Z","time spent":"376.864039ms","remote":"127.0.0.1:50794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:28.903543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:28.509581Z","time spent":"393.752646ms","remote":"127.0.0.1:50794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:28.90404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.897157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-09T02:06:28.904067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.632398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-09T02:06:28.90415Z","caller":"traceutil/trace.go:171","msg":"trace[1689920508] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1362; }","duration":"204.765622ms","start":"2026-04-09T02:06:28.699365Z","end":"2026-04-09T02:06:28.904131Z","steps":["trace[1689920508] 'agreement among raft nodes before linearized reading' (duration: 204.502064ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:28.904285Z","caller":"traceutil/trace.go:171","msg":"trace[1124482630] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1362; }","duration":"339.99776ms","start":"2026-04-09T02:06:28.564103Z","end":"2026-04-09T02:06:28.9041Z","steps":["trace[1124482630] 'agreement among raft nodes before linearized reading' (duration: 339.753263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:28.904885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:28.564092Z","time spent":"340.242397ms","remote":"127.0.0.1:50794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}