{"level":"warn","ts":"2026-02-25T19:24:58.314119Z","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-25T19:24:58.314236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.51:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.51:2380","--initial-cluster=instance=https://199.19.213.51:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.51:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.51: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-25T19:24:58.31445Z","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-25T19:24:58.314475Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.51:2380"]} {"level":"info","ts":"2026-02-25T19:24:58.314559Z","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-25T19:24:58.315605Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"]} {"level":"info","ts":"2026-02-25T19:24:58.315775Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.51:2380"],"listen-peer-urls":["https://199.19.213.51:2380"],"advertise-client-urls":["https://199.19.213.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.51: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-25T19:24:58.611494Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"295.40189ms"} {"level":"info","ts":"2026-02-25T19:24:58.64385Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d8a2e6f117593bd6","cluster-id":"28b0db2e76181428"} {"level":"info","ts":"2026-02-25T19:24:58.643954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:24:58.644118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:24:58.644147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d8a2e6f117593bd6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:24:58.644168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:24:58.644306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=(15610293181572791254)"} {"level":"warn","ts":"2026-02-25T19:24:58.653129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:24:58.656833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:24:58.660759Z","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-25T19:24:58.674218Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d8a2e6f117593bd6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:24:58.674526Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d8a2e6f117593bd6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:24:58.674725Z","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-25T19:24:58.674878Z","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-25T19:24:58.674905Z","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-25T19:24:58.676128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=(15610293181572791254)"} {"level":"info","ts":"2026-02-25T19:24:58.6763Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"28b0db2e76181428","local-member-id":"d8a2e6f117593bd6","added-peer-id":"d8a2e6f117593bd6","added-peer-peer-urls":["https://199.19.213.51:2380"]} {"level":"info","ts":"2026-02-25T19:24:58.679034Z","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-25T19:24:58.679276Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.51:2380"} {"level":"info","ts":"2026-02-25T19:24:58.679357Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.51:2380"} {"level":"info","ts":"2026-02-25T19:24:58.680488Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d8a2e6f117593bd6","initial-advertise-peer-urls":["https://199.19.213.51:2380"],"listen-peer-urls":["https://199.19.213.51:2380"],"advertise-client-urls":["https://199.19.213.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:24:58.680541Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:24:58.945314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:24:58.945383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:24:58.945404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 received MsgPreVoteResp from d8a2e6f117593bd6 at term 1"} {"level":"info","ts":"2026-02-25T19:24:58.945415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:24:58.94542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 received MsgVoteResp from d8a2e6f117593bd6 at term 2"} {"level":"info","ts":"2026-02-25T19:24:58.945429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:24:58.945436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d8a2e6f117593bd6 elected leader d8a2e6f117593bd6 at term 2"} {"level":"info","ts":"2026-02-25T19:24:58.946899Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d8a2e6f117593bd6","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.51:2379]}","request-path":"/0/members/d8a2e6f117593bd6/attributes","cluster-id":"28b0db2e76181428","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:24:58.94693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:24:58.947165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:24:58.947149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:58.947543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:24:58.94758Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:24:58.948473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:24:58.951704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.51:2379"} {"level":"info","ts":"2026-02-25T19:24:58.961033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"28b0db2e76181428","local-member-id":"d8a2e6f117593bd6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:58.961155Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:24:58.961203Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T19:25:17.052491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.646408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:17.052631Z","caller":"traceutil/trace.go:171","msg":"trace[1578134043] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"367.121262ms","start":"2026-02-25T19:25:16.685461Z","end":"2026-02-25T19:25:17.052582Z","steps":["trace[1578134043] 'read index received' (duration: 202.011352ms)","trace[1578134043] 'applied index is now lower than readState.Index' (duration: 165.10889ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:17.052688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.224792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T19:25:17.052664Z","caller":"traceutil/trace.go:171","msg":"trace[279062654] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"369.128433ms","start":"2026-02-25T19:25:16.68348Z","end":"2026-02-25T19:25:17.052609Z","steps":["trace[279062654] 'process raft request' (duration: 203.942903ms)","trace[279062654] 'compare' (duration: 164.542607ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:17.052709Z","caller":"traceutil/trace.go:171","msg":"trace[2107238690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"367.273903ms","start":"2026-02-25T19:25:16.685428Z","end":"2026-02-25T19:25:17.052702Z","steps":["trace[2107238690] 'agreement among raft nodes before linearized reading' (duration: 367.229442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:17.052742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:16.685417Z","time spent":"367.319313ms","remote":"127.0.0.1:56818","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-25T19:25:17.052824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:16.683464Z","time spent":"369.271654ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:18.516676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.779466ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:25:18.51683Z","caller":"traceutil/trace.go:171","msg":"trace[164116227] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"1.240173631s","start":"2026-02-25T19:25:17.276629Z","end":"2026-02-25T19:25:18.516803Z","steps":["trace[164116227] 'read index received' (duration: 285.101104ms)","trace[164116227] 'applied index is now lower than readState.Index' (duration: 955.070247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:18.516984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240330252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:18.517027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.767033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:25:18.517057Z","caller":"traceutil/trace.go:171","msg":"trace[612884640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"1.240455772s","start":"2026-02-25T19:25:17.276583Z","end":"2026-02-25T19:25:18.517039Z","steps":["trace[612884640] 'agreement among raft nodes before linearized reading' (duration: 1.240298311s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:18.517064Z","caller":"traceutil/trace.go:171","msg":"trace[1084640904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"869.867013ms","start":"2026-02-25T19:25:17.647181Z","end":"2026-02-25T19:25:18.517048Z","steps":["trace[1084640904] 'agreement among raft nodes before linearized reading' (duration: 869.752303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:18.517096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.276565Z","time spent":"1.240525452s","remote":"127.0.0.1:56542","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-02-25T19:25:18.517108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:17.647147Z","time spent":"869.952554ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:25:18.650663Z","caller":"traceutil/trace.go:171","msg":"trace[1794894312] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"129.493602ms","start":"2026-02-25T19:25:18.521141Z","end":"2026-02-25T19:25:18.650635Z","steps":["trace[1794894312] 'process raft request' (duration: 129.304791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:18.650991Z","caller":"traceutil/trace.go:171","msg":"trace[734703691] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:428; }","duration":"124.147636ms","start":"2026-02-25T19:25:18.526813Z","end":"2026-02-25T19:25:18.650961Z","steps":["trace[734703691] 'read index received' (duration: 124.135556ms)","trace[734703691] 'applied index is now lower than readState.Index' (duration: 11.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:18.65109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.260676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:18.651115Z","caller":"traceutil/trace.go:171","msg":"trace[1800564651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"124.327156ms","start":"2026-02-25T19:25:18.526778Z","end":"2026-02-25T19:25:18.651105Z","steps":["trace[1800564651] 'agreement among raft nodes before linearized reading' (duration: 124.261576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:23.650825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311805862436771785,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:25:23.675857Z","caller":"traceutil/trace.go:171","msg":"trace[1544332362] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"525.57193ms","start":"2026-02-25T19:25:23.15026Z","end":"2026-02-25T19:25:23.675831Z","steps":["trace[1544332362] 'read index received' (duration: 525.472099ms)","trace[1544332362] 'applied index is now lower than readState.Index' (duration: 98.531µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:23.675989Z","caller":"traceutil/trace.go:171","msg":"trace[853958611] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"591.391951ms","start":"2026-02-25T19:25:23.084551Z","end":"2026-02-25T19:25:23.675943Z","steps":["trace[853958611] 'process raft request' (duration: 591.102638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:23.676051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.613733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:23.676128Z","caller":"traceutil/trace.go:171","msg":"trace[977434487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"396.740993ms","start":"2026-02-25T19:25:23.279375Z","end":"2026-02-25T19:25:23.676116Z","steps":["trace[977434487] 'agreement among raft nodes before linearized reading' (duration: 396.592403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:23.676175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:23.084532Z","time spent":"591.538771ms","remote":"127.0.0.1:56818","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-02-25T19:25:23.676225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:23.279359Z","time spent":"396.846074ms","remote":"127.0.0.1:56542","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-02-25T19:25:23.676175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.828891ms","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-25T19:25:23.676518Z","caller":"traceutil/trace.go:171","msg":"trace[1828685970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"526.279313ms","start":"2026-02-25T19:25:23.150211Z","end":"2026-02-25T19:25:23.67649Z","steps":["trace[1828685970] 'agreement among raft nodes before linearized reading' (duration: 525.752461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:23.676673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:23.150194Z","time spent":"526.460674ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:25:24.395644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.629791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:24.395866Z","caller":"traceutil/trace.go:171","msg":"trace[1881096654] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:441; }","duration":"115.137105ms","start":"2026-02-25T19:25:24.280712Z","end":"2026-02-25T19:25:24.395849Z","steps":["trace[1881096654] 'read index received' (duration: 107.908888ms)","trace[1881096654] 'applied index is now lower than readState.Index' (duration: 7.225527ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:24.395909Z","caller":"traceutil/trace.go:171","msg":"trace[1535180102] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"715.399429ms","start":"2026-02-25T19:25:23.680488Z","end":"2026-02-25T19:25:24.395888Z","steps":["trace[1535180102] 'process raft request' (duration: 498.440017ms)","trace[1535180102] 'compare' (duration: 216.371099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:24.395958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.256276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:24.395992Z","caller":"traceutil/trace.go:171","msg":"trace[1745593130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"115.330296ms","start":"2026-02-25T19:25:24.280652Z","end":"2026-02-25T19:25:24.395983Z","steps":["trace[1745593130] 'agreement among raft nodes before linearized reading' (duration: 115.244585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:24.396011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:23.680476Z","time spent":"715.48561ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:24.396242Z","caller":"traceutil/trace.go:171","msg":"trace[16749056] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"153.208962ms","start":"2026-02-25T19:25:24.242994Z","end":"2026-02-25T19:25:24.396203Z","steps":["trace[16749056] 'process raft request' (duration: 152.76627ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:24.801002Z","caller":"traceutil/trace.go:171","msg":"trace[1210804672] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"104.589631ms","start":"2026-02-25T19:25:24.69637Z","end":"2026-02-25T19:25:24.80096Z","steps":["trace[1210804672] 'process raft request' (duration: 27.043531ms)","trace[1210804672] 'compare' (duration: 77.43214ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:30.54233Z","caller":"traceutil/trace.go:171","msg":"trace[886859522] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"120.026968ms","start":"2026-02-25T19:25:30.422279Z","end":"2026-02-25T19:25:30.542306Z","steps":["trace[886859522] 'process raft request' (duration: 119.893687ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:31.169218Z","caller":"traceutil/trace.go:171","msg":"trace[844873902] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"446.719939ms","start":"2026-02-25T19:25:30.722474Z","end":"2026-02-25T19:25:31.169194Z","steps":["trace[844873902] 'read index received' (duration: 446.486937ms)","trace[844873902] 'applied index is now lower than readState.Index' (duration: 231.982µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:31.169277Z","caller":"traceutil/trace.go:171","msg":"trace[1826367841] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"603.520245ms","start":"2026-02-25T19:25:30.565733Z","end":"2026-02-25T19:25:31.169253Z","steps":["trace[1826367841] 'process raft request' (duration: 603.319903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:31.169404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.91083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:25:31.169443Z","caller":"traceutil/trace.go:171","msg":"trace[1230798034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"447.00549ms","start":"2026-02-25T19:25:30.722425Z","end":"2026-02-25T19:25:31.169431Z","steps":["trace[1230798034] 'agreement among raft nodes before linearized reading' (duration: 446.89396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:31.169447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:30.565714Z","time spent":"603.610936ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:31.169483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:30.722405Z","time spent":"447.069661ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:31.169511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.881467ms","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-25T19:25:31.169587Z","caller":"traceutil/trace.go:171","msg":"trace[1733938375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"328.004927ms","start":"2026-02-25T19:25:30.841563Z","end":"2026-02-25T19:25:31.169568Z","steps":["trace[1733938375] 'agreement among raft nodes before linearized reading' (duration: 327.848666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:31.169641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:30.841542Z","time spent":"328.087538ms","remote":"127.0.0.1:56818","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-25T19:25:32.351625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020955379s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:32.35188Z","caller":"traceutil/trace.go:171","msg":"trace[1328435083] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:492; }","duration":"602.086465ms","start":"2026-02-25T19:25:31.749757Z","end":"2026-02-25T19:25:32.351843Z","steps":["trace[1328435083] 'read index received' (duration: 24.893128ms)","trace[1328435083] 'applied index is now lower than readState.Index' (duration: 577.192527ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:32.352096Z","caller":"traceutil/trace.go:171","msg":"trace[2061089004] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.178619478s","start":"2026-02-25T19:25:31.173464Z","end":"2026-02-25T19:25:32.352083Z","steps":["trace[2061089004] 'process raft request' (duration: 157.131207ms)","trace[2061089004] 'compare' (duration: 1.020842018s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:32.352165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:31.173438Z","time spent":"1.178693019s","remote":"127.0.0.1:56818","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-02-25T19:25:32.352241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.458997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:25:32.352257Z","caller":"traceutil/trace.go:171","msg":"trace[1738475816] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.178730109s","start":"2026-02-25T19:25:31.173489Z","end":"2026-02-25T19:25:32.352219Z","steps":["trace[1738475816] 'process raft request' (duration: 1.178224607s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:32.35228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.363381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:32.352321Z","caller":"traceutil/trace.go:171","msg":"trace[1519207397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"602.582978ms","start":"2026-02-25T19:25:31.749714Z","end":"2026-02-25T19:25:32.352296Z","steps":["trace[1519207397] 'agreement among raft nodes before linearized reading' (duration: 602.366746ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:32.352341Z","caller":"traceutil/trace.go:171","msg":"trace[110933848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"391.459402ms","start":"2026-02-25T19:25:31.960864Z","end":"2026-02-25T19:25:32.352324Z","steps":["trace[110933848] 'agreement among raft nodes before linearized reading' (duration: 391.362271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:32.352374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:31.960843Z","time spent":"391.524043ms","remote":"127.0.0.1:56542","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-02-25T19:25:32.352358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.360408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.51\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:25:32.352414Z","caller":"traceutil/trace.go:171","msg":"trace[786576837] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.13577401s","start":"2026-02-25T19:25:31.216629Z","end":"2026-02-25T19:25:32.352403Z","steps":["trace[786576837] 'process raft request' (duration: 1.135131456s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:32.352414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:31.173466Z","time spent":"1.17885885s","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:32.352434Z","caller":"traceutil/trace.go:171","msg":"trace[818867137] range","detail":"{range_begin:/registry/masterleases/199.19.213.51; range_end:; response_count:1; response_revision:478; }","duration":"252.479329ms","start":"2026-02-25T19:25:32.099934Z","end":"2026-02-25T19:25:32.352413Z","steps":["trace[818867137] 'agreement among raft nodes before linearized reading' (duration: 252.325627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:32.352374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:31.749689Z","time spent":"602.676009ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:32.352504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:31.216602Z","time spent":"1.135842641s","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:40.904897Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053014837s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:25:40.905819Z","caller":"traceutil/trace.go:171","msg":"trace[2118387460] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"454.907206ms","start":"2026-02-25T19:25:40.450854Z","end":"2026-02-25T19:25:40.905761Z","steps":["trace[2118387460] 'read index received' (duration: 454.375303ms)","trace[2118387460] 'applied index is now lower than readState.Index' (duration: 530.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:40.906117Z","caller":"traceutil/trace.go:171","msg":"trace[14113315] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.054312183s","start":"2026-02-25T19:25:39.851765Z","end":"2026-02-25T19:25:40.906077Z","steps":["trace[14113315] 'process raft request' (duration: 1.053794729s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:40.906269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.055826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:25:40.906297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:39.851745Z","time spent":"1.054462163s","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:40.906326Z","caller":"traceutil/trace.go:171","msg":"trace[57430081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"447.159047ms","start":"2026-02-25T19:25:40.459146Z","end":"2026-02-25T19:25:40.906305Z","steps":["trace[57430081] 'agreement among raft nodes before linearized reading' (duration: 446.846705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:40.906324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.443769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:25:40.906374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:40.459134Z","time spent":"447.229517ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:25:40.90639Z","caller":"traceutil/trace.go:171","msg":"trace[581739990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"455.59673ms","start":"2026-02-25T19:25:40.450768Z","end":"2026-02-25T19:25:40.906365Z","steps":["trace[581739990] 'agreement among raft nodes before linearized reading' (duration: 455.214818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:40.906446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:40.450737Z","time spent":"455.69833ms","remote":"127.0.0.1:56818","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-25T19:25:40.906528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.79506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T19:25:40.906555Z","caller":"traceutil/trace.go:171","msg":"trace[1743801003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"267.86866ms","start":"2026-02-25T19:25:40.638678Z","end":"2026-02-25T19:25:40.906547Z","steps":["trace[1743801003] 'agreement among raft nodes before linearized reading' (duration: 267.732349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:40.906722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.51786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:25:40.9068Z","caller":"traceutil/trace.go:171","msg":"trace[845015083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"367.65173ms","start":"2026-02-25T19:25:40.539129Z","end":"2026-02-25T19:25:40.906781Z","steps":["trace[845015083] 'agreement among raft nodes before linearized reading' (duration: 367.476079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:40.906836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:40.539091Z","time spent":"367.73804ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:41.851961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.406295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:41.852724Z","caller":"traceutil/trace.go:171","msg":"trace[922665777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"573.246221ms","start":"2026-02-25T19:25:41.279457Z","end":"2026-02-25T19:25:41.852703Z","steps":["trace[922665777] 'range keys from in-memory index tree' (duration: 572.286585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.852948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:41.279424Z","time spent":"573.506932ms","remote":"127.0.0.1:56542","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-02-25T19:25:43.434354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4311805862436771999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:43.664928Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.56204363s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:25:43.665179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.102706Z","time spent":"1.562469291s","remote":"127.0.0.1:56590","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T19:25:43.795162Z","caller":"traceutil/trace.go:171","msg":"trace[1003964088] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"861.104888ms","start":"2026-02-25T19:25:42.934038Z","end":"2026-02-25T19:25:43.795143Z","steps":["trace[1003964088] 'read index received' (duration: 731.046393ms)","trace[1003964088] 'applied index is now lower than readState.Index' (duration: 130.057525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:43.795448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.38922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:25:43.795574Z","caller":"traceutil/trace.go:171","msg":"trace[1509788805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"861.53269ms","start":"2026-02-25T19:25:42.934019Z","end":"2026-02-25T19:25:43.795552Z","steps":["trace[1509788805] 'agreement among raft nodes before linearized reading' (duration: 861.213208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.795625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.933946Z","time spent":"861.668232ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:25:43.795978Z","caller":"traceutil/trace.go:171","msg":"trace[1604015172] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.292692903s","start":"2026-02-25T19:25:42.503268Z","end":"2026-02-25T19:25:43.795961Z","steps":["trace[1604015172] 'process raft request' (duration: 1.291740868s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.796117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.503238Z","time spent":"1.292824684s","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:43.800356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.910456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:25:43.800446Z","caller":"traceutil/trace.go:171","msg":"trace[47388300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"849.043196ms","start":"2026-02-25T19:25:42.951369Z","end":"2026-02-25T19:25:43.800412Z","steps":["trace[47388300] 'agreement among raft nodes before linearized reading' (duration: 848.806655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.800441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.713729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:43.800499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.951345Z","time spent":"849.140706ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:43.800553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.434514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T19:25:43.800545Z","caller":"traceutil/trace.go:171","msg":"trace[1080252164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"518.878499ms","start":"2026-02-25T19:25:43.281642Z","end":"2026-02-25T19:25:43.800521Z","steps":["trace[1080252164] 'agreement among raft nodes before linearized reading' (duration: 518.652968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:43.800575Z","caller":"traceutil/trace.go:171","msg":"trace[353504209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"848.470814ms","start":"2026-02-25T19:25:42.952098Z","end":"2026-02-25T19:25:43.800569Z","steps":["trace[353504209] 'agreement among raft nodes before linearized reading' (duration: 848.420463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.800598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.95209Z","time spent":"848.505064ms","remote":"127.0.0.1:56818","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-25T19:25:43.8006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:43.281613Z","time spent":"518.97758ms","remote":"127.0.0.1:56542","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-02-25T19:25:43.800487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.638344ms","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-25T19:25:43.800727Z","caller":"traceutil/trace.go:171","msg":"trace[1308046441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"848.901635ms","start":"2026-02-25T19:25:42.95182Z","end":"2026-02-25T19:25:43.800721Z","steps":["trace[1308046441] 'agreement among raft nodes before linearized reading' (duration: 848.609834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:43.800747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:42.951809Z","time spent":"848.931945ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:25:43.800939Z","caller":"traceutil/trace.go:171","msg":"trace[114059749] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"133.865543ms","start":"2026-02-25T19:25:43.666627Z","end":"2026-02-25T19:25:43.800493Z","steps":["trace[114059749] 'process raft request' (duration: 133.418091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.675932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.92397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:45.676118Z","caller":"traceutil/trace.go:171","msg":"trace[344913714] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"321.618631ms","start":"2026-02-25T19:25:45.354485Z","end":"2026-02-25T19:25:45.676104Z","steps":["trace[344913714] 'process raft request' (duration: 321.538451ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:45.676139Z","caller":"traceutil/trace.go:171","msg":"trace[1200044686] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"329.215799ms","start":"2026-02-25T19:25:45.346898Z","end":"2026-02-25T19:25:45.676114Z","steps":["trace[1200044686] 'process raft request' (duration: 80.060838ms)","trace[1200044686] 'compare' (duration: 248.791908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:45.676261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.346866Z","time spent":"329.33562ms","remote":"127.0.0.1:56650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:45.676517Z","caller":"traceutil/trace.go:171","msg":"trace[101534188] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"326.041394ms","start":"2026-02-25T19:25:45.350443Z","end":"2026-02-25T19:25:45.676484Z","steps":["trace[101534188] 'read index received' (duration: 76.513331ms)","trace[101534188] 'applied index is now lower than readState.Index' (duration: 249.521813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:45.676593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.354464Z","time spent":"321.679002ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:45.676673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.218964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.676735Z","caller":"traceutil/trace.go:171","msg":"trace[1353796687] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"326.300415ms","start":"2026-02-25T19:25:45.350426Z","end":"2026-02-25T19:25:45.676726Z","steps":["trace[1353796687] 'agreement among raft nodes before linearized reading' (duration: 326.213564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.676754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.350418Z","time spent":"326.331065ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.676941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.837977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:45.67698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.545231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.676982Z","caller":"traceutil/trace.go:171","msg":"trace[1108780779] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"322.882927ms","start":"2026-02-25T19:25:45.354087Z","end":"2026-02-25T19:25:45.67697Z","steps":["trace[1108780779] 'agreement among raft nodes before linearized reading' (duration: 322.828727ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:45.676999Z","caller":"traceutil/trace.go:171","msg":"trace[714748582] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"325.574661ms","start":"2026-02-25T19:25:45.351417Z","end":"2026-02-25T19:25:45.676992Z","steps":["trace[714748582] 'agreement among raft nodes before linearized reading' (duration: 325.543731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.677008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.354083Z","time spent":"322.918517ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.35141Z","time spent":"325.600681ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.67708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.092598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677094Z","caller":"traceutil/trace.go:171","msg":"trace[517129471] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"325.120208ms","start":"2026-02-25T19:25:45.35197Z","end":"2026-02-25T19:25:45.67709Z","steps":["trace[517129471] 'agreement among raft nodes before linearized reading' (duration: 325.097738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.677109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.351965Z","time spent":"325.139108ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.912557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677172Z","caller":"traceutil/trace.go:171","msg":"trace[136104286] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"126.975379ms","start":"2026-02-25T19:25:45.550191Z","end":"2026-02-25T19:25:45.677166Z","steps":["trace[136104286] 'agreement among raft nodes before linearized reading' (duration: 126.939178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.677183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.605872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677197Z","caller":"traceutil/trace.go:171","msg":"trace[1439133309] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"325.632172ms","start":"2026-02-25T19:25:45.351561Z","end":"2026-02-25T19:25:45.677193Z","steps":["trace[1439133309] 'agreement among raft nodes before linearized reading' (duration: 325.611162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.67721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.351555Z","time spent":"325.652072ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.560897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677263Z","caller":"traceutil/trace.go:171","msg":"trace[568572752] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"326.587207ms","start":"2026-02-25T19:25:45.350672Z","end":"2026-02-25T19:25:45.677259Z","steps":["trace[568572752] 'agreement among raft nodes before linearized reading' (duration: 326.565017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.677275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.350665Z","time spent":"326.606687ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.656387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677325Z","caller":"traceutil/trace.go:171","msg":"trace[879483476] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"326.680017ms","start":"2026-02-25T19:25:45.35064Z","end":"2026-02-25T19:25:45.67732Z","steps":["trace[879483476] 'agreement among raft nodes before linearized reading' (duration: 326.662357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.677336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.350634Z","time spent":"326.698837ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.472506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:45.677393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.973143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677409Z","caller":"traceutil/trace.go:171","msg":"trace[56112956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"321.996353ms","start":"2026-02-25T19:25:45.355408Z","end":"2026-02-25T19:25:45.677404Z","steps":["trace[56112956] 'agreement among raft nodes before linearized reading' (duration: 321.971403ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:45.677409Z","caller":"traceutil/trace.go:171","msg":"trace[307960835] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"326.922429ms","start":"2026-02-25T19:25:45.35047Z","end":"2026-02-25T19:25:45.677392Z","steps":["trace[307960835] 'agreement among raft nodes before linearized reading' (duration: 326.440136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:45.677422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.355402Z","time spent":"322.016503ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:45.350466Z","time spent":"326.979759ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:45.677453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.680599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:45.677521Z","caller":"traceutil/trace.go:171","msg":"trace[1895005778] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"297.7846ms","start":"2026-02-25T19:25:45.379705Z","end":"2026-02-25T19:25:45.67749Z","steps":["trace[1895005778] 'agreement among raft nodes before linearized reading' (duration: 297.70405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:46.835471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.547234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:46.836256Z","caller":"traceutil/trace.go:171","msg":"trace[660415833] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"235.139419ms","start":"2026-02-25T19:25:46.601082Z","end":"2026-02-25T19:25:46.836222Z","steps":["trace[660415833] 'process raft request' (duration: 131.754722ms)","trace[660415833] 'compare' (duration: 102.226592ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:48.358545Z","caller":"traceutil/trace.go:171","msg":"trace[965723410] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"208.360253ms","start":"2026-02-25T19:25:48.150154Z","end":"2026-02-25T19:25:48.358515Z","steps":["trace[965723410] 'read index received' (duration: 207.815819ms)","trace[965723410] 'applied index is now lower than readState.Index' (duration: 543.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:48.358978Z","caller":"traceutil/trace.go:171","msg":"trace[713047966] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"357.366021ms","start":"2026-02-25T19:25:48.001597Z","end":"2026-02-25T19:25:48.358963Z","steps":["trace[713047966] 'process raft request' (duration: 356.555656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:48.359092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:48.001567Z","time spent":"357.443022ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:48.359708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.526418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-25T19:25:48.359812Z","caller":"traceutil/trace.go:171","msg":"trace[837994788] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"209.683569ms","start":"2026-02-25T19:25:48.150101Z","end":"2026-02-25T19:25:48.359785Z","steps":["trace[837994788] 'agreement among raft nodes before linearized reading' (duration: 209.430637ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.078859Z","caller":"traceutil/trace.go:171","msg":"trace[1440406674] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"328.977856ms","start":"2026-02-25T19:25:49.749859Z","end":"2026-02-25T19:25:50.078837Z","steps":["trace[1440406674] 'read index received' (duration: 328.821535ms)","trace[1440406674] 'applied index is now lower than readState.Index' (duration: 155.101µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:50.079137Z","caller":"traceutil/trace.go:171","msg":"trace[449009739] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"383.615434ms","start":"2026-02-25T19:25:49.695509Z","end":"2026-02-25T19:25:50.079124Z","steps":["trace[449009739] 'process raft request' (duration: 383.186521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.079246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.695493Z","time spent":"383.679544ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:50.07953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.6637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T19:25:50.079574Z","caller":"traceutil/trace.go:171","msg":"trace[602607145] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"329.74179ms","start":"2026-02-25T19:25:49.749821Z","end":"2026-02-25T19:25:50.079562Z","steps":["trace[602607145] 'agreement among raft nodes before linearized reading' (duration: 329.574159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.079626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.7498Z","time spent":"329.81835ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:25:50.079819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.516776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:25:50.079871Z","caller":"traceutil/trace.go:171","msg":"trace[278276318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"248.614337ms","start":"2026-02-25T19:25:49.831248Z","end":"2026-02-25T19:25:50.079862Z","steps":["trace[278276318] 'agreement among raft nodes before linearized reading' (duration: 248.508117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.080246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.560859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-25T19:25:50.080286Z","caller":"traceutil/trace.go:171","msg":"trace[1560929544] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"129.6345ms","start":"2026-02-25T19:25:49.950643Z","end":"2026-02-25T19:25:50.080277Z","steps":["trace[1560929544] 'agreement among raft nodes before linearized reading' (duration: 129.507399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.080484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.849165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x5tps\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-02-25T19:25:50.080518Z","caller":"traceutil/trace.go:171","msg":"trace[1321230673] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x5tps; range_end:; response_count:1; response_revision:609; }","duration":"175.916466ms","start":"2026-02-25T19:25:49.904593Z","end":"2026-02-25T19:25:50.08051Z","steps":["trace[1321230673] 'agreement among raft nodes before linearized reading' (duration: 175.827456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.080674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.661001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T19:25:50.080706Z","caller":"traceutil/trace.go:171","msg":"trace[2098901237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"237.710511ms","start":"2026-02-25T19:25:49.842988Z","end":"2026-02-25T19:25:50.080698Z","steps":["trace[2098901237] 'agreement among raft nodes before linearized reading' (duration: 237.630881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.080857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.36043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T19:25:50.08089Z","caller":"traceutil/trace.go:171","msg":"trace[1646188946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"241.439011ms","start":"2026-02-25T19:25:49.839444Z","end":"2026-02-25T19:25:50.080883Z","steps":["trace[1646188946] 'agreement among raft nodes before linearized reading' (duration: 241.36249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:50.081052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.697487ms","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-25T19:25:50.081086Z","caller":"traceutil/trace.go:171","msg":"trace[1070178092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"248.759998ms","start":"2026-02-25T19:25:49.832318Z","end":"2026-02-25T19:25:50.081078Z","steps":["trace[1070178092] 'agreement among raft nodes before linearized reading' (duration: 248.665947ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.255024Z","caller":"traceutil/trace.go:171","msg":"trace[1470432207] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"166.630429ms","start":"2026-02-25T19:25:50.088369Z","end":"2026-02-25T19:25:50.254999Z","steps":["trace[1470432207] 'process raft request' (duration: 166.324548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.255043Z","caller":"traceutil/trace.go:171","msg":"trace[316759680] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"166.591969ms","start":"2026-02-25T19:25:50.08842Z","end":"2026-02-25T19:25:50.255012Z","steps":["trace[316759680] 'process raft request' (duration: 166.482779ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.25532Z","caller":"traceutil/trace.go:171","msg":"trace[1480755822] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"166.852579ms","start":"2026-02-25T19:25:50.088458Z","end":"2026-02-25T19:25:50.255311Z","steps":["trace[1480755822] 'process raft request' (duration: 166.501768ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:50.255898Z","caller":"traceutil/trace.go:171","msg":"trace[1259458770] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"159.091391ms","start":"2026-02-25T19:25:50.096785Z","end":"2026-02-25T19:25:50.255876Z","steps":["trace[1259458770] 'process raft request' (duration: 158.441957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:15.270961Z","caller":"traceutil/trace.go:171","msg":"trace[664371946] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"172.355992ms","start":"2026-02-25T19:26:15.098577Z","end":"2026-02-25T19:26:15.270933Z","steps":["trace[664371946] 'process raft request' (duration: 172.04647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:27.813387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.173233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:26:53.214468Z","caller":"traceutil/trace.go:171","msg":"trace[1363772478] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"167.862175ms","start":"2026-02-25T19:26:53.046457Z","end":"2026-02-25T19:26:53.214319Z","steps":["trace[1363772478] 'process raft request' (duration: 131.420771ms)","trace[1363772478] 'compare' (duration: 36.194373ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:53.214735Z","caller":"traceutil/trace.go:171","msg":"trace[1029634563] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"167.369794ms","start":"2026-02-25T19:26:53.047354Z","end":"2026-02-25T19:26:53.214724Z","steps":["trace[1029634563] 'process raft request' (duration: 166.898421ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:59.563814Z","caller":"traceutil/trace.go:171","msg":"trace[1108405015] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"206.269359ms","start":"2026-02-25T19:26:59.357522Z","end":"2026-02-25T19:26:59.563791Z","steps":["trace[1108405015] 'process raft request' (duration: 113.953324ms)","trace[1108405015] 'compare' (duration: 92.210815ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:01.162243Z","caller":"traceutil/trace.go:171","msg":"trace[767871690] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"115.457471ms","start":"2026-02-25T19:27:01.046763Z","end":"2026-02-25T19:27:01.16222Z","steps":["trace[767871690] 'process raft request' (duration: 115.28881ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:01.334796Z","caller":"traceutil/trace.go:171","msg":"trace[210406083] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"124.499107ms","start":"2026-02-25T19:27:01.210269Z","end":"2026-02-25T19:27:01.334768Z","steps":["trace[210406083] 'process raft request' (duration: 73.32877ms)","trace[210406083] 'compare' (duration: 51.058597ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:01.937615Z","caller":"traceutil/trace.go:171","msg":"trace[471624401] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"203.934208ms","start":"2026-02-25T19:27:01.733663Z","end":"2026-02-25T19:27:01.937598Z","steps":["trace[471624401] 'process raft request' (duration: 203.831507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:02.108759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.220769ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-25T19:27:11.712323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.297203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T19:27:11.712529Z","caller":"traceutil/trace.go:171","msg":"trace[49885484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"332.552104ms","start":"2026-02-25T19:27:11.379962Z","end":"2026-02-25T19:27:11.712514Z","steps":["trace[49885484] 'range keys from in-memory index tree' (duration: 332.140053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:11.712605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:11.379939Z","time spent":"332.653555ms","remote":"127.0.0.1:56818","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-25T19:27:11.71278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.890714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:27:11.712907Z","caller":"traceutil/trace.go:171","msg":"trace[560058072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"275.132446ms","start":"2026-02-25T19:27:11.437748Z","end":"2026-02-25T19:27:11.71288Z","steps":["trace[560058072] 'range keys from in-memory index tree' (duration: 274.661543ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:11.817611Z","caller":"traceutil/trace.go:171","msg":"trace[2127975610] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"100.866708ms","start":"2026-02-25T19:27:11.716711Z","end":"2026-02-25T19:27:11.817577Z","steps":["trace[2127975610] 'process raft request' (duration: 100.618547ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:11.831916Z","caller":"traceutil/trace.go:171","msg":"trace[681654177] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"115.02024ms","start":"2026-02-25T19:27:11.71688Z","end":"2026-02-25T19:27:11.8319Z","steps":["trace[681654177] 'process raft request' (duration: 114.90702ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:11.832139Z","caller":"traceutil/trace.go:171","msg":"trace[1888820044] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"115.192941ms","start":"2026-02-25T19:27:11.716908Z","end":"2026-02-25T19:27:11.832101Z","steps":["trace[1888820044] 'process raft request' (duration: 114.95827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:19.507609Z","caller":"traceutil/trace.go:171","msg":"trace[268879455] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"554.993985ms","start":"2026-02-25T19:27:18.952544Z","end":"2026-02-25T19:27:19.507538Z","steps":["trace[268879455] 'process raft request' (duration: 554.794594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:19.507793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:18.952523Z","time spent":"555.164706ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:19.508069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.061619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T19:27:19.508143Z","caller":"traceutil/trace.go:171","msg":"trace[875124265] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"237.517257ms","start":"2026-02-25T19:27:19.269956Z","end":"2026-02-25T19:27:19.507473Z","steps":["trace[875124265] 'read index received' (duration: 237.311616ms)","trace[875124265] 'applied index is now lower than readState.Index' (duration: 204.661µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:19.50819Z","caller":"traceutil/trace.go:171","msg":"trace[524891647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"238.26524ms","start":"2026-02-25T19:27:19.269905Z","end":"2026-02-25T19:27:19.50817Z","steps":["trace[524891647] 'agreement among raft nodes before linearized reading' (duration: 237.969049ms)"],"step_count":1}