{"level":"warn","ts":"2026-03-02T18:25:16.248823Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T18:25:16.248936Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-02T18:25:16.249021Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T18:25:16.249038Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-03-02T18:25:16.249067Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T18:25:16.249945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-03-02T18:25:16.250053Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-02T18:25:16.254161Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.841017ms"} {"level":"info","ts":"2026-03-02T18:25:16.260204Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-03-02T18:25:16.260274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T18:25:16.260302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-03-02T18:25:16.260316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T18:25:16.260344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-03-02T18:25:16.260381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-03-02T18:25:16.267304Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T18:25:16.269931Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T18:25:16.270985Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-02T18:25:16.273524Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T18:25:16.277901Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T18:25:16.27812Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T18:25:16.278126Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-03-02T18:25:16.278268Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-03-02T18:25:16.278165Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T18:25:16.27881Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T18:25:16.27891Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T18:25:16.278942Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T18:25:16.27895Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T18:25:16.280296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-03-02T18:25:16.280503Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-03-02T18:25:16.361513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T18:25:16.361574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T18:25:16.361775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-03-02T18:25:16.361901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-03-02T18:25:16.361923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-03-02T18:25:16.36194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-03-02T18:25:16.36196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-03-02T18:25:16.363769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T18:25:16.363957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T18:25:16.363984Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T18:25:16.364486Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:25:16.367333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T18:25:16.367386Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T18:25:16.368282Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T18:25:16.368547Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:25:16.36873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-03-02T18:25:16.368846Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:25:16.368914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-02T18:25:40.720957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051064446117896,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:25:40.889194Z","caller":"traceutil/trace.go:171","msg":"trace[2083494908] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"668.894115ms","start":"2026-03-02T18:25:40.220277Z","end":"2026-03-02T18:25:40.889172Z","steps":["trace[2083494908] 'read index received' (duration: 668.58096ms)","trace[2083494908] 'applied index is now lower than readState.Index' (duration: 312.055µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:25:40.889371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.054193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-02T18:25:40.889433Z","caller":"traceutil/trace.go:171","msg":"trace[507782843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"669.178439ms","start":"2026-03-02T18:25:40.220244Z","end":"2026-03-02T18:25:40.889422Z","steps":["trace[507782843] 'agreement among raft nodes before linearized reading' (duration: 669.033772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:40.889475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:40.220202Z","time spent":"669.263194ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T18:25:40.889626Z","caller":"traceutil/trace.go:171","msg":"trace[32411196] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"712.901373ms","start":"2026-03-02T18:25:40.176688Z","end":"2026-03-02T18:25:40.88959Z","steps":["trace[32411196] 'process raft request' (duration: 712.281411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:40.890035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:40.176672Z","time spent":"713.032239ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:25:47.148655Z","caller":"traceutil/trace.go:171","msg":"trace[173036198] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"232.041008ms","start":"2026-03-02T18:25:46.916589Z","end":"2026-03-02T18:25:47.14863Z","steps":["trace[173036198] 'read index received' (duration: 231.914902ms)","trace[173036198] 'applied index is now lower than readState.Index' (duration: 125.206µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:25:47.148769Z","caller":"traceutil/trace.go:171","msg":"trace[1511608145] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"945.425813ms","start":"2026-03-02T18:25:46.203331Z","end":"2026-03-02T18:25:47.148757Z","steps":["trace[1511608145] 'process raft request' (duration: 945.173461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:47.148862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:46.20332Z","time spent":"945.473776ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:25:47.148861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.913005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-03-02T18:25:47.148912Z","caller":"traceutil/trace.go:171","msg":"trace[2140867797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"136.00799ms","start":"2026-03-02T18:25:47.01289Z","end":"2026-03-02T18:25:47.148898Z","steps":["trace[2140867797] 'agreement among raft nodes before linearized reading' (duration: 135.927606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:47.149048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.459708ms","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-03-02T18:25:47.149095Z","caller":"traceutil/trace.go:171","msg":"trace[527595168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"232.544251ms","start":"2026-03-02T18:25:46.916538Z","end":"2026-03-02T18:25:47.149082Z","steps":["trace[527595168] 'agreement among raft nodes before linearized reading' (duration: 232.466227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:47.317033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.952169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:25:47.317203Z","caller":"traceutil/trace.go:171","msg":"trace[1456091974] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"153.518313ms","start":"2026-03-02T18:25:47.16367Z","end":"2026-03-02T18:25:47.317188Z","steps":["trace[1456091974] 'read index received' (duration: 46.228568ms)","trace[1456091974] 'applied index is now lower than readState.Index' (duration: 107.288835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:25:47.317274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.600766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-02T18:25:47.317302Z","caller":"traceutil/trace.go:171","msg":"trace[1045696168] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:516; }","duration":"153.655369ms","start":"2026-03-02T18:25:47.163638Z","end":"2026-03-02T18:25:47.317293Z","steps":["trace[1045696168] 'agreement among raft nodes before linearized reading' (duration: 153.596047ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:25:47.317283Z","caller":"traceutil/trace.go:171","msg":"trace[2044953640] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"164.101975ms","start":"2026-03-02T18:25:47.153132Z","end":"2026-03-02T18:25:47.317234Z","steps":["trace[2044953640] 'process raft request' (duration: 56.755957ms)","trace[2044953640] 'compare' (duration: 106.860715ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:25:47.317427Z","caller":"traceutil/trace.go:171","msg":"trace[1100332750] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"164.111805ms","start":"2026-03-02T18:25:47.153287Z","end":"2026-03-02T18:25:47.317399Z","steps":["trace[1100332750] 'process raft request' (duration: 163.853583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:47.95915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.596148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:25:47.959249Z","caller":"traceutil/trace.go:171","msg":"trace[2066944765] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"364.81755ms","start":"2026-03-02T18:25:47.594416Z","end":"2026-03-02T18:25:47.959233Z","steps":["trace[2066944765] 'read index received' (duration: 172.050234ms)","trace[2066944765] 'applied index is now lower than readState.Index' (duration: 192.766276ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:25:47.959391Z","caller":"traceutil/trace.go:171","msg":"trace[546205965] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"633.709253ms","start":"2026-03-02T18:25:47.325652Z","end":"2026-03-02T18:25:47.959362Z","steps":["trace[546205965] 'process raft request' (duration: 440.828441ms)","trace[546205965] 'compare' (duration: 192.500964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:25:47.959497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.077792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T18:25:47.959564Z","caller":"traceutil/trace.go:171","msg":"trace[1222430377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"365.182866ms","start":"2026-03-02T18:25:47.594364Z","end":"2026-03-02T18:25:47.959547Z","steps":["trace[1222430377] 'agreement among raft nodes before linearized reading' (duration: 365.067451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:47.959605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:47.594344Z","time spent":"365.25212ms","remote":"127.0.0.1:44006","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-03-02T18:25:47.959687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:47.325627Z","time spent":"633.833899ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:25:51.089554Z","caller":"traceutil/trace.go:171","msg":"trace[1596217589] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"187.223534ms","start":"2026-03-02T18:25:50.902304Z","end":"2026-03-02T18:25:51.089527Z","steps":["trace[1596217589] 'process raft request' (duration: 187.076887ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:25:54.449138Z","caller":"traceutil/trace.go:171","msg":"trace[277408367] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"302.508165ms","start":"2026-03-02T18:25:54.146608Z","end":"2026-03-02T18:25:54.449116Z","steps":["trace[277408367] 'read index received' (duration: 302.39332ms)","trace[277408367] 'applied index is now lower than readState.Index' (duration: 114.265µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:25:54.44922Z","caller":"traceutil/trace.go:171","msg":"trace[728189683] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"460.727018ms","start":"2026-03-02T18:25:53.988474Z","end":"2026-03-02T18:25:54.449201Z","steps":["trace[728189683] 'process raft request' (duration: 460.526239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:54.449344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.705924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dr948\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-02T18:25:54.449371Z","caller":"traceutil/trace.go:171","msg":"trace[1315943056] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dr948; range_end:; response_count:1; response_revision:558; }","duration":"302.810398ms","start":"2026-03-02T18:25:54.146554Z","end":"2026-03-02T18:25:54.449364Z","steps":["trace[1315943056] 'agreement among raft nodes before linearized reading' (duration: 302.667171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:54.449362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:53.98846Z","time spent":"460.815481ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:25:54.44942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:54.146527Z","time spent":"302.86379ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-dr948\" "} {"level":"info","ts":"2026-03-02T18:25:54.734356Z","caller":"traceutil/trace.go:171","msg":"trace[2138965028] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"268.804437ms","start":"2026-03-02T18:25:54.465528Z","end":"2026-03-02T18:25:54.734332Z","steps":["trace[2138965028] 'process raft request' (duration: 268.64287ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:25:55.306433Z","caller":"traceutil/trace.go:171","msg":"trace[1691252017] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"377.032062ms","start":"2026-03-02T18:25:54.929368Z","end":"2026-03-02T18:25:55.3064Z","steps":["trace[1691252017] 'read index received' (duration: 284.805984ms)","trace[1691252017] 'applied index is now lower than readState.Index' (duration: 92.224108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:25:55.306667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.272813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:25:55.306737Z","caller":"traceutil/trace.go:171","msg":"trace[279147036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"377.389818ms","start":"2026-03-02T18:25:54.929331Z","end":"2026-03-02T18:25:55.30672Z","steps":["trace[279147036] 'agreement among raft nodes before linearized reading' (duration: 377.240051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:55.306773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:54.929313Z","time spent":"377.45372ms","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:25:55.306901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.998961ms","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-03-02T18:25:55.307024Z","caller":"traceutil/trace.go:171","msg":"trace[1854292121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"121.182608ms","start":"2026-03-02T18:25:55.185817Z","end":"2026-03-02T18:25:55.307Z","steps":["trace[1854292121] 'agreement among raft nodes before linearized reading' (duration: 120.807842ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:25:55.508345Z","caller":"traceutil/trace.go:171","msg":"trace[1944563405] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"105.378497ms","start":"2026-03-02T18:25:55.402914Z","end":"2026-03-02T18:25:55.508293Z","steps":["trace[1944563405] 'read index received' (duration: 56.459389ms)","trace[1944563405] 'applied index is now lower than readState.Index' (duration: 48.917988ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:25:55.508475Z","caller":"traceutil/trace.go:171","msg":"trace[1253443403] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"163.11495ms","start":"2026-03-02T18:25:55.345329Z","end":"2026-03-02T18:25:55.508444Z","steps":["trace[1253443403] 'process raft request' (duration: 114.15604ms)","trace[1253443403] 'compare' (duration: 48.673648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:25:55.508551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.624588ms","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-03-02T18:25:55.508592Z","caller":"traceutil/trace.go:171","msg":"trace[343865266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"105.715841ms","start":"2026-03-02T18:25:55.402864Z","end":"2026-03-02T18:25:55.50858Z","steps":["trace[343865266] 'agreement among raft nodes before linearized reading' (duration: 105.550564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:57.718821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.60593ms","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-03-02T18:25:57.718947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789551622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:25:57.71898Z","caller":"traceutil/trace.go:171","msg":"trace[2073580713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"1.789615814s","start":"2026-03-02T18:25:55.929353Z","end":"2026-03-02T18:25:57.718969Z","steps":["trace[2073580713] 'range keys from in-memory index tree' (duration: 1.789453667s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:57.719013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:55.929341Z","time spent":"1.789666497s","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:25:57.719024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.808418ms","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-03-02T18:25:57.719095Z","caller":"traceutil/trace.go:171","msg":"trace[1287180353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"206.925103ms","start":"2026-03-02T18:25:57.512153Z","end":"2026-03-02T18:25:57.719079Z","steps":["trace[1287180353] 'range keys from in-memory index tree' (duration: 206.658451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:57.719096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.696051ms","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-03-02T18:25:57.719157Z","caller":"traceutil/trace.go:171","msg":"trace[729515724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"377.792675ms","start":"2026-03-02T18:25:57.341344Z","end":"2026-03-02T18:25:57.719136Z","steps":["trace[729515724] 'range keys from in-memory index tree' (duration: 377.504533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:57.71921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:57.341299Z","time spent":"377.89902ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T18:25:57.719382Z","caller":"traceutil/trace.go:171","msg":"trace[1228180150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"143.729846ms","start":"2026-03-02T18:25:57.575149Z","end":"2026-03-02T18:25:57.718879Z","steps":["trace[1228180150] 'range keys from in-memory index tree' (duration: 143.478734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:57.719371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266493797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T18:25:57.719496Z","caller":"traceutil/trace.go:171","msg":"trace[969308746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"1.266662154s","start":"2026-03-02T18:25:56.452813Z","end":"2026-03-02T18:25:57.719475Z","steps":["trace[969308746] 'range keys from in-memory index tree' (duration: 1.26632065s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:57.719545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:56.452793Z","time spent":"1.266742988s","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T18:25:58.277649Z","caller":"traceutil/trace.go:171","msg":"trace[1859981043] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"102.735744ms","start":"2026-03-02T18:25:58.174885Z","end":"2026-03-02T18:25:58.277621Z","steps":["trace[1859981043] 'process raft request' (duration: 102.560097ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:25:59.821364Z","caller":"traceutil/trace.go:171","msg":"trace[1589214650] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:613; }","duration":"330.983054ms","start":"2026-03-02T18:25:59.490353Z","end":"2026-03-02T18:25:59.821336Z","steps":["trace[1589214650] 'read index received' (duration: 330.972694ms)","trace[1589214650] 'applied index is now lower than readState.Index' (duration: 8.33µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:25:59.821265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:59.406674Z","time spent":"414.586116ms","remote":"127.0.0.1:43530","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T18:25:59.821339Z","caller":"traceutil/trace.go:171","msg":"trace[1597808666] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"422.942765ms","start":"2026-03-02T18:25:59.398374Z","end":"2026-03-02T18:25:59.821317Z","steps":["trace[1597808666] 'process raft request' (duration: 422.641403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:59.821644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:59.398355Z","time spent":"423.237398ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:25:59.82167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.293478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T18:25:59.821801Z","caller":"traceutil/trace.go:171","msg":"trace[757301395] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:594; }","duration":"331.468244ms","start":"2026-03-02T18:25:59.490317Z","end":"2026-03-02T18:25:59.821785Z","steps":["trace[757301395] 'agreement among raft nodes before linearized reading' (duration: 331.090979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:59.82188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:25:59.490298Z","time spent":"331.568399ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-03-02T18:25:59.82544Z","caller":"traceutil/trace.go:171","msg":"trace[996614608] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"230.285718ms","start":"2026-03-02T18:25:59.595125Z","end":"2026-03-02T18:25:59.825411Z","steps":["trace[996614608] 'process raft request' (duration: 229.997256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:25:59.825482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.585241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-z6m5h\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-03-02T18:25:59.82555Z","caller":"traceutil/trace.go:171","msg":"trace[236883654] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-z6m5h; range_end:; response_count:1; response_revision:595; }","duration":"202.701676ms","start":"2026-03-02T18:25:59.622832Z","end":"2026-03-02T18:25:59.825534Z","steps":["trace[236883654] 'agreement among raft nodes before linearized reading' (duration: 202.461996ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:21.721338Z","caller":"traceutil/trace.go:171","msg":"trace[1529853770] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"102.84198ms","start":"2026-03-02T18:27:21.618471Z","end":"2026-03-02T18:27:21.721313Z","steps":["trace[1529853770] 'process raft request' (duration: 102.697457ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:30.175622Z","caller":"traceutil/trace.go:171","msg":"trace[1558014273] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"244.884057ms","start":"2026-03-02T18:27:29.930706Z","end":"2026-03-02T18:27:30.17559Z","steps":["trace[1558014273] 'read index received' (duration: 244.729334ms)","trace[1558014273] 'applied index is now lower than readState.Index' (duration: 152.913µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:30.175821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.106322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:27:30.175909Z","caller":"traceutil/trace.go:171","msg":"trace[944871740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"245.244555ms","start":"2026-03-02T18:27:29.930644Z","end":"2026-03-02T18:27:30.175889Z","steps":["trace[944871740] 'agreement among raft nodes before linearized reading' (duration: 245.107192ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:30.175725Z","caller":"traceutil/trace.go:171","msg":"trace[840055038] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"411.928245ms","start":"2026-03-02T18:27:29.76375Z","end":"2026-03-02T18:27:30.175678Z","steps":["trace[840055038] 'process raft request' (duration: 411.646759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:30.176157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:29.763719Z","time spent":"412.295282ms","remote":"127.0.0.1:44006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}