{"level":"warn","ts":"2026-02-26T01:30:27.967208Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:30:27.967297Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T01:30:27.967366Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:30:27.967375Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-26T01:30:27.967391Z","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-02-26T01:30:27.968042Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-26T01:30:27.968129Z","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://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T01:30:27.971769Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.429854ms"} {"level":"info","ts":"2026-02-26T01:30:27.979416Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-26T01:30:27.979505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:30:27.979531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-26T01:30:27.979548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:30:27.97957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-26T01:30:27.97961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-26T01:30:28.008452Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:30:28.010109Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:30:28.01141Z","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-02-26T01:30:28.013411Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:30:28.013609Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:30:28.013803Z","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-02-26T01:30:28.013972Z","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-02-26T01:30:28.014001Z","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-02-26T01:30:28.014932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-26T01:30:28.015235Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-26T01:30:28.018334Z","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-02-26T01:30:28.018473Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-26T01:30:28.018499Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-26T01:30:28.018746Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:30:28.018792Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:30:28.380156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:30:28.380215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:30:28.380374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-26T01:30:28.380457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:30:28.38048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-26T01:30:28.380498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-26T01:30:28.380517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-26T01:30:28.381518Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:30:28.381923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:30:28.381904Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:30:28.3819Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:30:28.382228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:30:28.382282Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:30:28.385077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T01:30:28.386331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-26T01:30:28.388985Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:30:28.389093Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:30:28.389127Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:30:30.032157Z","caller":"traceutil/trace.go:171","msg":"trace[246134064] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"100.650678ms","start":"2026-02-26T01:30:29.931487Z","end":"2026-02-26T01:30:30.032137Z","steps":["trace[246134064] 'process raft request' (duration: 100.619098ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:30.032188Z","caller":"traceutil/trace.go:171","msg":"trace[636810770] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"104.219366ms","start":"2026-02-26T01:30:29.927931Z","end":"2026-02-26T01:30:30.03215Z","steps":["trace[636810770] 'process raft request' (duration: 102.477412ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:38.618195Z","caller":"traceutil/trace.go:171","msg":"trace[1090215878] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"119.326927ms","start":"2026-02-26T01:30:38.498848Z","end":"2026-02-26T01:30:38.618175Z","steps":["trace[1090215878] 'process raft request' (duration: 99.099482ms)","trace[1090215878] 'compare' (duration: 20.142263ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:30:39.158987Z","caller":"traceutil/trace.go:171","msg":"trace[1237904303] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"142.360296ms","start":"2026-02-26T01:30:39.016607Z","end":"2026-02-26T01:30:39.158967Z","steps":["trace[1237904303] 'process raft request' (duration: 142.241223ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:41.326824Z","caller":"traceutil/trace.go:171","msg":"trace[1576203921] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"159.775275ms","start":"2026-02-26T01:30:41.167014Z","end":"2026-02-26T01:30:41.326789Z","steps":["trace[1576203921] 'process raft request' (duration: 159.61924ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:42.212409Z","caller":"traceutil/trace.go:171","msg":"trace[892760634] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"124.566906ms","start":"2026-02-26T01:30:42.087814Z","end":"2026-02-26T01:30:42.212381Z","steps":["trace[892760634] 'process raft request' (duration: 79.075257ms)","trace[892760634] 'compare' (duration: 45.381206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:30:42.498369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.427708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-02-26T01:30:42.498565Z","caller":"traceutil/trace.go:171","msg":"trace[1650952634] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:363; }","duration":"139.762946ms","start":"2026-02-26T01:30:42.358776Z","end":"2026-02-26T01:30:42.498539Z","steps":["trace[1650952634] 'range keys from in-memory index tree' (duration: 139.322865ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:42.639384Z","caller":"traceutil/trace.go:171","msg":"trace[2008557685] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"132.405426ms","start":"2026-02-26T01:30:42.506953Z","end":"2026-02-26T01:30:42.639358Z","steps":["trace[2008557685] 'process raft request' (duration: 132.197951ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:46.867449Z","caller":"traceutil/trace.go:171","msg":"trace[1238774846] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"130.769237ms","start":"2026-02-26T01:30:46.736664Z","end":"2026-02-26T01:30:46.867433Z","steps":["trace[1238774846] 'process raft request' (duration: 130.656164ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.286704Z","caller":"traceutil/trace.go:171","msg":"trace[1880435808] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"122.422744ms","start":"2026-02-26T01:30:47.164253Z","end":"2026-02-26T01:30:47.286676Z","steps":["trace[1880435808] 'process raft request' (duration: 46.08745ms)","trace[1880435808] 'compare' (duration: 76.208911ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:30:47.289871Z","caller":"traceutil/trace.go:171","msg":"trace[44932309] transaction","detail":"{read_only:false; number_of_response:1; response_revision:392; }","duration":"102.314435ms","start":"2026-02-26T01:30:47.187497Z","end":"2026-02-26T01:30:47.289812Z","steps":["trace[44932309] 'process raft request' (duration: 101.729271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:30:47.500202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.770028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:30:47.500615Z","caller":"traceutil/trace.go:171","msg":"trace[117695684] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"185.648638ms","start":"2026-02-26T01:30:47.314955Z","end":"2026-02-26T01:30:47.500603Z","steps":["trace[117695684] 'process raft request' (duration: 185.355591ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.500646Z","caller":"traceutil/trace.go:171","msg":"trace[801435446] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"185.132906ms","start":"2026-02-26T01:30:47.315427Z","end":"2026-02-26T01:30:47.50056Z","steps":["trace[801435446] 'process raft request' (duration: 184.901111ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.500719Z","caller":"traceutil/trace.go:171","msg":"trace[1724418384] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"195.21051ms","start":"2026-02-26T01:30:47.305444Z","end":"2026-02-26T01:30:47.500655Z","steps":["trace[1724418384] 'process raft request' (duration: 81.718685ms)","trace[1724418384] 'compare' (duration: 112.653435ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:30:47.501202Z","caller":"traceutil/trace.go:171","msg":"trace[1021004952] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"192.657389ms","start":"2026-02-26T01:30:47.308523Z","end":"2026-02-26T01:30:47.50118Z","steps":["trace[1021004952] 'process raft request' (duration: 191.744976ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.501449Z","caller":"traceutil/trace.go:171","msg":"trace[38935043] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"184.522131ms","start":"2026-02-26T01:30:47.316912Z","end":"2026-02-26T01:30:47.501434Z","steps":["trace[38935043] 'process raft request' (duration: 183.438634ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.67372Z","caller":"traceutil/trace.go:171","msg":"trace[1427303104] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"167.122478ms","start":"2026-02-26T01:30:47.506578Z","end":"2026-02-26T01:30:47.673701Z","steps":["trace[1427303104] 'process raft request' (duration: 136.007353ms)","trace[1427303104] 'compare' (duration: 30.834749ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:30:47.673748Z","caller":"traceutil/trace.go:171","msg":"trace[913032277] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"166.913703ms","start":"2026-02-26T01:30:47.506803Z","end":"2026-02-26T01:30:47.673717Z","steps":["trace[913032277] 'process raft request' (duration: 166.776809ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.673656Z","caller":"traceutil/trace.go:171","msg":"trace[1610589136] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"166.945484ms","start":"2026-02-26T01:30:47.506695Z","end":"2026-02-26T01:30:47.673641Z","steps":["trace[1610589136] 'process raft request' (duration: 166.839731ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.674063Z","caller":"traceutil/trace.go:171","msg":"trace[1503101111] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"166.288418ms","start":"2026-02-26T01:30:47.507744Z","end":"2026-02-26T01:30:47.674033Z","steps":["trace[1503101111] 'process raft request' (duration: 165.865658ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.674483Z","caller":"traceutil/trace.go:171","msg":"trace[1424259504] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"165.471079ms","start":"2026-02-26T01:30:47.508988Z","end":"2026-02-26T01:30:47.674459Z","steps":["trace[1424259504] 'process raft request' (duration: 165.037098ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:47.674551Z","caller":"traceutil/trace.go:171","msg":"trace[1894656811] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"164.518855ms","start":"2026-02-26T01:30:47.510027Z","end":"2026-02-26T01:30:47.674546Z","steps":["trace[1894656811] 'process raft request' (duration: 164.364461ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:52.216812Z","caller":"traceutil/trace.go:171","msg":"trace[1006133853] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"499.508352ms","start":"2026-02-26T01:30:51.717278Z","end":"2026-02-26T01:30:52.216787Z","steps":["trace[1006133853] 'read index received' (duration: 499.43597ms)","trace[1006133853] 'applied index is now lower than readState.Index' (duration: 70.942µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:30:52.216814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:30:51.329231Z","time spent":"887.576247ms","remote":"127.0.0.1:53606","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T01:30:52.216986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.690436ms","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-02-26T01:30:52.217047Z","caller":"traceutil/trace.go:171","msg":"trace[735481877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"499.781648ms","start":"2026-02-26T01:30:51.717246Z","end":"2026-02-26T01:30:52.217028Z","steps":["trace[735481877] 'agreement among raft nodes before linearized reading' (duration: 499.651495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:30:52.216986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.040538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:30:52.217085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:30:51.717233Z","time spent":"499.84474ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:30:52.217118Z","caller":"traceutil/trace.go:171","msg":"trace[1945949488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"359.207452ms","start":"2026-02-26T01:30:51.857897Z","end":"2026-02-26T01:30:52.217105Z","steps":["trace[1945949488] 'agreement among raft nodes before linearized reading' (duration: 359.042228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:30:52.217183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:30:51.857865Z","time spent":"359.285784ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:30:52.473187Z","caller":"traceutil/trace.go:171","msg":"trace[1192626906] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:436; }","duration":"181.793653ms","start":"2026-02-26T01:30:52.291375Z","end":"2026-02-26T01:30:52.473169Z","steps":["trace[1192626906] 'read index received' (duration: 133.660597ms)","trace[1192626906] 'applied index is now lower than readState.Index' (duration: 48.132076ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:30:52.473215Z","caller":"traceutil/trace.go:171","msg":"trace[153794669] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"255.383786ms","start":"2026-02-26T01:30:52.217801Z","end":"2026-02-26T01:30:52.473185Z","steps":["trace[153794669] 'process raft request' (duration: 207.22525ms)","trace[153794669] 'compare' (duration: 47.938961ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:30:52.473295Z","caller":"traceutil/trace.go:171","msg":"trace[2054933988] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"252.004073ms","start":"2026-02-26T01:30:52.221284Z","end":"2026-02-26T01:30:52.473288Z","steps":["trace[2054933988] 'process raft request' (duration: 251.849119ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:52.473263Z","caller":"traceutil/trace.go:171","msg":"trace[50468859] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"252.26652ms","start":"2026-02-26T01:30:52.220961Z","end":"2026-02-26T01:30:52.473227Z","steps":["trace[50468859] 'process raft request' (duration: 252.126897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:30:52.473593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.204723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:30:52.473626Z","caller":"traceutil/trace.go:171","msg":"trace[1555726577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"182.290225ms","start":"2026-02-26T01:30:52.291326Z","end":"2026-02-26T01:30:52.473616Z","steps":["trace[1555726577] 'agreement among raft nodes before linearized reading' (duration: 182.220244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:30:52.473664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.625989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:30:52.473709Z","caller":"traceutil/trace.go:171","msg":"trace[2111367793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"169.723301ms","start":"2026-02-26T01:30:52.303975Z","end":"2026-02-26T01:30:52.473698Z","steps":["trace[2111367793] 'agreement among raft nodes before linearized reading' (duration: 169.640119ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:30:53.244879Z","caller":"traceutil/trace.go:171","msg":"trace[1646199568] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"121.574214ms","start":"2026-02-26T01:30:53.123279Z","end":"2026-02-26T01:30:53.244853Z","steps":["trace[1646199568] 'process raft request' (duration: 121.42031ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:31:01.011553Z","caller":"traceutil/trace.go:171","msg":"trace[1984731394] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"442.091509ms","start":"2026-02-26T01:31:00.569437Z","end":"2026-02-26T01:31:01.011528Z","steps":["trace[1984731394] 'read index received' (duration: 441.978206ms)","trace[1984731394] 'applied index is now lower than readState.Index' (duration: 112.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:31:01.011614Z","caller":"traceutil/trace.go:171","msg":"trace[1928542781] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"549.510342ms","start":"2026-02-26T01:31:00.462059Z","end":"2026-02-26T01:31:01.011569Z","steps":["trace[1928542781] 'process raft request' (duration: 549.321408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:01.011817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.351156ms","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-02-26T01:31:01.011802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.089953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:31:01.011858Z","caller":"traceutil/trace.go:171","msg":"trace[2133076120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"442.440857ms","start":"2026-02-26T01:31:00.569404Z","end":"2026-02-26T01:31:01.011845Z","steps":["trace[2133076120] 'agreement among raft nodes before linearized reading' (duration: 442.266343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:01.011897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:00.569388Z","time spent":"442.501409ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:31:01.011889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.736474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:31:01.011851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.452418ms","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-02-26T01:31:01.012006Z","caller":"traceutil/trace.go:171","msg":"trace[485138470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"389.596903ms","start":"2026-02-26T01:31:00.622357Z","end":"2026-02-26T01:31:01.011953Z","steps":["trace[485138470] 'agreement among raft nodes before linearized reading' (duration: 389.368737ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:31:01.011901Z","caller":"traceutil/trace.go:171","msg":"trace[2113194537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"397.211676ms","start":"2026-02-26T01:31:00.614661Z","end":"2026-02-26T01:31:01.011873Z","steps":["trace[2113194537] 'agreement among raft nodes before linearized reading' (duration: 397.027801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:01.012033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:00.462046Z","time spent":"549.649566ms","remote":"127.0.0.1:53780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:31:01.011963Z","caller":"traceutil/trace.go:171","msg":"trace[445505487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"391.850156ms","start":"2026-02-26T01:31:00.620096Z","end":"2026-02-26T01:31:01.011946Z","steps":["trace[445505487] 'agreement among raft nodes before linearized reading' (duration: 391.682502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:01.012066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:00.622351Z","time spent":"389.704816ms","remote":"127.0.0.1:53852","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-02-26T01:31:01.012093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:00.614656Z","time spent":"397.418181ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:31:01.012111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:00.620075Z","time spent":"392.021201ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:31:01.796036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:01.329873Z","time spent":"466.157808ms","remote":"127.0.0.1:53606","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-26T01:31:02.322806Z","caller":"traceutil/trace.go:171","msg":"trace[1312271964] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"525.62488ms","start":"2026-02-26T01:31:01.797143Z","end":"2026-02-26T01:31:02.322768Z","steps":["trace[1312271964] 'process raft request' (duration: 438.868167ms)","trace[1312271964] 'compare' (duration: 86.337183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:31:02.32296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:01.797125Z","time spent":"525.753123ms","remote":"127.0.0.1:53606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:31:07.369185Z","caller":"traceutil/trace.go:171","msg":"trace[521123945] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"317.3149ms","start":"2026-02-26T01:31:07.05185Z","end":"2026-02-26T01:31:07.369165Z","steps":["trace[521123945] 'read index received' (duration: 317.135446ms)","trace[521123945] 'applied index is now lower than readState.Index' (duration: 178.854µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:31:07.369313Z","caller":"traceutil/trace.go:171","msg":"trace[1178350884] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"530.465774ms","start":"2026-02-26T01:31:06.838815Z","end":"2026-02-26T01:31:07.369281Z","steps":["trace[1178350884] 'process raft request' (duration: 530.223008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:07.369406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.934265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:31:07.369434Z","caller":"traceutil/trace.go:171","msg":"trace[168468536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"312.975996ms","start":"2026-02-26T01:31:07.056447Z","end":"2026-02-26T01:31:07.369423Z","steps":["trace[168468536] 'agreement among raft nodes before linearized reading' (duration: 312.921545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:07.369452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:06.838779Z","time spent":"530.607968ms","remote":"127.0.0.1:53784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:31:07.369459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:07.056436Z","time spent":"313.016077ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:31:07.369344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.472484ms","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-02-26T01:31:07.369541Z","caller":"traceutil/trace.go:171","msg":"trace[516716481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"317.72097ms","start":"2026-02-26T01:31:07.051808Z","end":"2026-02-26T01:31:07.369529Z","steps":["trace[516716481] 'agreement among raft nodes before linearized reading' (duration: 317.461114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:07.369562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:07.051789Z","time spent":"317.766731ms","remote":"127.0.0.1:53852","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":"warn","ts":"2026-02-26T01:31:07.369651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.881732ms","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-02-26T01:31:07.369677Z","caller":"traceutil/trace.go:171","msg":"trace[19640834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"297.928293ms","start":"2026-02-26T01:31:07.071743Z","end":"2026-02-26T01:31:07.369671Z","steps":["trace[19640834] 'agreement among raft nodes before linearized reading' (duration: 297.882202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:07.36974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.305975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T01:31:07.369767Z","caller":"traceutil/trace.go:171","msg":"trace[1952000447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"296.357755ms","start":"2026-02-26T01:31:07.073402Z","end":"2026-02-26T01:31:07.36976Z","steps":["trace[1952000447] 'agreement among raft nodes before linearized reading' (duration: 296.315764ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:31:07.852704Z","caller":"traceutil/trace.go:171","msg":"trace[728652879] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"476.437661ms","start":"2026-02-26T01:31:07.376228Z","end":"2026-02-26T01:31:07.852665Z","steps":["trace[728652879] 'process raft request' (duration: 476.39978ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:31:07.852735Z","caller":"traceutil/trace.go:171","msg":"trace[1695576186] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"478.569002ms","start":"2026-02-26T01:31:07.374138Z","end":"2026-02-26T01:31:07.852707Z","steps":["trace[1695576186] 'process raft request' (duration: 478.253324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:07.852865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:07.374116Z","time spent":"478.677784ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:31:07.852897Z","caller":"traceutil/trace.go:171","msg":"trace[377408411] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"477.793773ms","start":"2026-02-26T01:31:07.375094Z","end":"2026-02-26T01:31:07.852888Z","steps":["trace[377408411] 'process raft request' (duration: 477.495856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:07.852857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:07.376207Z","time spent":"476.571635ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:31:07.852948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:07.375086Z","time spent":"477.838995ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:31:08.192029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.380464ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-26T01:31:10.890518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034544997s","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-02-26T01:31:10.89061Z","caller":"traceutil/trace.go:171","msg":"trace[748046715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.03467s","start":"2026-02-26T01:31:09.85592Z","end":"2026-02-26T01:31:10.89059Z","steps":["trace[748046715] 'range keys from in-memory index tree' (duration: 1.034464605s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:10.890649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:09.855903Z","time spent":"1.034737132s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:31:10.891046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:10.371875Z","time spent":"519.165159ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T01:31:10.891066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03467392s","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-02-26T01:31:10.891118Z","caller":"traceutil/trace.go:171","msg":"trace[824577225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"1.034745731s","start":"2026-02-26T01:31:09.856361Z","end":"2026-02-26T01:31:10.891107Z","steps":["trace[824577225] 'range keys from in-memory index tree' (duration: 1.034610479s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:10.891146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:09.856355Z","time spent":"1.034786113s","remote":"127.0.0.1:53852","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-02-26T01:31:10.891162Z","caller":"traceutil/trace.go:171","msg":"trace[130298621] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"188.406822ms","start":"2026-02-26T01:31:10.702738Z","end":"2026-02-26T01:31:10.891145Z","steps":["trace[130298621] 'process raft request' (duration: 106.164504ms)","trace[130298621] 'compare' (duration: 80.913147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:31:10.892107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034553509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:31:10.892159Z","caller":"traceutil/trace.go:171","msg":"trace[383955142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.036419472s","start":"2026-02-26T01:31:09.855727Z","end":"2026-02-26T01:31:10.892146Z","steps":["trace[383955142] 'range keys from in-memory index tree' (duration: 1.034475346s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:31:10.892199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:31:09.855709Z","time spent":"1.036478554s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:31:14.291888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.756325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:31:14.292002Z","caller":"traceutil/trace.go:171","msg":"trace[649713286] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"278.782474ms","start":"2026-02-26T01:31:14.013202Z","end":"2026-02-26T01:31:14.291984Z","steps":["trace[649713286] 'process raft request' (duration: 101.853057ms)","trace[649713286] 'compare' (duration: 176.479982ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:31:14.56753Z","caller":"traceutil/trace.go:171","msg":"trace[42811018] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"260.394513ms","start":"2026-02-26T01:31:14.30689Z","end":"2026-02-26T01:31:14.567284Z","steps":["trace[42811018] 'process raft request' (duration: 260.18676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:17.698255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085912045326996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:32:18.199335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085912045326996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:32:18.232183Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.036476085s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:32:18.232613Z","caller":"traceutil/trace.go:171","msg":"trace[120584507] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"1.037059646s","start":"2026-02-26T01:32:17.195531Z","end":"2026-02-26T01:32:18.232591Z","steps":["trace[120584507] 'process raft request' (duration: 1.036931353s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:32:18.232623Z","caller":"traceutil/trace.go:171","msg":"trace[1136962112] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:890; }","duration":"1.034791114s","start":"2026-02-26T01:32:17.197796Z","end":"2026-02-26T01:32:18.232588Z","steps":["trace[1136962112] 'read index received' (duration: 1.034778044s)","trace[1136962112] 'applied index is now lower than readState.Index' (duration: 8.66µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:32:18.232741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:17.195471Z","time spent":"1.037205028s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:32:18.232914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035087289s","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-02-26T01:32:18.232987Z","caller":"traceutil/trace.go:171","msg":"trace[2011112311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:853; }","duration":"1.035194091s","start":"2026-02-26T01:32:17.197773Z","end":"2026-02-26T01:32:18.232967Z","steps":["trace[2011112311] 'agreement among raft nodes before linearized reading' (duration: 1.034984687s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:18.232976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.766525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:32:18.233044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:17.197762Z","time spent":"1.035269342s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T01:32:18.23305Z","caller":"traceutil/trace.go:171","msg":"trace[1805973956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"926.841196ms","start":"2026-02-26T01:32:17.30619Z","end":"2026-02-26T01:32:18.233031Z","steps":["trace[1805973956] 'agreement among raft nodes before linearized reading' (duration: 926.720374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:18.233094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:17.306151Z","time spent":"926.933448ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:32:18.233129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.593125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:32:18.233218Z","caller":"traceutil/trace.go:171","msg":"trace[1479866445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:853; }","duration":"415.733367ms","start":"2026-02-26T01:32:17.817449Z","end":"2026-02-26T01:32:18.233182Z","steps":["trace[1479866445] 'agreement among raft nodes before linearized reading' (duration: 415.459742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:18.233277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:17.817431Z","time spent":"415.836869ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:32:18.233985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032783636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:32:18.234037Z","caller":"traceutil/trace.go:171","msg":"trace[1270519958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"1.032889008s","start":"2026-02-26T01:32:17.201134Z","end":"2026-02-26T01:32:18.234023Z","steps":["trace[1270519958] 'agreement among raft nodes before linearized reading' (duration: 1.031768338s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:18.234481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:17.201108Z","time spent":"1.032956019s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:32:18.833725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085912045327000,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:32:19.173044Z","caller":"traceutil/trace.go:171","msg":"trace[1741313606] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:890; }","duration":"839.630862ms","start":"2026-02-26T01:32:18.333395Z","end":"2026-02-26T01:32:19.173026Z","steps":["trace[1741313606] 'read index received' (duration: 836.752838ms)","trace[1741313606] 'applied index is now lower than readState.Index' (duration: 2.877154ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:32:19.173243Z","caller":"traceutil/trace.go:171","msg":"trace[1513507884] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"935.176865ms","start":"2026-02-26T01:32:18.238059Z","end":"2026-02-26T01:32:19.173236Z","steps":["trace[1513507884] 'process raft request' (duration: 931.940504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:19.173301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.911957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:32:19.173358Z","caller":"traceutil/trace.go:171","msg":"trace[1394131618] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:856; }","duration":"840.028969ms","start":"2026-02-26T01:32:18.333316Z","end":"2026-02-26T01:32:19.173345Z","steps":["trace[1394131618] 'agreement among raft nodes before linearized reading' (duration: 839.932827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:19.173389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:18.333293Z","time spent":"840.087901ms","remote":"127.0.0.1:53774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:32:19.173414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.052519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-26T01:32:19.173439Z","caller":"traceutil/trace.go:171","msg":"trace[888530758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"451.100429ms","start":"2026-02-26T01:32:18.722331Z","end":"2026-02-26T01:32:19.173432Z","steps":["trace[888530758] 'agreement among raft nodes before linearized reading' (duration: 451.012368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:19.17346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:18.722316Z","time spent":"451.137891ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:32:19.173553Z","caller":"traceutil/trace.go:171","msg":"trace[447457061] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"933.564254ms","start":"2026-02-26T01:32:18.239979Z","end":"2026-02-26T01:32:19.173543Z","steps":["trace[447457061] 'process raft request' (duration: 932.935673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:19.17332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:18.238034Z","time spent":"935.227875ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:32:19.173624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:18.239961Z","time spent":"933.611755ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:32:19.173699Z","caller":"traceutil/trace.go:171","msg":"trace[1016113449] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"933.29632ms","start":"2026-02-26T01:32:18.240371Z","end":"2026-02-26T01:32:19.173668Z","steps":["trace[1016113449] 'process raft request' (duration: 932.627918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:19.173936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:18.240365Z","time spent":"933.423472ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:32:33.519668Z","caller":"traceutil/trace.go:171","msg":"trace[1852650629] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"389.305422ms","start":"2026-02-26T01:32:33.130334Z","end":"2026-02-26T01:32:33.519639Z","steps":["trace[1852650629] 'read index received' (duration: 389.033677ms)","trace[1852650629] 'applied index is now lower than readState.Index' (duration: 270.345µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:32:33.519689Z","caller":"traceutil/trace.go:171","msg":"trace[1003455452] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"416.066054ms","start":"2026-02-26T01:32:33.103598Z","end":"2026-02-26T01:32:33.519664Z","steps":["trace[1003455452] 'process raft request' (duration: 415.839859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:33.519857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.501766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:32:33.5199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:33.103572Z","time spent":"416.243507ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:32:33.519924Z","caller":"traceutil/trace.go:171","msg":"trace[899398885] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:924; }","duration":"389.611237ms","start":"2026-02-26T01:32:33.130286Z","end":"2026-02-26T01:32:33.519897Z","steps":["trace[899398885] 'agreement among raft nodes before linearized reading' (duration: 389.479835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:33.519998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:32:33.130266Z","time spent":"389.7225ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:32:33.520022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.601771ms","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-02-26T01:32:33.520082Z","caller":"traceutil/trace.go:171","msg":"trace[525889125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"287.703253ms","start":"2026-02-26T01:32:33.232363Z","end":"2026-02-26T01:32:33.520066Z","steps":["trace[525889125] 'agreement among raft nodes before linearized reading' (duration: 287.52965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:33.520232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.321967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:32:33.520259Z","caller":"traceutil/trace.go:171","msg":"trace[1329927533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"265.373658ms","start":"2026-02-26T01:32:33.254877Z","end":"2026-02-26T01:32:33.520251Z","steps":["trace[1329927533] 'agreement among raft nodes before linearized reading' (duration: 265.295326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:32:33.520483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.06917ms","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-02-26T01:32:33.520554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.735385ms","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-02-26T01:32:33.520589Z","caller":"traceutil/trace.go:171","msg":"trace[1978426245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"265.793866ms","start":"2026-02-26T01:32:33.254786Z","end":"2026-02-26T01:32:33.52058Z","steps":["trace[1978426245] 'agreement among raft nodes before linearized reading' (duration: 265.500541ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:32:33.520593Z","caller":"traceutil/trace.go:171","msg":"trace[409593588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"268.206022ms","start":"2026-02-26T01:32:33.25237Z","end":"2026-02-26T01:32:33.520576Z","steps":["trace[409593588] 'agreement among raft nodes before linearized reading' (duration: 267.948697ms)"],"step_count":1}