{"level":"warn","ts":"2026-04-09T22:28:09.848224Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:28:09.848338Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.102: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.102:2380","--initial-cluster=instance=https://199.204.45.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.102:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-09T22:28:09.849247Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:28:09.849264Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-04-09T22:28:09.849288Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T22:28:09.850313Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"]} {"level":"info","ts":"2026-04-09T22:28:09.850487Z","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.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.102:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-09T22:28:09.855598Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.837225ms"} {"level":"info","ts":"2026-04-09T22:28:09.863389Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e7960f761c1028e","cluster-id":"502e2b31b867adf"} {"level":"info","ts":"2026-04-09T22:28:09.863705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:28:09.863796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 0"} {"level":"info","ts":"2026-04-09T22:28:09.863908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e7960f761c1028e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:28:09.864015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 1"} {"level":"info","ts":"2026-04-09T22:28:09.864112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"warn","ts":"2026-04-09T22:28:09.870137Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:28:09.871754Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:28:09.872809Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-09T22:28:09.875671Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e7960f761c1028e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:28:09.878127Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e7960f761c1028e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:28:09.878365Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T22:28:09.878472Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T22:28:09.878495Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T22:28:09.879705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"info","ts":"2026-04-09T22:28:09.879947Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","added-peer-id":"6e7960f761c1028e","added-peer-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-04-09T22:28:09.88036Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T22:28:09.880637Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e7960f761c1028e","initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:28:09.880666Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:28:09.88076Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-04-09T22:28:09.880775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-04-09T22:28:10.364677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:28:10.364731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:28:10.364757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgPreVoteResp from 6e7960f761c1028e at term 1"} {"level":"info","ts":"2026-04-09T22:28:10.364769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:28:10.364774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgVoteResp from 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-04-09T22:28:10.364782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became leader at term 2"} {"level":"info","ts":"2026-04-09T22:28:10.364789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e7960f761c1028e elected leader 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-04-09T22:28:10.36667Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:28:10.367143Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:28:10.367146Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e7960f761c1028e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.102:2379]}","request-path":"/0/members/6e7960f761c1028e/attributes","cluster-id":"502e2b31b867adf","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:28:10.367358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:28:10.367495Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:28:10.367586Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:28:10.368924Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:28:10.369018Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:28:10.369057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:28:10.369646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.102:2379"} {"level":"info","ts":"2026-04-09T22:28:10.370073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-09T22:28:17.939356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.581679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-09T22:28:17.939468Z","caller":"traceutil/trace.go:171","msg":"trace[1428795917] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:320; }","duration":"243.743334ms","start":"2026-04-09T22:28:17.695707Z","end":"2026-04-09T22:28:17.939451Z","steps":["trace[1428795917] 'range keys from in-memory index tree' (duration: 243.433245ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:18.102797Z","caller":"traceutil/trace.go:171","msg":"trace[1245298432] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"159.858349ms","start":"2026-04-09T22:28:17.94292Z","end":"2026-04-09T22:28:18.102779Z","steps":["trace[1245298432] 'process raft request' (duration: 159.746466ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:18.139754Z","caller":"traceutil/trace.go:171","msg":"trace[229490788] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"195.584107ms","start":"2026-04-09T22:28:17.944141Z","end":"2026-04-09T22:28:18.139725Z","steps":["trace[229490788] 'process raft request' (duration: 195.396082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:18.354557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.925757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-09T22:28:18.354647Z","caller":"traceutil/trace.go:171","msg":"trace[184271682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:322; }","duration":"157.058011ms","start":"2026-04-09T22:28:18.197567Z","end":"2026-04-09T22:28:18.354625Z","steps":["trace[184271682] 'range keys from in-memory index tree' (duration: 156.809804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:18.354603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.941262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-09T22:28:18.354684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.028226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:18.354808Z","caller":"traceutil/trace.go:171","msg":"trace[828897955] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:322; }","duration":"247.208781ms","start":"2026-04-09T22:28:18.107568Z","end":"2026-04-09T22:28:18.354776Z","steps":["trace[828897955] 'agreement among raft nodes before linearized reading' (duration: 32.132439ms)","trace[828897955] 'range keys from in-memory index tree' (duration: 214.880717ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:28:18.355199Z","caller":"traceutil/trace.go:171","msg":"trace[1175763720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:322; }","duration":"184.206759ms","start":"2026-04-09T22:28:18.170579Z","end":"2026-04-09T22:28:18.354786Z","steps":["trace[1175763720] 'range keys from in-memory index tree' (duration: 183.824029ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.35137Z","caller":"traceutil/trace.go:171","msg":"trace[1400155260] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"120.932574ms","start":"2026-04-09T22:28:40.230414Z","end":"2026-04-09T22:28:40.351347Z","steps":["trace[1400155260] 'process raft request' (duration: 120.804321ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440041Z","caller":"traceutil/trace.go:171","msg":"trace[1904253473] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"133.538057ms","start":"2026-04-09T22:28:40.306471Z","end":"2026-04-09T22:28:40.440009Z","steps":["trace[1904253473] 'process raft request' (duration: 133.029734ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440171Z","caller":"traceutil/trace.go:171","msg":"trace[2061253569] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"131.68557ms","start":"2026-04-09T22:28:40.308459Z","end":"2026-04-09T22:28:40.440144Z","steps":["trace[2061253569] 'process raft request' (duration: 131.077584ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.44034Z","caller":"traceutil/trace.go:171","msg":"trace[958313183] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"121.114719ms","start":"2026-04-09T22:28:40.319215Z","end":"2026-04-09T22:28:40.44033Z","steps":["trace[958313183] 'process raft request' (duration: 120.356219ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440433Z","caller":"traceutil/trace.go:171","msg":"trace[106653467] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"146.09731ms","start":"2026-04-09T22:28:40.294319Z","end":"2026-04-09T22:28:40.440417Z","steps":["trace[106653467] 'process raft request' (duration: 144.878259ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440461Z","caller":"traceutil/trace.go:171","msg":"trace[1415070830] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"105.010585ms","start":"2026-04-09T22:28:40.33544Z","end":"2026-04-09T22:28:40.440451Z","steps":["trace[1415070830] 'process raft request' (duration: 104.175053ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440554Z","caller":"traceutil/trace.go:171","msg":"trace[468629142] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"103.794584ms","start":"2026-04-09T22:28:40.336752Z","end":"2026-04-09T22:28:40.440547Z","steps":["trace[468629142] 'process raft request' (duration: 102.931122ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440625Z","caller":"traceutil/trace.go:171","msg":"trace[1215397099] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"141.573064ms","start":"2026-04-09T22:28:40.299041Z","end":"2026-04-09T22:28:40.440614Z","steps":["trace[1215397099] 'process raft request' (duration: 140.320272ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440727Z","caller":"traceutil/trace.go:171","msg":"trace[2015224401] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"141.626885ms","start":"2026-04-09T22:28:40.299091Z","end":"2026-04-09T22:28:40.440718Z","steps":["trace[2015224401] 'process raft request' (duration: 140.326731ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:40.440789Z","caller":"traceutil/trace.go:171","msg":"trace[1397992572] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"136.139084ms","start":"2026-04-09T22:28:40.304642Z","end":"2026-04-09T22:28:40.440781Z","steps":["trace[1397992572] 'process raft request' (duration: 134.81671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:45.946612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257757856664807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:28:46.447358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257757856664807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:28:46.719998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.694656012s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T22:28:46.720484Z","caller":"traceutil/trace.go:171","msg":"trace[129967947] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.695216087s","start":"2026-04-09T22:28:45.025238Z","end":"2026-04-09T22:28:46.720454Z","steps":["trace[129967947] 'process raft request' (duration: 1.695068143s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:46.721026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:45.025222Z","time spent":"1.69533035s","remote":"127.0.0.1:42492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:28:46.72941Z","caller":"traceutil/trace.go:171","msg":"trace[1542672249] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"1.283631295s","start":"2026-04-09T22:28:45.44575Z","end":"2026-04-09T22:28:46.729381Z","steps":["trace[1542672249] 'read index received' (duration: 1.274605374s)","trace[1542672249] 'applied index is now lower than readState.Index' (duration: 9.025161ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:28:46.729461Z","caller":"traceutil/trace.go:171","msg":"trace[1748275556] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.246195143s","start":"2026-04-09T22:28:45.483246Z","end":"2026-04-09T22:28:46.729441Z","steps":["trace[1748275556] 'process raft request' (duration: 1.245102695s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:46.729523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283766938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:46.72955Z","caller":"traceutil/trace.go:171","msg":"trace[1683575435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"1.283829729s","start":"2026-04-09T22:28:45.445713Z","end":"2026-04-09T22:28:46.729543Z","steps":["trace[1683575435] 'agreement among raft nodes before linearized reading' (duration: 1.283780878s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:46.729553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:45.483216Z","time spent":"1.246282436s","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:28:46.729573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:45.445697Z","time spent":"1.283872111s","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T22:28:46.729622Z","caller":"traceutil/trace.go:171","msg":"trace[151204287] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"283.101246ms","start":"2026-04-09T22:28:46.446485Z","end":"2026-04-09T22:28:46.729586Z","steps":["trace[151204287] 'process raft request' (duration: 282.85744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:46.72971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.676796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:46.729765Z","caller":"traceutil/trace.go:171","msg":"trace[715685100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"953.752348ms","start":"2026-04-09T22:28:45.775989Z","end":"2026-04-09T22:28:46.729741Z","steps":["trace[715685100] 'agreement among raft nodes before linearized reading' (duration: 953.673316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:46.729791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:45.775975Z","time spent":"953.810479ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:28:46.729844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.479193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-09T22:28:46.729845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.773567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T22:28:46.729832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.746634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T22:28:46.729869Z","caller":"traceutil/trace.go:171","msg":"trace[2010782443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"356.797787ms","start":"2026-04-09T22:28:46.373065Z","end":"2026-04-09T22:28:46.729862Z","steps":["trace[2010782443] 'agreement among raft nodes before linearized reading' (duration: 356.719115ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:46.729867Z","caller":"traceutil/trace.go:171","msg":"trace[437291658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"159.540514ms","start":"2026-04-09T22:28:46.570319Z","end":"2026-04-09T22:28:46.72986Z","steps":["trace[437291658] 'agreement among raft nodes before linearized reading' (duration: 159.462822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:46.72989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:46.372994Z","time spent":"356.892289ms","remote":"127.0.0.1:42492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T22:28:46.729905Z","caller":"traceutil/trace.go:171","msg":"trace[1040779113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"172.857467ms","start":"2026-04-09T22:28:46.557026Z","end":"2026-04-09T22:28:46.729883Z","steps":["trace[1040779113] 'agreement among raft nodes before linearized reading' (duration: 172.670332ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:48.049146Z","caller":"traceutil/trace.go:171","msg":"trace[1771791909] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"739.421217ms","start":"2026-04-09T22:28:47.309681Z","end":"2026-04-09T22:28:48.049102Z","steps":["trace[1771791909] 'process raft request' (duration: 737.792615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:48.049424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:47.309654Z","time spent":"739.611122ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:28:48.192775Z","caller":"traceutil/trace.go:171","msg":"trace[506980636] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"120.435191ms","start":"2026-04-09T22:28:48.072281Z","end":"2026-04-09T22:28:48.192716Z","steps":["trace[506980636] 'process raft request' (duration: 120.144193ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:58.431553Z","caller":"traceutil/trace.go:171","msg":"trace[740130710] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"163.408123ms","start":"2026-04-09T22:29:58.268124Z","end":"2026-04-09T22:29:58.431532Z","steps":["trace[740130710] 'process raft request' (duration: 111.426164ms)","trace[740130710] 'compare' (duration: 51.764203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:59.312069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.621705ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T22:29:59.312155Z","caller":"traceutil/trace.go:171","msg":"trace[1519208284] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"238.489068ms","start":"2026-04-09T22:29:59.073654Z","end":"2026-04-09T22:29:59.312143Z","steps":["trace[1519208284] 'read index received' (duration: 30.901µs)","trace[1519208284] 'applied index is now lower than readState.Index' (duration: 238.457017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:59.312298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.642233ms","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-09T22:29:59.312318Z","caller":"traceutil/trace.go:171","msg":"trace[1254654088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"238.696094ms","start":"2026-04-09T22:29:59.073615Z","end":"2026-04-09T22:29:59.312311Z","steps":["trace[1254654088] 'agreement among raft nodes before linearized reading' (duration: 238.593081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:59.312406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.273608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-09T22:29:59.312414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.842608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-09T22:29:59.312471Z","caller":"traceutil/trace.go:171","msg":"trace[395706839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"224.35067ms","start":"2026-04-09T22:29:59.088104Z","end":"2026-04-09T22:29:59.312454Z","steps":["trace[395706839] 'agreement among raft nodes before linearized reading' (duration: 224.165645ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:59.31248Z","caller":"traceutil/trace.go:171","msg":"trace[103784942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"230.933401ms","start":"2026-04-09T22:29:59.081533Z","end":"2026-04-09T22:29:59.312466Z","steps":["trace[103784942] 'agreement among raft nodes before linearized reading' (duration: 230.748136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:59.312406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.474789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T22:29:59.312587Z","caller":"traceutil/trace.go:171","msg":"trace[668721286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"234.691634ms","start":"2026-04-09T22:29:59.077887Z","end":"2026-04-09T22:29:59.312579Z","steps":["trace[668721286] 'agreement among raft nodes before linearized reading' (duration: 234.371705ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:59.426867Z","caller":"traceutil/trace.go:171","msg":"trace[1210142095] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"111.219599ms","start":"2026-04-09T22:29:59.315628Z","end":"2026-04-09T22:29:59.426847Z","steps":["trace[1210142095] 'process raft request' (duration: 111.087395ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:59.450899Z","caller":"traceutil/trace.go:171","msg":"trace[295065462] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"134.982172ms","start":"2026-04-09T22:29:59.31589Z","end":"2026-04-09T22:29:59.450872Z","steps":["trace[295065462] 'process raft request' (duration: 134.729175ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:59.450911Z","caller":"traceutil/trace.go:171","msg":"trace[1993836818] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"134.729505ms","start":"2026-04-09T22:29:59.316157Z","end":"2026-04-09T22:29:59.450887Z","steps":["trace[1993836818] 'process raft request' (duration: 134.628302ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:59.451135Z","caller":"traceutil/trace.go:171","msg":"trace[1239922169] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"134.638962ms","start":"2026-04-09T22:29:59.316485Z","end":"2026-04-09T22:29:59.451124Z","steps":["trace[1239922169] 'process raft request' (duration: 134.346364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.915325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.083849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T22:30:13.915407Z","caller":"traceutil/trace.go:171","msg":"trace[304363740] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:1; response_revision:1165; }","duration":"169.218683ms","start":"2026-04-09T22:30:13.746172Z","end":"2026-04-09T22:30:13.915391Z","steps":["trace[304363740] 'range keys from in-memory index tree' (duration: 168.914395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.915301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.590704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:30:13.915515Z","caller":"traceutil/trace.go:171","msg":"trace[1887197885] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1165; }","duration":"109.81955ms","start":"2026-04-09T22:30:13.805675Z","end":"2026-04-09T22:30:13.915494Z","steps":["trace[1887197885] 'count revisions from in-memory index tree' (duration: 109.46516ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:31.720676Z","caller":"traceutil/trace.go:171","msg":"trace[1510041347] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"114.305948ms","start":"2026-04-09T22:30:31.606339Z","end":"2026-04-09T22:30:31.720645Z","steps":["trace[1510041347] 'process raft request' (duration: 114.137554ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:31.727672Z","caller":"traceutil/trace.go:171","msg":"trace[1235818311] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"102.696361ms","start":"2026-04-09T22:30:31.624956Z","end":"2026-04-09T22:30:31.727652Z","steps":["trace[1235818311] 'process raft request' (duration: 102.622089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:31.998155Z","caller":"traceutil/trace.go:171","msg":"trace[1265455788] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"266.140344ms","start":"2026-04-09T22:30:31.731986Z","end":"2026-04-09T22:30:31.998127Z","steps":["trace[1265455788] 'process raft request' (duration: 212.131356ms)","trace[1265455788] 'compare' (duration: 53.677369ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:32.011266Z","caller":"traceutil/trace.go:171","msg":"trace[238442805] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"278.939374ms","start":"2026-04-09T22:30:31.7323Z","end":"2026-04-09T22:30:32.01124Z","steps":["trace[238442805] 'process raft request' (duration: 278.870592ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:32.011293Z","caller":"traceutil/trace.go:171","msg":"trace[189153323] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"279.004396ms","start":"2026-04-09T22:30:31.732269Z","end":"2026-04-09T22:30:32.011273Z","steps":["trace[189153323] 'process raft request' (duration: 278.701468ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:59.966705Z","caller":"traceutil/trace.go:171","msg":"trace[2140818302] linearizableReadLoop","detail":"{readStateIndex:1743; appliedIndex:1742; }","duration":"190.185065ms","start":"2026-04-09T22:31:59.776495Z","end":"2026-04-09T22:31:59.96668Z","steps":["trace[2140818302] 'read index received' (duration: 190.062092ms)","trace[2140818302] 'applied index is now lower than readState.Index' (duration: 122.153µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:59.966837Z","caller":"traceutil/trace.go:171","msg":"trace[919711708] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"289.03566ms","start":"2026-04-09T22:31:59.67779Z","end":"2026-04-09T22:31:59.966825Z","steps":["trace[919711708] 'process raft request' (duration: 288.721012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:59.967033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.525454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:31:59.967086Z","caller":"traceutil/trace.go:171","msg":"trace[1186956586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1679; }","duration":"190.631237ms","start":"2026-04-09T22:31:59.776443Z","end":"2026-04-09T22:31:59.967074Z","steps":["trace[1186956586] 'agreement among raft nodes before linearized reading' (duration: 190.536555ms)"],"step_count":1}