{"level":"warn","ts":"2026-02-25T16:58:07.391583Z","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-25T16:58:07.391692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.212: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.19.213.212:2380","--initial-cluster=instance=https://199.19.213.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.212: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-25T16:58:07.391784Z","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-25T16:58:07.391803Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.212:2380"]} {"level":"info","ts":"2026-02-25T16:58:07.391826Z","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-25T16:58:07.392708Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.212:2379"]} {"level":"info","ts":"2026-02-25T16:58:07.392815Z","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.19.213.212:2380"],"listen-peer-urls":["https://199.19.213.212:2380"],"advertise-client-urls":["https://199.19.213.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.212: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-25T16:58:07.404577Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.476218ms"} {"level":"info","ts":"2026-02-25T16:58:07.461448Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e1ab0380b9290bdc","cluster-id":"a3ed131e36ad6843"} {"level":"info","ts":"2026-02-25T16:58:07.461558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T16:58:07.461585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became follower at term 0"} {"level":"info","ts":"2026-02-25T16:58:07.4616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e1ab0380b9290bdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T16:58:07.46161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became follower at term 1"} {"level":"info","ts":"2026-02-25T16:58:07.461653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc switched to configuration voters=(16261094730948479964)"} {"level":"warn","ts":"2026-02-25T16:58:07.475144Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T16:58:07.476702Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T16:58:07.478376Z","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-25T16:58:07.480628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e1ab0380b9290bdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T16:58:07.480737Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e1ab0380b9290bdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T16:58:07.481058Z","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-25T16:58:07.481191Z","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-25T16:58:07.481203Z","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-25T16:58:07.482858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc switched to configuration voters=(16261094730948479964)"} {"level":"info","ts":"2026-02-25T16:58:07.483106Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a3ed131e36ad6843","local-member-id":"e1ab0380b9290bdc","added-peer-id":"e1ab0380b9290bdc","added-peer-peer-urls":["https://199.19.213.212:2380"]} {"level":"info","ts":"2026-02-25T16:58:07.486893Z","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-25T16:58:07.487052Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.212:2380"} {"level":"info","ts":"2026-02-25T16:58:07.487086Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.212:2380"} {"level":"info","ts":"2026-02-25T16:58:07.487251Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e1ab0380b9290bdc","initial-advertise-peer-urls":["https://199.19.213.212:2380"],"listen-peer-urls":["https://199.19.213.212:2380"],"advertise-client-urls":["https://199.19.213.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T16:58:07.487305Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T16:58:08.262412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T16:58:08.26248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T16:58:08.262521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc received MsgPreVoteResp from e1ab0380b9290bdc at term 1"} {"level":"info","ts":"2026-02-25T16:58:08.262541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became candidate at term 2"} {"level":"info","ts":"2026-02-25T16:58:08.262552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc received MsgVoteResp from e1ab0380b9290bdc at term 2"} {"level":"info","ts":"2026-02-25T16:58:08.262568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became leader at term 2"} {"level":"info","ts":"2026-02-25T16:58:08.262588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e1ab0380b9290bdc elected leader e1ab0380b9290bdc at term 2"} {"level":"info","ts":"2026-02-25T16:58:08.385166Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T16:58:08.385176Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e1ab0380b9290bdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.212:2379]}","request-path":"/0/members/e1ab0380b9290bdc/attributes","cluster-id":"a3ed131e36ad6843","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T16:58:08.385438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T16:58:08.385575Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T16:58:08.385751Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T16:58:08.385817Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:58:08.389039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T16:58:08.38914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.212:2379"} {"level":"info","ts":"2026-02-25T16:58:08.590535Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a3ed131e36ad6843","local-member-id":"e1ab0380b9290bdc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:58:08.590638Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:58:08.590676Z","caller":"traceutil/trace.go:171","msg":"trace[1911437889] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"154.626759ms","start":"2026-02-25T16:58:08.436038Z","end":"2026-02-25T16:58:08.590664Z","steps":["trace[1911437889] 'read index received' (duration: 154.440277ms)","trace[1911437889] 'applied index is now lower than readState.Index' (duration: 185.442µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:58:08.590705Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T16:58:08.59306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.927285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T16:58:08.593137Z","caller":"traceutil/trace.go:171","msg":"trace[430025259] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"157.027166ms","start":"2026-02-25T16:58:08.436087Z","end":"2026-02-25T16:58:08.593114Z","steps":["trace[430025259] 'agreement among raft nodes before linearized reading' (duration: 154.6484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:08.593665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.616753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T16:58:08.593711Z","caller":"traceutil/trace.go:171","msg":"trace[1621698939] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"157.665254ms","start":"2026-02-25T16:58:08.436032Z","end":"2026-02-25T16:58:08.593697Z","steps":["trace[1621698939] 'agreement among raft nodes before linearized reading' (duration: 154.678971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:08.595342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.716503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T16:58:08.595391Z","caller":"traceutil/trace.go:171","msg":"trace[960150796] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"111.805333ms","start":"2026-02-25T16:58:08.483575Z","end":"2026-02-25T16:58:08.59538Z","steps":["trace[960150796] 'agreement among raft nodes before linearized reading' (duration: 110.670551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:10.901303Z","caller":"traceutil/trace.go:171","msg":"trace[2096538400] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"136.819655ms","start":"2026-02-25T16:58:10.764449Z","end":"2026-02-25T16:58:10.901269Z","steps":["trace[2096538400] 'process raft request' (duration: 132.792181ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:11.922894Z","caller":"traceutil/trace.go:171","msg":"trace[548040758] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"101.349169ms","start":"2026-02-25T16:58:11.821527Z","end":"2026-02-25T16:58:11.922876Z","steps":["trace[548040758] 'process raft request' (duration: 101.271728ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:11.922901Z","caller":"traceutil/trace.go:171","msg":"trace[1965607100] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"118.340673ms","start":"2026-02-25T16:58:11.804518Z","end":"2026-02-25T16:58:11.922859Z","steps":["trace[1965607100] 'process raft request' (duration: 90.206248ms)","trace[1965607100] 'compare' (duration: 27.927432ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:58:12.30522Z","caller":"traceutil/trace.go:171","msg":"trace[22198984] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"103.061318ms","start":"2026-02-25T16:58:12.202122Z","end":"2026-02-25T16:58:12.305183Z","steps":["trace[22198984] 'process raft request' (duration: 69.728559ms)","trace[22198984] 'compare' (duration: 33.168077ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:58:25.605306Z","caller":"traceutil/trace.go:171","msg":"trace[2120805307] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"124.390514ms","start":"2026-02-25T16:58:25.48089Z","end":"2026-02-25T16:58:25.60528Z","steps":["trace[2120805307] 'read index received' (duration: 124.172862ms)","trace[2120805307] 'applied index is now lower than readState.Index' (duration: 216.802µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:58:25.605375Z","caller":"traceutil/trace.go:171","msg":"trace[1595116214] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"178.380076ms","start":"2026-02-25T16:58:25.426963Z","end":"2026-02-25T16:58:25.605343Z","steps":["trace[1595116214] 'process raft request' (duration: 178.164684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:25.605445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.538226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T16:58:25.605515Z","caller":"traceutil/trace.go:171","msg":"trace[1226246357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"124.623687ms","start":"2026-02-25T16:58:25.480847Z","end":"2026-02-25T16:58:25.60547Z","steps":["trace[1226246357] 'agreement among raft nodes before linearized reading' (duration: 124.543456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:25.909732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.711246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T16:58:25.910017Z","caller":"traceutil/trace.go:171","msg":"trace[1305250271] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"298.750231ms","start":"2026-02-25T16:58:25.611217Z","end":"2026-02-25T16:58:25.909967Z","steps":["trace[1305250271] 'process raft request' (duration: 96.428629ms)","trace[1305250271] 'compare' (duration: 201.592305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:58:32.537725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854730196220831975,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T16:58:32.689423Z","caller":"traceutil/trace.go:171","msg":"trace[954808712] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"652.010527ms","start":"2026-02-25T16:58:32.03739Z","end":"2026-02-25T16:58:32.689401Z","steps":["trace[954808712] 'read index received' (duration: 651.876855ms)","trace[954808712] 'applied index is now lower than readState.Index' (duration: 132.392µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:58:32.689535Z","caller":"traceutil/trace.go:171","msg":"trace[766524041] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"669.031978ms","start":"2026-02-25T16:58:32.020474Z","end":"2026-02-25T16:58:32.689506Z","steps":["trace[766524041] 'process raft request' (duration: 668.768605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:32.68959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.173498ms","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-25T16:58:32.689649Z","caller":"traceutil/trace.go:171","msg":"trace[1520807747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"652.274019ms","start":"2026-02-25T16:58:32.037359Z","end":"2026-02-25T16:58:32.689633Z","steps":["trace[1520807747] 'agreement among raft nodes before linearized reading' (duration: 652.155118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:32.689686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:32.037345Z","time spent":"652.332599ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T16:58:32.689983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:32.020458Z","time spent":"669.166569ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:58:33.148212Z","caller":"traceutil/trace.go:171","msg":"trace[887519341] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"337.629487ms","start":"2026-02-25T16:58:32.81056Z","end":"2026-02-25T16:58:33.14819Z","steps":["trace[887519341] 'read index received' (duration: 337.419895ms)","trace[887519341] 'applied index is now lower than readState.Index' (duration: 208.732µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:58:33.148337Z","caller":"traceutil/trace.go:171","msg":"trace[1729973210] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"454.245243ms","start":"2026-02-25T16:58:32.694052Z","end":"2026-02-25T16:58:33.148297Z","steps":["trace[1729973210] 'process raft request' (duration: 454.003811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:33.148384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.802109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T16:58:33.148469Z","caller":"traceutil/trace.go:171","msg":"trace[167646294] range","detail":"{range_begin:/registry/masterleases/199.19.213.212; range_end:; response_count:1; response_revision:459; }","duration":"337.94074ms","start":"2026-02-25T16:58:32.810511Z","end":"2026-02-25T16:58:33.148452Z","steps":["trace[167646294] 'agreement among raft nodes before linearized reading' (duration: 337.800898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:33.148471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.418183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T16:58:33.148535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:32.810491Z","time spent":"338.028921ms","remote":"127.0.0.1:49158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.212\" "} {"level":"info","ts":"2026-02-25T16:58:33.148549Z","caller":"traceutil/trace.go:171","msg":"trace[914121381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"264.544663ms","start":"2026-02-25T16:58:32.88398Z","end":"2026-02-25T16:58:33.148525Z","steps":["trace[914121381] 'agreement among raft nodes before linearized reading' (duration: 264.398872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:33.148556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:32.694034Z","time spent":"454.419485ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:58:33.148767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.324938ms","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-25T16:58:33.148825Z","caller":"traceutil/trace.go:171","msg":"trace[1086805988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"175.415308ms","start":"2026-02-25T16:58:32.973391Z","end":"2026-02-25T16:58:33.148807Z","steps":["trace[1086805988] 'agreement among raft nodes before linearized reading' (duration: 175.038405ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:39.487936Z","caller":"traceutil/trace.go:171","msg":"trace[848973967] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"302.108531ms","start":"2026-02-25T16:58:39.185809Z","end":"2026-02-25T16:58:39.487918Z","steps":["trace[848973967] 'read index received' (duration: 301.97881ms)","trace[848973967] 'applied index is now lower than readState.Index' (duration: 128.961µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:58:39.488175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.346593ms","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-25T16:58:39.488023Z","caller":"traceutil/trace.go:171","msg":"trace[1001644607] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"315.631955ms","start":"2026-02-25T16:58:39.172355Z","end":"2026-02-25T16:58:39.487987Z","steps":["trace[1001644607] 'process raft request' (duration: 315.434783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:39.488283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.779988ms","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-02-25T16:58:39.488429Z","caller":"traceutil/trace.go:171","msg":"trace[1183862018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"301.950849ms","start":"2026-02-25T16:58:39.186429Z","end":"2026-02-25T16:58:39.48838Z","steps":["trace[1183862018] 'agreement among raft nodes before linearized reading' (duration: 301.664157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:39.488509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:39.186418Z","time spent":"302.07756ms","remote":"127.0.0.1:49376","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":"info","ts":"2026-02-25T16:58:39.488231Z","caller":"traceutil/trace.go:171","msg":"trace[1306890847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"302.499294ms","start":"2026-02-25T16:58:39.185721Z","end":"2026-02-25T16:58:39.48822Z","steps":["trace[1306890847] 'agreement among raft nodes before linearized reading' (duration: 302.322912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:39.488535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:39.172331Z","time spent":"316.005958ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:58:39.48863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:39.185698Z","time spent":"302.913828ms","remote":"127.0.0.1:49376","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-25T16:58:43.037667Z","caller":"traceutil/trace.go:171","msg":"trace[942093166] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"222.807113ms","start":"2026-02-25T16:58:42.814804Z","end":"2026-02-25T16:58:43.037611Z","steps":["trace[942093166] 'process raft request' (duration: 218.185368ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:46.928711Z","caller":"traceutil/trace.go:171","msg":"trace[1690385863] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"170.302996ms","start":"2026-02-25T16:58:46.758378Z","end":"2026-02-25T16:58:46.928681Z","steps":["trace[1690385863] 'read index received' (duration: 170.122129ms)","trace[1690385863] 'applied index is now lower than readState.Index' (duration: 179.587µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:58:46.928998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.581387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T16:58:46.929061Z","caller":"traceutil/trace.go:171","msg":"trace[14346133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"170.719633ms","start":"2026-02-25T16:58:46.758325Z","end":"2026-02-25T16:58:46.929045Z","steps":["trace[14346133] 'agreement among raft nodes before linearized reading' (duration: 170.472193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:46.929087Z","caller":"traceutil/trace.go:171","msg":"trace[1269458479] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"328.356703ms","start":"2026-02-25T16:58:46.600706Z","end":"2026-02-25T16:58:46.929062Z","steps":["trace[1269458479] 'process raft request' (duration: 327.821771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:46.929217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:46.600685Z","time spent":"328.458417ms","remote":"127.0.0.1:49298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:58:47.669804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.042949ms","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-25T16:58:47.669919Z","caller":"traceutil/trace.go:171","msg":"trace[713145040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"132.187294ms","start":"2026-02-25T16:58:47.537705Z","end":"2026-02-25T16:58:47.669893Z","steps":["trace[713145040] 'range keys from in-memory index tree' (duration: 131.880542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:47.670076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.027203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T16:58:47.670128Z","caller":"traceutil/trace.go:171","msg":"trace[1125381645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"190.111307ms","start":"2026-02-25T16:58:47.480002Z","end":"2026-02-25T16:58:47.670114Z","steps":["trace[1125381645] 'range keys from in-memory index tree' (duration: 189.959451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:47.670281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.345781ms","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-25T16:58:47.670188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.701305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T16:58:47.670372Z","caller":"traceutil/trace.go:171","msg":"trace[49616560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"133.465916ms","start":"2026-02-25T16:58:47.536882Z","end":"2026-02-25T16:58:47.670348Z","steps":["trace[49616560] 'range keys from in-memory index tree' (duration: 133.179174ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:47.670405Z","caller":"traceutil/trace.go:171","msg":"trace[1225703414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"135.016958ms","start":"2026-02-25T16:58:47.535353Z","end":"2026-02-25T16:58:47.67037Z","steps":["trace[1225703414] 'range keys from in-memory index tree' (duration: 134.491447ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:47.869696Z","caller":"traceutil/trace.go:171","msg":"trace[1261311015] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"195.876999ms","start":"2026-02-25T16:58:47.673796Z","end":"2026-02-25T16:58:47.869673Z","steps":["trace[1261311015] 'process raft request' (duration: 195.737673ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:48.190395Z","caller":"traceutil/trace.go:171","msg":"trace[819734234] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"516.25402ms","start":"2026-02-25T16:58:47.674118Z","end":"2026-02-25T16:58:48.190372Z","steps":["trace[819734234] 'process raft request' (duration: 430.384769ms)","trace[819734234] 'compare' (duration: 85.714445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:58:48.19054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:47.674108Z","time spent":"516.355234ms","remote":"127.0.0.1:49376","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-25T16:58:48.190685Z","caller":"traceutil/trace.go:171","msg":"trace[782692565] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"516.072303ms","start":"2026-02-25T16:58:47.674596Z","end":"2026-02-25T16:58:48.190669Z","steps":["trace[782692565] 'process raft request' (duration: 515.734169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:48.190979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:47.674583Z","time spent":"516.155716ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:58:49.504493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854730196220832176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T16:58:50.005269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854730196220832176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T16:58:50.121797Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.595228559s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T16:58:50.710343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229922814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T16:58:50.710431Z","caller":"traceutil/trace.go:171","msg":"trace[660855940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"2.230025038s","start":"2026-02-25T16:58:48.480388Z","end":"2026-02-25T16:58:50.710413Z","steps":["trace[660855940] 'range keys from in-memory index tree' (duration: 2.229848352s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:50.710474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:48.480335Z","time spent":"2.230130642s","remote":"127.0.0.1:49128","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-25T16:58:50.710829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:49.996165Z","time spent":"714.662016ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T16:58:50.839416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.358017ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T16:58:50.839551Z","caller":"traceutil/trace.go:171","msg":"trace[738829779] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"1.835899029s","start":"2026-02-25T16:58:49.003638Z","end":"2026-02-25T16:58:50.839537Z","steps":["trace[738829779] 'read index received' (duration: 1.118320064s)","trace[738829779] 'applied index is now lower than readState.Index' (duration: 717.577455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:58:50.83973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.97566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T16:58:50.839785Z","caller":"traceutil/trace.go:171","msg":"trace[1378127337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"359.078004ms","start":"2026-02-25T16:58:50.48069Z","end":"2026-02-25T16:58:50.839768Z","steps":["trace[1378127337] 'agreement among raft nodes before linearized reading' (duration: 358.96906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:50.839856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:50.480668Z","time spent":"359.173078ms","remote":"127.0.0.1:49124","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-25T16:58:50.839869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.542549ms","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-25T16:58:50.839907Z","caller":"traceutil/trace.go:171","msg":"trace[1240276000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"644.622792ms","start":"2026-02-25T16:58:50.195274Z","end":"2026-02-25T16:58:50.839897Z","steps":["trace[1240276000] 'agreement among raft nodes before linearized reading' (duration: 644.418425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:50.839936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:50.195252Z","time spent":"644.678515ms","remote":"127.0.0.1:49376","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-25T16:58:50.839873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836216401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T16:58:50.840036Z","caller":"traceutil/trace.go:171","msg":"trace[201468318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"1.83642695s","start":"2026-02-25T16:58:49.003587Z","end":"2026-02-25T16:58:50.840014Z","steps":["trace[201468318] 'agreement among raft nodes before linearized reading' (duration: 1.835998373s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:50.840052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.248673ms","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-25T16:58:50.84003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.539859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T16:58:50.840086Z","caller":"traceutil/trace.go:171","msg":"trace[1223174126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"966.334306ms","start":"2026-02-25T16:58:49.873742Z","end":"2026-02-25T16:58:50.840077Z","steps":["trace[1223174126] 'agreement among raft nodes before linearized reading' (duration: 965.90187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:50.840107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:49.873718Z","time spent":"966.383918ms","remote":"127.0.0.1:49376","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-25T16:58:50.84009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:49.003566Z","time spent":"1.836511103s","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T16:58:50.84011Z","caller":"traceutil/trace.go:171","msg":"trace[1846786575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"644.659804ms","start":"2026-02-25T16:58:50.195429Z","end":"2026-02-25T16:58:50.840089Z","steps":["trace[1846786575] 'agreement among raft nodes before linearized reading' (duration: 644.233897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:50.840162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:50.195416Z","time spent":"644.735177ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T16:58:53.007746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.500837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T16:58:53.007767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.422421ms","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-25T16:58:53.007814Z","caller":"traceutil/trace.go:171","msg":"trace[1446871417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"146.566059ms","start":"2026-02-25T16:58:52.861232Z","end":"2026-02-25T16:58:53.007798Z","steps":["trace[1446871417] 'range keys from in-memory index tree' (duration: 146.366451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:53.007798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.414342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T16:58:53.007885Z","caller":"traceutil/trace.go:171","msg":"trace[551495020] range","detail":"{range_begin:/registry/masterleases/199.19.213.212; range_end:; response_count:1; response_revision:588; }","duration":"195.548957ms","start":"2026-02-25T16:58:52.812314Z","end":"2026-02-25T16:58:53.007863Z","steps":["trace[551495020] 'range keys from in-memory index tree' (duration: 195.223485ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:53.007826Z","caller":"traceutil/trace.go:171","msg":"trace[224516529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"147.513465ms","start":"2026-02-25T16:58:52.860296Z","end":"2026-02-25T16:58:53.00781Z","steps":["trace[224516529] 'range keys from in-memory index tree' (duration: 147.291757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:53.007963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.812038ms","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-25T16:58:53.007733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.626071ms","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-25T16:58:53.008058Z","caller":"traceutil/trace.go:171","msg":"trace[414022081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"146.954924ms","start":"2026-02-25T16:58:52.861077Z","end":"2026-02-25T16:58:53.008032Z","steps":["trace[414022081] 'range keys from in-memory index tree' (duration: 146.655113ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:53.008106Z","caller":"traceutil/trace.go:171","msg":"trace[1223201697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"147.040237ms","start":"2026-02-25T16:58:52.861058Z","end":"2026-02-25T16:58:53.008098Z","steps":["trace[1223201697] 'range keys from in-memory index tree' (duration: 146.474696ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:53.342477Z","caller":"traceutil/trace.go:171","msg":"trace[1066800101] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"327.985524ms","start":"2026-02-25T16:58:53.014473Z","end":"2026-02-25T16:58:53.342459Z","steps":["trace[1066800101] 'process raft request' (duration: 318.949263ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:53.342588Z","caller":"traceutil/trace.go:171","msg":"trace[701054174] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"327.35654ms","start":"2026-02-25T16:58:53.015195Z","end":"2026-02-25T16:58:53.342552Z","steps":["trace[701054174] 'process raft request' (duration: 327.308639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:53.342613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:53.014437Z","time spent":"328.090439ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:58:53.342639Z","caller":"traceutil/trace.go:171","msg":"trace[1945726847] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"328.089578ms","start":"2026-02-25T16:58:53.014535Z","end":"2026-02-25T16:58:53.342625Z","steps":["trace[1945726847] 'process raft request' (duration: 327.86325ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:53.342669Z","caller":"traceutil/trace.go:171","msg":"trace[1373900686] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"328.033576ms","start":"2026-02-25T16:58:53.014619Z","end":"2026-02-25T16:58:53.342653Z","steps":["trace[1373900686] 'process raft request' (duration: 327.828269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:58:53.342721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:53.014488Z","time spent":"328.200663ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:58:53.342775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:53.014596Z","time spent":"328.110579ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:58:53.342773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:58:53.015188Z","time spent":"327.477285ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:58:53.346096Z","caller":"traceutil/trace.go:171","msg":"trace[429917273] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"212.911302ms","start":"2026-02-25T16:58:53.133161Z","end":"2026-02-25T16:58:53.346072Z","steps":["trace[429917273] 'process raft request' (duration: 212.85653ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:53.346478Z","caller":"traceutil/trace.go:171","msg":"trace[1949365522] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"295.333762ms","start":"2026-02-25T16:58:53.051121Z","end":"2026-02-25T16:58:53.346455Z","steps":["trace[1949365522] 'process raft request' (duration: 294.777821ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:58:55.991881Z","caller":"traceutil/trace.go:171","msg":"trace[781704164] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"133.300483ms","start":"2026-02-25T16:58:55.858423Z","end":"2026-02-25T16:58:55.991724Z","steps":["trace[781704164] 'process raft request' (duration: 131.302599ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:00:20.067555Z","caller":"traceutil/trace.go:171","msg":"trace[1766040870] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"264.007396ms","start":"2026-02-25T17:00:19.803523Z","end":"2026-02-25T17:00:20.06753Z","steps":["trace[1766040870] 'process raft request' (duration: 263.848903ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:00:20.069939Z","caller":"traceutil/trace.go:171","msg":"trace[1862636847] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1025; }","duration":"250.013285ms","start":"2026-02-25T17:00:19.819903Z","end":"2026-02-25T17:00:20.069916Z","steps":["trace[1862636847] 'read index received' (duration: 247.550909ms)","trace[1862636847] 'applied index is now lower than readState.Index' (duration: 2.461366ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:00:20.069981Z","caller":"traceutil/trace.go:171","msg":"trace[508467250] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"266.259538ms","start":"2026-02-25T17:00:19.8037Z","end":"2026-02-25T17:00:20.06996Z","steps":["trace[508467250] 'process raft request' (duration: 266.079354ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:00:20.070092Z","caller":"traceutil/trace.go:171","msg":"trace[1977271757] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"266.256948ms","start":"2026-02-25T17:00:19.803817Z","end":"2026-02-25T17:00:20.070074Z","steps":["trace[1977271757] 'process raft request' (duration: 266.059754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:00:20.070159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.236669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T17:00:20.070207Z","caller":"traceutil/trace.go:171","msg":"trace[1175303587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"250.340721ms","start":"2026-02-25T17:00:19.819854Z","end":"2026-02-25T17:00:20.070195Z","steps":["trace[1175303587] 'agreement among raft nodes before linearized reading' (duration: 250.152568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:00:28.369096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.183208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T17:00:28.369222Z","caller":"traceutil/trace.go:171","msg":"trace[702369926] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"185.988565ms","start":"2026-02-25T17:00:28.183214Z","end":"2026-02-25T17:00:28.369203Z","steps":["trace[702369926] 'read index received' (duration: 45.391µs)","trace[702369926] 'applied index is now lower than readState.Index' (duration: 185.941694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:00:28.369357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.548924ms","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-25T17:00:28.369427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.880002ms","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-02-25T17:00:28.369467Z","caller":"traceutil/trace.go:171","msg":"trace[1044305317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"257.946763ms","start":"2026-02-25T17:00:28.111511Z","end":"2026-02-25T17:00:28.369457Z","steps":["trace[1044305317] 'agreement among raft nodes before linearized reading' (duration: 257.78658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:00:28.369535Z","caller":"traceutil/trace.go:171","msg":"trace[1890217318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"258.718777ms","start":"2026-02-25T17:00:28.11074Z","end":"2026-02-25T17:00:28.369459Z","steps":["trace[1890217318] 'agreement among raft nodes before linearized reading' (duration: 72.517939ms)","trace[1890217318] 'range keys from in-memory index tree' (duration: 185.878343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:00:28.875871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854730196220833293,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:00:28.973563Z","caller":"traceutil/trace.go:171","msg":"trace[1954017529] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"599.856347ms","start":"2026-02-25T17:00:28.373675Z","end":"2026-02-25T17:00:28.973531Z","steps":["trace[1954017529] 'process raft request' (duration: 599.611103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:00:28.973786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:28.373652Z","time spent":"600.01721ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:00:28.996455Z","caller":"traceutil/trace.go:171","msg":"trace[1328545666] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"621.076664ms","start":"2026-02-25T17:00:28.375355Z","end":"2026-02-25T17:00:28.996432Z","steps":["trace[1328545666] 'read index received' (duration: 597.921503ms)","trace[1328545666] 'applied index is now lower than readState.Index' (duration: 23.154041ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:00:28.996506Z","caller":"traceutil/trace.go:171","msg":"trace[643469280] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"622.591931ms","start":"2026-02-25T17:00:28.373884Z","end":"2026-02-25T17:00:28.996476Z","steps":["trace[643469280] 'process raft request' (duration: 622.403788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:00:28.996652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.277108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:00:28.996679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:28.373867Z","time spent":"622.707393ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:00:28.996695Z","caller":"traceutil/trace.go:171","msg":"trace[1481415830] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1013; }","duration":"621.38307ms","start":"2026-02-25T17:00:28.375301Z","end":"2026-02-25T17:00:28.996684Z","steps":["trace[1481415830] 'agreement among raft nodes before linearized reading' (duration: 621.255328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:00:28.996693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.868455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:00:28.996792Z","caller":"traceutil/trace.go:171","msg":"trace[320972528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"515.979247ms","start":"2026-02-25T17:00:28.480795Z","end":"2026-02-25T17:00:28.996774Z","steps":["trace[320972528] 'agreement among raft nodes before linearized reading' (duration: 515.836115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:00:28.996736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:28.375286Z","time spent":"621.442341ms","remote":"127.0.0.1:49256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-25T17:00:28.99685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:28.480731Z","time spent":"516.10852ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}