{"level":"warn","ts":"2026-02-24T10:40:54.069389Z","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-24T10:40:54.06947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.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://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.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-24T10:40:54.069569Z","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-24T10:40:54.069583Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-24T10:40:54.0696Z","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-24T10:40:54.070185Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-24T10:40:54.070259Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.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-24T10:40:54.074072Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.597492ms"} {"level":"info","ts":"2026-02-24T10:40:54.080048Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-24T10:40:54.080113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:40:54.080141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-24T10:40:54.080154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:40:54.080162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-24T10:40:54.080244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-24T10:40:54.086208Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:40:54.087643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:40:54.090804Z","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-24T10:40:54.093693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:40:54.094189Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:40:54.094278Z","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-24T10:40:54.094419Z","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-24T10:40:54.094434Z","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-24T10:40:54.094784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-24T10:40:54.094893Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-24T10:40:54.104289Z","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-24T10:40:54.104467Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-24T10:40:54.104479Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-24T10:40:54.104558Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:40:54.104574Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:40:54.181168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:40:54.181208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:40:54.18133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-24T10:40:54.181357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:40:54.181374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-24T10:40:54.181389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-24T10:40:54.181402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-24T10:40:54.182318Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:40:54.183021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:40:54.183015Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:40:54.183052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:40:54.183243Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:40:54.18326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:40:54.183467Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:40:54.183563Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:40:54.183592Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:40:54.186407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-24T10:40:54.187364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T10:40:58.762766Z","caller":"traceutil/trace.go:171","msg":"trace[1401833382] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"219.557433ms","start":"2026-02-24T10:40:58.543183Z","end":"2026-02-24T10:40:58.76274Z","steps":["trace[1401833382] 'process raft request' (duration: 219.397972ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:04.515429Z","caller":"traceutil/trace.go:171","msg":"trace[1161620162] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"515.432515ms","start":"2026-02-24T10:41:03.999974Z","end":"2026-02-24T10:41:04.515406Z","steps":["trace[1161620162] 'process raft request' (duration: 515.281643ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:04.515783Z","caller":"traceutil/trace.go:171","msg":"trace[1685111601] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"486.896269ms","start":"2026-02-24T10:41:04.02886Z","end":"2026-02-24T10:41:04.515756Z","steps":["trace[1685111601] 'read index received' (duration: 486.286189ms)","trace[1685111601] 'applied index is now lower than readState.Index' (duration: 608.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:04.516091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.867723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T10:41:04.516201Z","caller":"traceutil/trace.go:171","msg":"trace[834182104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:318; }","duration":"235.043664ms","start":"2026-02-24T10:41:04.281133Z","end":"2026-02-24T10:41:04.516176Z","steps":["trace[834182104] 'agreement among raft nodes before linearized reading' (duration: 234.833804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.516364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.505379ms","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-24T10:41:04.516429Z","caller":"traceutil/trace.go:171","msg":"trace[391246923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:318; }","duration":"487.598794ms","start":"2026-02-24T10:41:04.028814Z","end":"2026-02-24T10:41:04.516412Z","steps":["trace[391246923] 'agreement among raft nodes before linearized reading' (duration: 487.496959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.516469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.028798Z","time spent":"487.662151ms","remote":"127.0.0.1:42616","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-24T10:41:04.516671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.254617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-24T10:41:04.516734Z","caller":"traceutil/trace.go:171","msg":"trace[440663858] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:318; }","duration":"127.370021ms","start":"2026-02-24T10:41:04.389348Z","end":"2026-02-24T10:41:04.516718Z","steps":["trace[440663858] 'agreement among raft nodes before linearized reading' (duration: 127.239037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.516917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.363982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-24T10:41:04.516234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.406917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-24T10:41:04.517Z","caller":"traceutil/trace.go:171","msg":"trace[1979930464] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:318; }","duration":"328.474476ms","start":"2026-02-24T10:41:04.188494Z","end":"2026-02-24T10:41:04.516969Z","steps":["trace[1979930464] 'agreement among raft nodes before linearized reading' (duration: 328.318504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.517037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.188475Z","time spent":"328.553562ms","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-02-24T10:41:04.517028Z","caller":"traceutil/trace.go:171","msg":"trace[463619582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:318; }","duration":"244.214757ms","start":"2026-02-24T10:41:04.27277Z","end":"2026-02-24T10:41:04.516985Z","steps":["trace[463619582] 'agreement among raft nodes before linearized reading' (duration: 243.392758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.517136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.816234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-24T10:41:04.517169Z","caller":"traceutil/trace.go:171","msg":"trace[1413549133] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:318; }","duration":"228.874821ms","start":"2026-02-24T10:41:04.288283Z","end":"2026-02-24T10:41:04.517158Z","steps":["trace[1413549133] 'agreement among raft nodes before linearized reading' (duration: 228.779036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.51609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.810323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-24T10:41:04.517257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.072935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-24T10:41:04.517304Z","caller":"traceutil/trace.go:171","msg":"trace[386337342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:318; }","duration":"479.104388ms","start":"2026-02-24T10:41:04.038186Z","end":"2026-02-24T10:41:04.51729Z","steps":["trace[386337342] 'agreement among raft nodes before linearized reading' (duration: 477.776815ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:04.517305Z","caller":"traceutil/trace.go:171","msg":"trace[613717078] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:318; }","duration":"379.1515ms","start":"2026-02-24T10:41:04.138141Z","end":"2026-02-24T10:41:04.517293Z","steps":["trace[613717078] 'agreement among raft nodes before linearized reading' (duration: 379.076084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.517344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.138123Z","time spent":"379.212937ms","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-24T10:41:04.517346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.038166Z","time spent":"479.168305ms","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-24T10:41:04.517472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.547998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-24T10:41:04.516922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.537995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-24T10:41:04.517548Z","caller":"traceutil/trace.go:171","msg":"trace[1247895978] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:318; }","duration":"429.627554ms","start":"2026-02-24T10:41:04.087903Z","end":"2026-02-24T10:41:04.51753Z","steps":["trace[1247895978] 'agreement among raft nodes before linearized reading' (duration: 429.50573ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:04.517591Z","caller":"traceutil/trace.go:171","msg":"trace[2139667368] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:318; }","duration":"278.203992ms","start":"2026-02-24T10:41:04.239337Z","end":"2026-02-24T10:41:04.517541Z","steps":["trace[2139667368] 'agreement among raft nodes before linearized reading' (duration: 277.552714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.517601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.087852Z","time spent":"429.737738ms","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-24T10:41:04.517754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.655014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-24T10:41:04.51799Z","caller":"traceutil/trace.go:171","msg":"trace[735694115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:318; }","duration":"179.842025ms","start":"2026-02-24T10:41:04.33806Z","end":"2026-02-24T10:41:04.517902Z","steps":["trace[735694115] 'agreement among raft nodes before linearized reading' (duration: 178.896932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:04.51831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:03.999948Z","time spent":"515.630326ms","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:04.518357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.628281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"info","ts":"2026-02-24T10:41:04.518516Z","caller":"traceutil/trace.go:171","msg":"trace[429797928] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:318; }","duration":"275.584643ms","start":"2026-02-24T10:41:04.24282Z","end":"2026-02-24T10:41:04.518405Z","steps":["trace[429797928] 'agreement among raft nodes before linearized reading' (duration: 273.447509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:05.038975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548558957003507,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:41:05.705857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.124451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:41:05.705963Z","caller":"traceutil/trace.go:171","msg":"trace[40741489] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"1.181942745s","start":"2026-02-24T10:41:04.524001Z","end":"2026-02-24T10:41:05.705943Z","steps":["trace[40741489] 'process raft request' (duration: 653.527498ms)","trace[40741489] 'compare' (duration: 527.95436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:05.706023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.523987Z","time spent":"1.182009461s","remote":"127.0.0.1:42418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:41:06.806064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.508257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:41:06.806486Z","caller":"traceutil/trace.go:171","msg":"trace[1307364410] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"2.281009753s","start":"2026-02-24T10:41:04.525455Z","end":"2026-02-24T10:41:06.806465Z","steps":["trace[1307364410] 'process raft request' (duration: 2.280798143s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.806559Z","caller":"traceutil/trace.go:171","msg":"trace[427450054] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"2.279511708s","start":"2026-02-24T10:41:04.526998Z","end":"2026-02-24T10:41:06.80651Z","steps":["trace[427450054] 'process raft request' (duration: 2.279319777s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.80666Z","caller":"traceutil/trace.go:171","msg":"trace[1238035759] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"2.279558676s","start":"2026-02-24T10:41:04.52709Z","end":"2026-02-24T10:41:06.806648Z","steps":["trace[1238035759] 'process raft request' (duration: 2.279297209s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.806677Z","caller":"traceutil/trace.go:171","msg":"trace[1140814289] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"1.051662741s","start":"2026-02-24T10:41:05.755003Z","end":"2026-02-24T10:41:06.806666Z","steps":["trace[1140814289] 'process raft request' (duration: 1.051621283s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.806711Z","caller":"traceutil/trace.go:171","msg":"trace[1901302393] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"2.27967506s","start":"2026-02-24T10:41:04.527028Z","end":"2026-02-24T10:41:06.806704Z","steps":["trace[1901302393] 'process raft request' (duration: 2.279323937s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.806742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.527086Z","time spent":"2.279607384s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:41:06.806755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.52702Z","time spent":"2.279715577s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:06.806756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:05.754977Z","time spent":"1.051742396s","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:06.806836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.52699Z","time spent":"2.279716378s","remote":"127.0.0.1:42622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:41:06.806873Z","caller":"traceutil/trace.go:171","msg":"trace[836198834] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"2.279327718s","start":"2026-02-24T10:41:04.527527Z","end":"2026-02-24T10:41:06.806855Z","steps":["trace[836198834] 'process raft request' (duration: 2.279023583s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.806876Z","caller":"traceutil/trace.go:171","msg":"trace[122934860] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"2.281651971s","start":"2026-02-24T10:41:04.525189Z","end":"2026-02-24T10:41:06.80684Z","steps":["trace[122934860] 'process raft request' (duration: 1.334153363s)","trace[122934860] 'compare' (duration: 946.336605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:06.806609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.52542Z","time spent":"2.281122358s","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:06.806959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.527498Z","time spent":"2.279419163s","remote":"127.0.0.1:42616","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-24T10:41:06.806955Z","caller":"traceutil/trace.go:171","msg":"trace[1386727558] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"2.276974845s","start":"2026-02-24T10:41:04.52996Z","end":"2026-02-24T10:41:06.806935Z","steps":["trace[1386727558] 'process raft request' (duration: 2.276645631s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.806971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.525177Z","time spent":"2.281746037s","remote":"127.0.0.1:42616","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-24T10:41:06.806987Z","caller":"traceutil/trace.go:171","msg":"trace[2025148866] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"2.2780769s","start":"2026-02-24T10:41:04.528903Z","end":"2026-02-24T10:41:06.80698Z","steps":["trace[2025148866] 'process raft request' (duration: 2.277682969s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.807014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.268909207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-24T10:41:06.807025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.528896Z","time spent":"2.278108568s","remote":"127.0.0.1:42448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:41:06.806975Z","caller":"traceutil/trace.go:171","msg":"trace[1156871044] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:327; }","duration":"2.26885145s","start":"2026-02-24T10:41:04.538102Z","end":"2026-02-24T10:41:06.806953Z","steps":["trace[1156871044] 'read index received' (duration: 639.432731ms)","trace[1156871044] 'applied index is now lower than readState.Index' (duration: 1.629417999s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:41:06.807048Z","caller":"traceutil/trace.go:171","msg":"trace[830788606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:330; }","duration":"2.268952085s","start":"2026-02-24T10:41:04.538077Z","end":"2026-02-24T10:41:06.807029Z","steps":["trace[830788606] 'agreement among raft nodes before linearized reading' (duration: 2.268913117s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.807037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.529951Z","time spent":"2.277050151s","remote":"127.0.0.1:42448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:41:06.807077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.538067Z","time spent":"2.269004732s","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-24T10:41:06.807136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.21788723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-24T10:41:06.807142Z","caller":"traceutil/trace.go:171","msg":"trace[1231327527] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"2.280003994s","start":"2026-02-24T10:41:04.527127Z","end":"2026-02-24T10:41:06.807131Z","steps":["trace[1231327527] 'process raft request' (duration: 2.279286699s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.807187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33215232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:41:06.80721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39485692s","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-24T10:41:06.807214Z","caller":"traceutil/trace.go:171","msg":"trace[730147218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:330; }","duration":"1.332204826s","start":"2026-02-24T10:41:05.475002Z","end":"2026-02-24T10:41:06.807207Z","steps":["trace[730147218] 'agreement among raft nodes before linearized reading' (duration: 1.33213381s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.807225Z","caller":"traceutil/trace.go:171","msg":"trace[1898364809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:330; }","duration":"1.394901719s","start":"2026-02-24T10:41:05.412319Z","end":"2026-02-24T10:41:06.80722Z","steps":["trace[1898364809] 'agreement among raft nodes before linearized reading' (duration: 1.39486893s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.807216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.527123Z","time spent":"2.280070881s","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:41:06.807229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167834906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-24T10:41:06.807234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:05.47499Z","time spent":"1.332240455s","remote":"127.0.0.1:42356","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-24T10:41:06.80724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:05.412294Z","time spent":"1.394942417s","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:41:06.807177Z","caller":"traceutil/trace.go:171","msg":"trace[1781197250] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:330; }","duration":"2.217956917s","start":"2026-02-24T10:41:04.589197Z","end":"2026-02-24T10:41:06.807154Z","steps":["trace[1781197250] 'agreement among raft nodes before linearized reading' (duration: 2.21789754s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:06.807281Z","caller":"traceutil/trace.go:171","msg":"trace[60394209] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"2.279853221s","start":"2026-02-24T10:41:04.527415Z","end":"2026-02-24T10:41:06.807268Z","steps":["trace[60394209] 'process raft request' (duration: 2.279109748s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.807311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.589174Z","time spent":"2.218129429s","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-24T10:41:06.807324Z","caller":"traceutil/trace.go:171","msg":"trace[1646215717] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:330; }","duration":"2.167882234s","start":"2026-02-24T10:41:04.639361Z","end":"2026-02-24T10:41:06.807244Z","steps":["trace[1646215717] 'agreement among raft nodes before linearized reading' (duration: 2.167833806s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:06.807346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.527404Z","time spent":"2.279905589s","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:06.807377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:04.639351Z","time spent":"2.167992618s","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-24T10:41:17.763772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.146875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:41:17.763883Z","caller":"traceutil/trace.go:171","msg":"trace[1144787839] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"377.661777ms","start":"2026-02-24T10:41:17.386204Z","end":"2026-02-24T10:41:17.763866Z","steps":["trace[1144787839] 'process raft request' (duration: 47.349619ms)","trace[1144787839] 'compare' (duration: 330.013821ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:17.763954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:17.386183Z","time spent":"377.731304ms","remote":"127.0.0.1:42378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:41:33.362795Z","caller":"traceutil/trace.go:171","msg":"trace[408072575] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"317.683437ms","start":"2026-02-24T10:41:33.045091Z","end":"2026-02-24T10:41:33.362775Z","steps":["trace[408072575] 'process raft request' (duration: 317.568802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:33.36292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:33.045072Z","time spent":"317.788913ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:33.788506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.90211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:41:33.788666Z","caller":"traceutil/trace.go:171","msg":"trace[875622783] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"486.375291ms","start":"2026-02-24T10:41:33.302273Z","end":"2026-02-24T10:41:33.788648Z","steps":["trace[875622783] 'read index received' (duration: 60.494834ms)","trace[875622783] 'applied index is now lower than readState.Index' (duration: 425.879357ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:41:33.788742Z","caller":"traceutil/trace.go:171","msg":"trace[1020467323] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"743.504336ms","start":"2026-02-24T10:41:33.045204Z","end":"2026-02-24T10:41:33.788709Z","steps":["trace[1020467323] 'process raft request' (duration: 583.331498ms)","trace[1020467323] 'compare' (duration: 159.792154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:33.788767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.494916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kckzg\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-24T10:41:33.788782Z","caller":"traceutil/trace.go:171","msg":"trace[574112674] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"743.491138ms","start":"2026-02-24T10:41:33.04526Z","end":"2026-02-24T10:41:33.788751Z","steps":["trace[574112674] 'process raft request' (duration: 743.330104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:33.788937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:33.045186Z","time spent":"743.616942ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:33.78897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:33.045242Z","time spent":"743.622542ms","remote":"127.0.0.1:42616","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-24T10:41:33.788823Z","caller":"traceutil/trace.go:171","msg":"trace[1881001221] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kckzg; range_end:; response_count:1; response_revision:528; }","duration":"486.570933ms","start":"2026-02-24T10:41:33.302226Z","end":"2026-02-24T10:41:33.788797Z","steps":["trace[1881001221] 'agreement among raft nodes before linearized reading' (duration: 486.473907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:33.789198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:33.302208Z","time spent":"486.897149ms","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-kckzg\" "} {"level":"info","ts":"2026-02-24T10:41:34.389972Z","caller":"traceutil/trace.go:171","msg":"trace[771013313] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"588.306067ms","start":"2026-02-24T10:41:33.801628Z","end":"2026-02-24T10:41:34.389934Z","steps":["trace[771013313] 'process raft request' (duration: 551.503311ms)","trace[771013313] 'compare' (duration: 36.653902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:34.390152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:33.801603Z","time spent":"588.45819ms","remote":"127.0.0.1:42536","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-24T10:41:35.867028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548558957003902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:41:36.367682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548558957003902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:41:36.667098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.616420221s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:41:36.667396Z","caller":"traceutil/trace.go:171","msg":"trace[432979322] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"1.301298143s","start":"2026-02-24T10:41:35.366083Z","end":"2026-02-24T10:41:36.667381Z","steps":["trace[432979322] 'read index received' (duration: 1.301142269s)","trace[432979322] 'applied index is now lower than readState.Index' (duration: 155.334µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:36.667497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274180242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:41:36.66752Z","caller":"traceutil/trace.go:171","msg":"trace[635855266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"1.274282187s","start":"2026-02-24T10:41:35.393232Z","end":"2026-02-24T10:41:36.667514Z","steps":["trace[635855266] 'agreement among raft nodes before linearized reading' (duration: 1.27421245s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:36.66754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:35.393198Z","time spent":"1.274338096s","remote":"127.0.0.1:42356","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-24T10:41:36.667602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.034625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T10:41:36.667619Z","caller":"traceutil/trace.go:171","msg":"trace[696705181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"873.099093ms","start":"2026-02-24T10:41:35.794513Z","end":"2026-02-24T10:41:36.667613Z","steps":["trace[696705181] 'agreement among raft nodes before linearized reading' (duration: 873.061434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:36.667571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301467497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T10:41:36.667658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.17774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T10:41:36.667672Z","caller":"traceutil/trace.go:171","msg":"trace[138327644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"873.233207ms","start":"2026-02-24T10:41:35.794435Z","end":"2026-02-24T10:41:36.667669Z","steps":["trace[138327644] 'agreement among raft nodes before linearized reading' (duration: 873.209908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:36.667684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:35.794408Z","time spent":"873.273156ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:41:36.667677Z","caller":"traceutil/trace.go:171","msg":"trace[1372043686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"1.301587342s","start":"2026-02-24T10:41:35.366065Z","end":"2026-02-24T10:41:36.667653Z","steps":["trace[1372043686] 'agreement among raft nodes before linearized reading' (duration: 1.30138789s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:36.667652Z","caller":"traceutil/trace.go:171","msg":"trace[1180429306] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.617034857s","start":"2026-02-24T10:41:35.050563Z","end":"2026-02-24T10:41:36.667598Z","steps":["trace[1180429306] 'process raft request' (duration: 1.61671358s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:36.667632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:35.79448Z","time spent":"873.149471ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T10:41:36.667726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:35.365962Z","time spent":"1.301755924s","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:41:36.667887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:35.050529Z","time spent":"1.617243158s","remote":"127.0.0.1:42616","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-24T10:41:37.015617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.896038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:41:37.015834Z","caller":"traceutil/trace.go:171","msg":"trace[1831054193] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:546; }","duration":"337.739828ms","start":"2026-02-24T10:41:36.678078Z","end":"2026-02-24T10:41:37.015818Z","steps":["trace[1831054193] 'read index received' (duration: 168.599729ms)","trace[1831054193] 'applied index is now lower than readState.Index' (duration: 169.139009ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:41:37.01586Z","caller":"traceutil/trace.go:171","msg":"trace[1743127226] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"343.469221ms","start":"2026-02-24T10:41:36.672375Z","end":"2026-02-24T10:41:37.015844Z","steps":["trace[1743127226] 'process raft request' (duration: 343.336536ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:37.01587Z","caller":"traceutil/trace.go:171","msg":"trace[1890545314] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"342.586176ms","start":"2026-02-24T10:41:36.67327Z","end":"2026-02-24T10:41:37.015856Z","steps":["trace[1890545314] 'process raft request' (duration: 342.4896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:37.015925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:36.672345Z","time spent":"343.547888ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:41:37.015987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:36.673245Z","time spent":"342.677162ms","remote":"127.0.0.1:42616","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-24T10:41:37.015971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.869943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:41:37.015879Z","caller":"traceutil/trace.go:171","msg":"trace[728148022] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"344.348836ms","start":"2026-02-24T10:41:36.671474Z","end":"2026-02-24T10:41:37.015823Z","steps":["trace[728148022] 'process raft request' (duration: 175.168299ms)","trace[728148022] 'compare' (duration: 168.772773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:37.016113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:36.671446Z","time spent":"344.598187ms","remote":"127.0.0.1:42616","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-24T10:41:37.016116Z","caller":"traceutil/trace.go:171","msg":"trace[237526150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"337.978608ms","start":"2026-02-24T10:41:36.678053Z","end":"2026-02-24T10:41:37.016032Z","steps":["trace[237526150] 'agreement among raft nodes before linearized reading' (duration: 337.808005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:37.016194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:36.678041Z","time spent":"338.125643ms","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:41:39.277436Z","caller":"traceutil/trace.go:171","msg":"trace[502215950] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"251.794999ms","start":"2026-02-24T10:41:39.025608Z","end":"2026-02-24T10:41:39.277403Z","steps":["trace[502215950] 'read index received' (duration: 234.710253ms)","trace[502215950] 'applied index is now lower than readState.Index' (duration: 17.083166ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:41:39.277496Z","caller":"traceutil/trace.go:171","msg":"trace[1307543790] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"572.742653ms","start":"2026-02-24T10:41:38.704704Z","end":"2026-02-24T10:41:39.277446Z","steps":["trace[1307543790] 'process raft request' (duration: 555.900648ms)","trace[1307543790] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 16.622234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:39.277791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:38.704692Z","time spent":"573.003672ms","remote":"127.0.0.1:42616","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-24T10:41:39.348819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.803736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T10:41:39.348912Z","caller":"traceutil/trace.go:171","msg":"trace[2127967981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"322.899762ms","start":"2026-02-24T10:41:39.025992Z","end":"2026-02-24T10:41:39.348892Z","steps":["trace[2127967981] 'agreement among raft nodes before linearized reading' (duration: 251.696542ms)","trace[2127967981] 'range keys from in-memory index tree' (duration: 71.051965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:39.348957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:39.025946Z","time spent":"322.998398ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T10:41:39.349118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.493748ms","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-24T10:41:39.349188Z","caller":"traceutil/trace.go:171","msg":"trace[918851863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"323.622883ms","start":"2026-02-24T10:41:39.025548Z","end":"2026-02-24T10:41:39.34917Z","steps":["trace[918851863] 'agreement among raft nodes before linearized reading' (duration: 252.048588ms)","trace[918851863] 'range keys from in-memory index tree' (duration: 71.414051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:39.349224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:39.025524Z","time spent":"323.69455ms","remote":"127.0.0.1:42616","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-24T10:41:39.349253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.20719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T10:41:39.349361Z","caller":"traceutil/trace.go:171","msg":"trace[1026627270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"323.372813ms","start":"2026-02-24T10:41:39.025962Z","end":"2026-02-24T10:41:39.349335Z","steps":["trace[1026627270] 'agreement among raft nodes before linearized reading' (duration: 251.728311ms)","trace[1026627270] 'range keys from in-memory index tree' (duration: 71.42659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:41:39.349409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:41:39.025946Z","time spent":"323.45499ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:41:40.448781Z","caller":"traceutil/trace.go:171","msg":"trace[758543784] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"107.142955ms","start":"2026-02-24T10:41:40.341615Z","end":"2026-02-24T10:41:40.448758Z","steps":["trace[758543784] 'process raft request' (duration: 106.946432ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:41:40.497187Z","caller":"traceutil/trace.go:171","msg":"trace[443845567] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"112.996604ms","start":"2026-02-24T10:41:40.384164Z","end":"2026-02-24T10:41:40.497161Z","steps":["trace[443845567] 'process raft request' (duration: 106.882255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:41:40.497323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.852795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:41:40.497436Z","caller":"traceutil/trace.go:171","msg":"trace[418379618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"104.015408ms","start":"2026-02-24T10:41:40.393395Z","end":"2026-02-24T10:41:40.497411Z","steps":["trace[418379618] 'agreement among raft nodes before linearized reading' (duration: 103.803826ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:39.138287Z","caller":"traceutil/trace.go:171","msg":"trace[1861906095] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"164.935557ms","start":"2026-02-24T10:42:38.973322Z","end":"2026-02-24T10:42:39.138258Z","steps":["trace[1861906095] 'process raft request' (duration: 164.762343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:08.508794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.618765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:43:08.508951Z","caller":"traceutil/trace.go:171","msg":"trace[1380945222] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1015; }","duration":"172.874011ms","start":"2026-02-24T10:43:08.336064Z","end":"2026-02-24T10:43:08.508938Z","steps":["trace[1380945222] 'read index received' (duration: 22.769µs)","trace[1380945222] 'applied index is now lower than readState.Index' (duration: 172.850522ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:08.508974Z","caller":"traceutil/trace.go:171","msg":"trace[526822222] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"643.654874ms","start":"2026-02-24T10:43:07.8653Z","end":"2026-02-24T10:43:08.508955Z","steps":["trace[526822222] 'process raft request' (duration: 643.612986ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:08.508979Z","caller":"traceutil/trace.go:171","msg":"trace[655575673] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"644.075388ms","start":"2026-02-24T10:43:07.864876Z","end":"2026-02-24T10:43:08.508952Z","steps":["trace[655575673] 'process raft request' (duration: 643.993091ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:08.508979Z","caller":"traceutil/trace.go:171","msg":"trace[1424173098] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"644.104747ms","start":"2026-02-24T10:43:07.864831Z","end":"2026-02-24T10:43:08.508936Z","steps":["trace[1424173098] 'process raft request' (duration: 124.140914ms)","trace[1424173098] 'compare' (duration: 519.436313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:43:08.509052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.985417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T10:43:08.509073Z","caller":"traceutil/trace.go:171","msg":"trace[873350275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"173.033625ms","start":"2026-02-24T10:43:08.336032Z","end":"2026-02-24T10:43:08.509066Z","steps":["trace[873350275] 'agreement among raft nodes before linearized reading' (duration: 172.946528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:08.509073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:07.865271Z","time spent":"643.75626ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:43:08.509131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:07.864861Z","time spent":"644.186154ms","remote":"127.0.0.1:42616","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-24T10:43:08.509166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:07.864816Z","time spent":"644.255452ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:43:10.768464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.030773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:43:10.768669Z","caller":"traceutil/trace.go:171","msg":"trace[561578091] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"195.391136ms","start":"2026-02-24T10:43:10.573256Z","end":"2026-02-24T10:43:10.768647Z","steps":["trace[561578091] 'read index received' (duration: 78.148351ms)","trace[561578091] 'applied index is now lower than readState.Index' (duration: 117.241645ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:10.7688Z","caller":"traceutil/trace.go:171","msg":"trace[1969774723] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"251.98702ms","start":"2026-02-24T10:43:10.516784Z","end":"2026-02-24T10:43:10.768771Z","steps":["trace[1969774723] 'process raft request' (duration: 134.546761ms)","trace[1969774723] 'compare' (duration: 116.901889ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:10.768826Z","caller":"traceutil/trace.go:171","msg":"trace[1077302049] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"251.138761ms","start":"2026-02-24T10:43:10.517672Z","end":"2026-02-24T10:43:10.768811Z","steps":["trace[1077302049] 'process raft request' (duration: 250.89657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:10.768986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.731944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T10:43:10.769033Z","caller":"traceutil/trace.go:171","msg":"trace[295284760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"195.823641ms","start":"2026-02-24T10:43:10.573194Z","end":"2026-02-24T10:43:10.769017Z","steps":["trace[295284760] 'agreement among raft nodes before linearized reading' (duration: 195.675906ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:17.516038Z","caller":"traceutil/trace.go:171","msg":"trace[1000874810] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"161.51347ms","start":"2026-02-24T10:43:17.354506Z","end":"2026-02-24T10:43:17.516019Z","steps":["trace[1000874810] 'process raft request' (duration: 134.294751ms)","trace[1000874810] 'compare' (duration: 27.018506ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:17.516016Z","caller":"traceutil/trace.go:171","msg":"trace[1319534453] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"148.06165ms","start":"2026-02-24T10:43:17.367929Z","end":"2026-02-24T10:43:17.515991Z","steps":["trace[1319534453] 'read index received' (duration: 120.824862ms)","trace[1319534453] 'applied index is now lower than readState.Index' (duration: 27.235938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:43:17.516192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.202565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-02-24T10:43:17.516608Z","caller":"traceutil/trace.go:171","msg":"trace[1382934301] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1007; }","duration":"148.693597ms","start":"2026-02-24T10:43:17.367893Z","end":"2026-02-24T10:43:17.516586Z","steps":["trace[1382934301] 'agreement among raft nodes before linearized reading' (duration: 148.180896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:17.516313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.053139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:728"} {"level":"info","ts":"2026-02-24T10:43:17.516726Z","caller":"traceutil/trace.go:171","msg":"trace[695632611] range","detail":"{range_begin:/registry/clusterroles/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1007; }","duration":"137.496862ms","start":"2026-02-24T10:43:17.379214Z","end":"2026-02-24T10:43:17.51671Z","steps":["trace[695632611] 'agreement among raft nodes before linearized reading' (duration: 137.01472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:18.166964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.882889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"warn","ts":"2026-02-24T10:43:18.167026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.792507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-24T10:43:18.167056Z","caller":"traceutil/trace.go:171","msg":"trace[1938007317] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1008; }","duration":"152.014124ms","start":"2026-02-24T10:43:18.015019Z","end":"2026-02-24T10:43:18.167033Z","steps":["trace[1938007317] 'range keys from in-memory index tree' (duration: 151.793503ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:18.167097Z","caller":"traceutil/trace.go:171","msg":"trace[1214338567] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1008; }","duration":"149.906823ms","start":"2026-02-24T10:43:18.017172Z","end":"2026-02-24T10:43:18.167078Z","steps":["trace[1214338567] 'range keys from in-memory index tree' (duration: 149.70587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:19.116974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.803881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:695"} {"level":"warn","ts":"2026-02-24T10:43:19.117024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.827101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T10:43:19.117058Z","caller":"traceutil/trace.go:171","msg":"trace[760288676] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1010; }","duration":"154.921497ms","start":"2026-02-24T10:43:18.962118Z","end":"2026-02-24T10:43:19.11704Z","steps":["trace[760288676] 'range keys from in-memory index tree' (duration: 154.717464ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:19.11708Z","caller":"traceutil/trace.go:171","msg":"trace[680538050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"196.910747ms","start":"2026-02-24T10:43:18.920155Z","end":"2026-02-24T10:43:19.117066Z","steps":["trace[680538050] 'range keys from in-memory index tree' (duration: 196.721234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:19.117322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.650743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-24T10:43:19.117465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.052924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-24T10:43:19.117506Z","caller":"traceutil/trace.go:171","msg":"trace[28337202] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1010; }","duration":"159.13229ms","start":"2026-02-24T10:43:18.958361Z","end":"2026-02-24T10:43:19.117494Z","steps":["trace[28337202] 'range keys from in-memory index tree' (duration: 158.965047ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:19.117347Z","caller":"traceutil/trace.go:171","msg":"trace[1639246625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"198.704671ms","start":"2026-02-24T10:43:18.918635Z","end":"2026-02-24T10:43:19.11734Z","steps":["trace[1639246625] 'range keys from in-memory index tree' (duration: 198.571476ms)"],"step_count":1}