{"level":"warn","ts":"2026-02-25T02:09:58.043534Z","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-25T02:09:58.043694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-25T02:09:58.044348Z","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-25T02:09:58.044375Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-25T02:09:58.044399Z","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-25T02:09:58.046644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-02-25T02:09:58.046728Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-25T02:09:58.055816Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.542992ms"} {"level":"info","ts":"2026-02-25T02:09:58.062925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-02-25T02:09:58.063019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:09:58.06305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-02-25T02:09:58.063064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:09:58.063084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-02-25T02:09:58.063134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-02-25T02:09:58.076277Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:09:58.078337Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:09:58.079627Z","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-25T02:09:58.082315Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:09:58.08398Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:09:58.084848Z","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-25T02:09:58.085023Z","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-25T02:09:58.085102Z","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-25T02:09:58.08865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-02-25T02:09:58.088982Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-25T02:09:58.09033Z","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-25T02:09:58.090444Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-25T02:09:58.090526Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-25T02:09:58.090587Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:09:58.090631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:09:58.164315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:09:58.164364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:09:58.164402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-02-25T02:09:58.164424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:09:58.164437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-25T02:09:58.164455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-02-25T02:09:58.164471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-25T02:09:58.16616Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:09:58.166188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:09:58.166525Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:09:58.166363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:09:58.166667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:09:58.166839Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:09:58.16759Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:09:58.167732Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:09:58.167775Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:09:58.171049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-02-25T02:09:58.171068Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:10:09.092172Z","caller":"traceutil/trace.go:171","msg":"trace[1547848562] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"147.225732ms","start":"2026-02-25T02:10:08.944914Z","end":"2026-02-25T02:10:09.092139Z","steps":["trace[1547848562] 'read index received' (duration: 147.040949ms)","trace[1547848562] 'applied index is now lower than readState.Index' (duration: 182.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:10:09.092534Z","caller":"traceutil/trace.go:171","msg":"trace[847694374] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"190.810165ms","start":"2026-02-25T02:10:08.901697Z","end":"2026-02-25T02:10:09.092506Z","steps":["trace[847694374] 'process raft request' (duration: 190.290576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:09.092724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.790502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T02:10:09.094004Z","caller":"traceutil/trace.go:171","msg":"trace[2125474598] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:314; }","duration":"149.128754ms","start":"2026-02-25T02:10:08.944856Z","end":"2026-02-25T02:10:09.093984Z","steps":["trace[2125474598] 'agreement among raft nodes before linearized reading' (duration: 147.74741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:40.617414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119986948256621,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:10:40.740048Z","caller":"traceutil/trace.go:171","msg":"trace[889446173] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"623.57764ms","start":"2026-02-25T02:10:40.116444Z","end":"2026-02-25T02:10:40.740021Z","steps":["trace[889446173] 'read index received' (duration: 623.372217ms)","trace[889446173] 'applied index is now lower than readState.Index' (duration: 203.983µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:10:40.740197Z","caller":"traceutil/trace.go:171","msg":"trace[2126538966] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"651.651989ms","start":"2026-02-25T02:10:40.088537Z","end":"2026-02-25T02:10:40.740189Z","steps":["trace[2126538966] 'process raft request' (duration: 651.315763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:40.740499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.945799ms","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-25T02:10:40.740602Z","caller":"traceutil/trace.go:171","msg":"trace[1483892618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"245.081071ms","start":"2026-02-25T02:10:40.495482Z","end":"2026-02-25T02:10:40.740563Z","steps":["trace[1483892618] 'agreement among raft nodes before linearized reading' (duration: 244.865977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:40.740779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:40.088496Z","time spent":"651.72653ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:10:40.740859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.412913ms","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-25T02:10:40.740914Z","caller":"traceutil/trace.go:171","msg":"trace[90826484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"624.530475ms","start":"2026-02-25T02:10:40.116373Z","end":"2026-02-25T02:10:40.740904Z","steps":["trace[90826484] 'agreement among raft nodes before linearized reading' (duration: 624.409943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:40.740952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:40.116348Z","time spent":"624.595627ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:10:40.741244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.034177ms","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-25T02:10:40.741372Z","caller":"traceutil/trace.go:171","msg":"trace[402068486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"209.189409ms","start":"2026-02-25T02:10:40.53214Z","end":"2026-02-25T02:10:40.741329Z","steps":["trace[402068486] 'agreement among raft nodes before linearized reading' (duration: 208.860133ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:41.204404Z","caller":"traceutil/trace.go:171","msg":"trace[1564818332] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"458.194318ms","start":"2026-02-25T02:10:40.746159Z","end":"2026-02-25T02:10:41.204354Z","steps":["trace[1564818332] 'process raft request' (duration: 458.085496ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:41.204553Z","caller":"traceutil/trace.go:171","msg":"trace[628854094] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:522; }","duration":"436.428165ms","start":"2026-02-25T02:10:40.768089Z","end":"2026-02-25T02:10:41.204517Z","steps":["trace[628854094] 'read index received' (duration: 369.616456ms)","trace[628854094] 'applied index is now lower than readState.Index' (duration: 66.808139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:10:41.204639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:40.746133Z","time spent":"458.37376ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:10:41.204695Z","caller":"traceutil/trace.go:171","msg":"trace[754534661] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"457.18773ms","start":"2026-02-25T02:10:40.747414Z","end":"2026-02-25T02:10:41.204601Z","steps":["trace[754534661] 'process raft request' (duration: 456.891085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:41.204418Z","caller":"traceutil/trace.go:171","msg":"trace[315449253] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"459.069443ms","start":"2026-02-25T02:10:40.745283Z","end":"2026-02-25T02:10:41.204352Z","steps":["trace[315449253] 'process raft request' (duration: 392.406056ms)","trace[315449253] 'compare' (duration: 66.385282ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:10:41.204825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.707242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:10:41.204944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:40.74524Z","time spent":"459.579482ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:10:41.205235Z","caller":"traceutil/trace.go:171","msg":"trace[869423031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"436.946195ms","start":"2026-02-25T02:10:40.76804Z","end":"2026-02-25T02:10:41.204986Z","steps":["trace[869423031] 'agreement among raft nodes before linearized reading' (duration: 436.566188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:10:41.204945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:40.747403Z","time spent":"457.418155ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:10:41.205331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:10:40.768007Z","time spent":"437.311241ms","remote":"127.0.0.1:57392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:10:43.221294Z","caller":"traceutil/trace.go:171","msg":"trace[545551458] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"235.132672ms","start":"2026-02-25T02:10:42.986132Z","end":"2026-02-25T02:10:43.221265Z","steps":["trace[545551458] 'process raft request' (duration: 234.947019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:53.418188Z","caller":"traceutil/trace.go:171","msg":"trace[147148138] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"125.315738ms","start":"2026-02-25T02:10:53.29285Z","end":"2026-02-25T02:10:53.418166Z","steps":["trace[147148138] 'process raft request' (duration: 125.103554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:53.423886Z","caller":"traceutil/trace.go:171","msg":"trace[1089671249] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"129.46737ms","start":"2026-02-25T02:10:53.29439Z","end":"2026-02-25T02:10:53.423857Z","steps":["trace[1089671249] 'process raft request' (duration: 129.424489ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:53.42395Z","caller":"traceutil/trace.go:171","msg":"trace[296867370] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"130.694851ms","start":"2026-02-25T02:10:53.293225Z","end":"2026-02-25T02:10:53.42392Z","steps":["trace[296867370] 'process raft request' (duration: 130.466007ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:59.00276Z","caller":"traceutil/trace.go:171","msg":"trace[1903172106] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"173.107123ms","start":"2026-02-25T02:10:58.829615Z","end":"2026-02-25T02:10:59.002722Z","steps":["trace[1903172106] 'process raft request' (duration: 172.759487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:10:59.257612Z","caller":"traceutil/trace.go:171","msg":"trace[551673791] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"249.183991ms","start":"2026-02-25T02:10:59.008396Z","end":"2026-02-25T02:10:59.25758Z","steps":["trace[551673791] 'process raft request' (duration: 249.003388ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:01.182843Z","caller":"traceutil/trace.go:171","msg":"trace[1122560292] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"167.796203ms","start":"2026-02-25T02:11:01.015008Z","end":"2026-02-25T02:11:01.182804Z","steps":["trace[1122560292] 'process raft request' (duration: 167.58286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:02.083876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.759224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:11:02.083928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.880877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:11:02.084003Z","caller":"traceutil/trace.go:171","msg":"trace[1092144192] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:694; }","duration":"176.991979ms","start":"2026-02-25T02:11:01.906991Z","end":"2026-02-25T02:11:02.083983Z","steps":["trace[1092144192] 'range keys from in-memory index tree' (duration: 176.745545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:02.083995Z","caller":"traceutil/trace.go:171","msg":"trace[1820571374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"316.916217ms","start":"2026-02-25T02:11:01.76705Z","end":"2026-02-25T02:11:02.083966Z","steps":["trace[1820571374] 'range keys from in-memory index tree' (duration: 316.698024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:02.084005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.939805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:11:02.084043Z","caller":"traceutil/trace.go:171","msg":"trace[2578039] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:694; }","duration":"328.017107ms","start":"2026-02-25T02:11:01.756016Z","end":"2026-02-25T02:11:02.084033Z","steps":["trace[2578039] 'count revisions from in-memory index tree' (duration: 327.864594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:02.084074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:01.755988Z","time spent":"328.077657ms","remote":"127.0.0.1:57556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:11:02.084067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:01.767041Z","time spent":"317.003968ms","remote":"127.0.0.1:57392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:11:21.785621Z","caller":"traceutil/trace.go:171","msg":"trace[1548170883] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"100.011996ms","start":"2026-02-25T02:11:21.685581Z","end":"2026-02-25T02:11:21.785593Z","steps":["trace[1548170883] 'process raft request' (duration: 99.388855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:21.79141Z","caller":"traceutil/trace.go:171","msg":"trace[1229958721] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"105.636937ms","start":"2026-02-25T02:11:21.685744Z","end":"2026-02-25T02:11:21.791381Z","steps":["trace[1229958721] 'process raft request' (duration: 105.490284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.733546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.660065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:11:25.733764Z","caller":"traceutil/trace.go:171","msg":"trace[1860436347] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"307.038033ms","start":"2026-02-25T02:11:25.426705Z","end":"2026-02-25T02:11:25.733743Z","steps":["trace[1860436347] 'read index received' (duration: 199.964911ms)","trace[1860436347] 'applied index is now lower than readState.Index' (duration: 107.071632ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:25.733892Z","caller":"traceutil/trace.go:171","msg":"trace[1191813267] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"371.037239ms","start":"2026-02-25T02:11:25.362817Z","end":"2026-02-25T02:11:25.733855Z","steps":["trace[1191813267] 'process raft request' (duration: 263.803764ms)","trace[1191813267] 'compare' (duration: 106.38271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:25.734083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.362803Z","time spent":"371.187112ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:11:25.734105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.773648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:11:25.734094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.366259ms","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-25T02:11:25.734159Z","caller":"traceutil/trace.go:171","msg":"trace[2144873693] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:764; }","duration":"250.855539ms","start":"2026-02-25T02:11:25.483285Z","end":"2026-02-25T02:11:25.73414Z","steps":["trace[2144873693] 'agreement among raft nodes before linearized reading' (duration: 250.752008ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:25.734191Z","caller":"traceutil/trace.go:171","msg":"trace[599627387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"307.512782ms","start":"2026-02-25T02:11:25.426656Z","end":"2026-02-25T02:11:25.734169Z","steps":["trace[599627387] 'agreement among raft nodes before linearized reading' (duration: 307.292568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.734242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:11:25.426629Z","time spent":"307.603174ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:11:25.942083Z","caller":"traceutil/trace.go:171","msg":"trace[1397417739] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"144.103822ms","start":"2026-02-25T02:11:25.797955Z","end":"2026-02-25T02:11:25.942058Z","steps":["trace[1397417739] 'read index received' (duration: 143.872558ms)","trace[1397417739] 'applied index is now lower than readState.Index' (duration: 230.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:25.942131Z","caller":"traceutil/trace.go:171","msg":"trace[1200279189] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"202.182021ms","start":"2026-02-25T02:11:25.739916Z","end":"2026-02-25T02:11:25.942098Z","steps":["trace[1200279189] 'process raft request' (duration: 201.984278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.942341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.362926ms","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-25T02:11:25.942393Z","caller":"traceutil/trace.go:171","msg":"trace[530416150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"144.463658ms","start":"2026-02-25T02:11:25.797915Z","end":"2026-02-25T02:11:25.942379Z","steps":["trace[530416150] 'agreement among raft nodes before linearized reading' (duration: 144.283665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:25.942504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.537061ms","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-25T02:11:25.94259Z","caller":"traceutil/trace.go:171","msg":"trace[68097120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"138.672174ms","start":"2026-02-25T02:11:25.803903Z","end":"2026-02-25T02:11:25.942575Z","steps":["trace[68097120] 'agreement among raft nodes before linearized reading' (duration: 138.407769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:27.804903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.7355ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:11:42.091547Z","caller":"traceutil/trace.go:171","msg":"trace[1747492090] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"161.440174ms","start":"2026-02-25T02:11:41.930078Z","end":"2026-02-25T02:11:42.091518Z","steps":["trace[1747492090] 'read index received' (duration: 161.17302ms)","trace[1747492090] 'applied index is now lower than readState.Index' (duration: 265.494µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:11:42.091894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.458058ms","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-25T02:11:42.091942Z","caller":"traceutil/trace.go:171","msg":"trace[421865055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:858; }","duration":"154.525809ms","start":"2026-02-25T02:11:41.9374Z","end":"2026-02-25T02:11:42.091926Z","steps":["trace[421865055] 'agreement among raft nodes before linearized reading' (duration: 154.321036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:42.091976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.852731ms","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-25T02:11:42.092138Z","caller":"traceutil/trace.go:171","msg":"trace[1174784647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:858; }","duration":"162.081425ms","start":"2026-02-25T02:11:41.930012Z","end":"2026-02-25T02:11:42.092094Z","steps":["trace[1174784647] 'agreement among raft nodes before linearized reading' (duration: 161.650298ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:54.446484Z","caller":"traceutil/trace.go:171","msg":"trace[68929078] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:934; }","duration":"215.625333ms","start":"2026-02-25T02:11:54.230829Z","end":"2026-02-25T02:11:54.446454Z","steps":["trace[68929078] 'read index received' (duration: 214.771088ms)","trace[68929078] 'applied index is now lower than readState.Index' (duration: 852.905µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:11:54.446497Z","caller":"traceutil/trace.go:171","msg":"trace[185746446] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"242.264275ms","start":"2026-02-25T02:11:54.204212Z","end":"2026-02-25T02:11:54.446476Z","steps":["trace[185746446] 'process raft request' (duration: 242.049821ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:54.446351Z","caller":"traceutil/trace.go:171","msg":"trace[1947276446] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"244.822921ms","start":"2026-02-25T02:11:54.201491Z","end":"2026-02-25T02:11:54.446314Z","steps":["trace[1947276446] 'process raft request' (duration: 244.090958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:11:54.446809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.951413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T02:11:54.446818Z","caller":"traceutil/trace.go:171","msg":"trace[1105635476] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"209.37783ms","start":"2026-02-25T02:11:54.237401Z","end":"2026-02-25T02:11:54.446778Z","steps":["trace[1105635476] 'process raft request' (duration: 209.134726ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:54.446893Z","caller":"traceutil/trace.go:171","msg":"trace[205107207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"245.114296ms","start":"2026-02-25T02:11:54.201759Z","end":"2026-02-25T02:11:54.446873Z","steps":["trace[205107207] 'agreement among raft nodes before linearized reading' (duration: 244.833581ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:58.599672Z","caller":"traceutil/trace.go:171","msg":"trace[1232998493] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"132.182597ms","start":"2026-02-25T02:11:58.467455Z","end":"2026-02-25T02:11:58.599637Z","steps":["trace[1232998493] 'process raft request' (duration: 131.926782ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:58.60013Z","caller":"traceutil/trace.go:171","msg":"trace[309219990] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"131.82341ms","start":"2026-02-25T02:11:58.468273Z","end":"2026-02-25T02:11:58.600097Z","steps":["trace[309219990] 'process raft request' (duration: 131.3176ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:11:58.600507Z","caller":"traceutil/trace.go:171","msg":"trace[1912673970] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"129.274544ms","start":"2026-02-25T02:11:58.471219Z","end":"2026-02-25T02:11:58.600494Z","steps":["trace[1912673970] 'process raft request' (duration: 128.782995ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:00.632447Z","caller":"traceutil/trace.go:171","msg":"trace[1419670742] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"105.667647ms","start":"2026-02-25T02:12:00.526751Z","end":"2026-02-25T02:12:00.632418Z","steps":["trace[1419670742] 'read index received' (duration: 105.426283ms)","trace[1419670742] 'applied index is now lower than readState.Index' (duration: 240.224µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:00.632616Z","caller":"traceutil/trace.go:171","msg":"trace[1638162468] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"123.442279ms","start":"2026-02-25T02:12:00.509137Z","end":"2026-02-25T02:12:00.632579Z","steps":["trace[1638162468] 'process raft request' (duration: 123.120004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:00.632675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.899132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:12:00.632713Z","caller":"traceutil/trace.go:171","msg":"trace[394576417] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:931; }","duration":"106.014084ms","start":"2026-02-25T02:12:00.526689Z","end":"2026-02-25T02:12:00.632703Z","steps":["trace[394576417] 'agreement among raft nodes before linearized reading' (duration: 105.859591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:01.408237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.591803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:01.408419Z","caller":"traceutil/trace.go:171","msg":"trace[1918153789] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:973; }","duration":"641.737849ms","start":"2026-02-25T02:12:00.766662Z","end":"2026-02-25T02:12:01.4084Z","steps":["trace[1918153789] 'read index received' (duration: 271.764378ms)","trace[1918153789] 'applied index is now lower than readState.Index' (duration: 369.972391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:01.408496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.8321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:01.408523Z","caller":"traceutil/trace.go:171","msg":"trace[1064767914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"641.892921ms","start":"2026-02-25T02:12:00.76662Z","end":"2026-02-25T02:12:01.408513Z","steps":["trace[1064767914] 'agreement among raft nodes before linearized reading' (duration: 641.84558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:01.408555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:00.766604Z","time spent":"641.943982ms","remote":"127.0.0.1:57392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:12:01.40868Z","caller":"traceutil/trace.go:171","msg":"trace[1122925987] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"770.239508ms","start":"2026-02-25T02:12:00.638407Z","end":"2026-02-25T02:12:01.408647Z","steps":["trace[1122925987] 'process raft request' (duration: 769.924601ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:01.408797Z","caller":"traceutil/trace.go:171","msg":"trace[1877519251] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"771.44655ms","start":"2026-02-25T02:12:00.637313Z","end":"2026-02-25T02:12:01.40876Z","steps":["trace[1877519251] 'process raft request' (duration: 401.199154ms)","trace[1877519251] 'compare' (duration: 369.37945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:01.408863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:00.638401Z","time spent":"770.35081ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:01.409029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:00.637308Z","time spent":"771.605692ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:02.293189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.449975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:02.293266Z","caller":"traceutil/trace.go:171","msg":"trace[2034261065] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:934; }","duration":"732.588678ms","start":"2026-02-25T02:12:01.560658Z","end":"2026-02-25T02:12:02.293247Z","steps":["trace[2034261065] 'count revisions from in-memory index tree' (duration: 732.300603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:02.293306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:01.560629Z","time spent":"732.66631ms","remote":"127.0.0.1:44090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:12:02.294132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.973711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:12:02.294128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.281238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:12:02.29418Z","caller":"traceutil/trace.go:171","msg":"trace[894734334] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:934; }","duration":"272.061853ms","start":"2026-02-25T02:12:02.022105Z","end":"2026-02-25T02:12:02.294167Z","steps":["trace[894734334] 'count revisions from in-memory index tree' (duration: 271.833059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:02.294174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.680101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:02.294216Z","caller":"traceutil/trace.go:171","msg":"trace[1384369741] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:934; }","duration":"384.441671ms","start":"2026-02-25T02:12:01.90975Z","end":"2026-02-25T02:12:02.294192Z","steps":["trace[1384369741] 'range keys from in-memory index tree' (duration: 384.059654ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:02.29424Z","caller":"traceutil/trace.go:171","msg":"trace[1840577873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"525.778372ms","start":"2026-02-25T02:12:01.768444Z","end":"2026-02-25T02:12:02.294223Z","steps":["trace[1840577873] 'range keys from in-memory index tree' (duration: 525.59519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:02.294253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:01.90972Z","time spent":"384.526243ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-02-25T02:12:02.294282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:01.768419Z","time spent":"525.854164ms","remote":"127.0.0.1:57392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:12:02.66265Z","caller":"traceutil/trace.go:171","msg":"trace[479113895] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"363.557834ms","start":"2026-02-25T02:12:02.299058Z","end":"2026-02-25T02:12:02.662616Z","steps":["trace[479113895] 'process raft request' (duration: 274.517587ms)","trace[479113895] 'compare' (duration: 88.775293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:02.662862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:02.299029Z","time spent":"363.727447ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:02.66284Z","caller":"traceutil/trace.go:171","msg":"trace[243557928] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"119.780722ms","start":"2026-02-25T02:12:02.543023Z","end":"2026-02-25T02:12:02.662804Z","steps":["trace[243557928] 'read index received' (duration: 30.551352ms)","trace[243557928] 'applied index is now lower than readState.Index' (duration: 89.22645ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:02.662843Z","caller":"traceutil/trace.go:171","msg":"trace[1063168944] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"276.253698ms","start":"2026-02-25T02:12:02.386565Z","end":"2026-02-25T02:12:02.662819Z","steps":["trace[1063168944] 'process raft request' (duration: 275.986843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:02.663004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.960176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:12:02.663089Z","caller":"traceutil/trace.go:171","msg":"trace[1403807513] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:936; }","duration":"120.119789ms","start":"2026-02-25T02:12:02.54295Z","end":"2026-02-25T02:12:02.66307Z","steps":["trace[1403807513] 'agreement among raft nodes before linearized reading' (duration: 119.943656ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:02.81919Z","caller":"traceutil/trace.go:171","msg":"trace[1988642474] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"143.829997ms","start":"2026-02-25T02:12:02.675327Z","end":"2026-02-25T02:12:02.819157Z","steps":["trace[1988642474] 'process raft request' (duration: 128.724124ms)","trace[1988642474] 'compare' (duration: 14.721076ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:03.704298Z","caller":"traceutil/trace.go:171","msg":"trace[428779040] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"285.910512ms","start":"2026-02-25T02:12:03.418352Z","end":"2026-02-25T02:12:03.704262Z","steps":["trace[428779040] 'process raft request' (duration: 285.675228ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:03.94943Z","caller":"traceutil/trace.go:171","msg":"trace[700632490] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"180.474259ms","start":"2026-02-25T02:12:03.768924Z","end":"2026-02-25T02:12:03.949398Z","steps":["trace[700632490] 'read index received' (duration: 99.899224ms)","trace[700632490] 'applied index is now lower than readState.Index' (duration: 80.573735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:03.949601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.660012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:03.949633Z","caller":"traceutil/trace.go:171","msg":"trace[423032484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"180.722844ms","start":"2026-02-25T02:12:03.768899Z","end":"2026-02-25T02:12:03.949622Z","steps":["trace[423032484] 'agreement among raft nodes before linearized reading' (duration: 180.607421ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:03.949985Z","caller":"traceutil/trace.go:171","msg":"trace[464138983] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"531.03693ms","start":"2026-02-25T02:12:03.418935Z","end":"2026-02-25T02:12:03.949972Z","steps":["trace[464138983] 'process raft request' (duration: 449.966786ms)","trace[464138983] 'compare' (duration: 80.34234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:03.950065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:03.418903Z","time spent":"531.10231ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:04.813102Z","caller":"traceutil/trace.go:171","msg":"trace[1431198400] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"140.795201ms","start":"2026-02-25T02:12:04.672277Z","end":"2026-02-25T02:12:04.813072Z","steps":["trace[1431198400] 'process raft request' (duration: 140.255502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:04.953488Z","caller":"traceutil/trace.go:171","msg":"trace[850887033] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"130.495756ms","start":"2026-02-25T02:12:04.822947Z","end":"2026-02-25T02:12:04.953443Z","steps":["trace[850887033] 'read index received' (duration: 97.401659ms)","trace[850887033] 'applied index is now lower than readState.Index' (duration: 33.091057ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:04.953654Z","caller":"traceutil/trace.go:171","msg":"trace[1952961055] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"135.562808ms","start":"2026-02-25T02:12:04.818055Z","end":"2026-02-25T02:12:04.953617Z","steps":["trace[1952961055] 'process raft request' (duration: 102.409629ms)","trace[1952961055] 'compare' (duration: 32.14475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:04.953825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.854394ms","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-25T02:12:04.953867Z","caller":"traceutil/trace.go:171","msg":"trace[1995024749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"130.964135ms","start":"2026-02-25T02:12:04.822889Z","end":"2026-02-25T02:12:04.953853Z","steps":["trace[1995024749] 'agreement among raft nodes before linearized reading' (duration: 130.719351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:12.63396Z","caller":"traceutil/trace.go:171","msg":"trace[448512576] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"169.02967ms","start":"2026-02-25T02:12:12.4649Z","end":"2026-02-25T02:12:12.633929Z","steps":["trace[448512576] 'process raft request' (duration: 168.863647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:13.271187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.142828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:13.271298Z","caller":"traceutil/trace.go:171","msg":"trace[1848481802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"502.317441ms","start":"2026-02-25T02:12:12.768948Z","end":"2026-02-25T02:12:13.271266Z","steps":["trace[1848481802] 'range keys from in-memory index tree' (duration: 501.981104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:13.271344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:12.768913Z","time spent":"502.422023ms","remote":"127.0.0.1:57406","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-25T02:12:13.271547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.929462ms","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-25T02:12:13.271724Z","caller":"traceutil/trace.go:171","msg":"trace[680927291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"427.153946ms","start":"2026-02-25T02:12:12.844523Z","end":"2026-02-25T02:12:13.271677Z","steps":["trace[680927291] 'range keys from in-memory index tree' (duration: 426.673396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:13.271778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:12.844497Z","time spent":"427.268769ms","remote":"127.0.0.1:57634","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-25T02:12:13.498203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119986948257735,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:12:13.514501Z","caller":"traceutil/trace.go:171","msg":"trace[1890633317] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"580.938169ms","start":"2026-02-25T02:12:12.933532Z","end":"2026-02-25T02:12:13.51447Z","steps":["trace[1890633317] 'process raft request' (duration: 580.756806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:13.514692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:12.933492Z","time spent":"581.093061ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:12:13.516427Z","caller":"traceutil/trace.go:171","msg":"trace[1838598068] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"239.143022ms","start":"2026-02-25T02:12:13.277221Z","end":"2026-02-25T02:12:13.516364Z","steps":["trace[1838598068] 'process raft request' (duration: 238.952409ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:13.516496Z","caller":"traceutil/trace.go:171","msg":"trace[825549110] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1045; }","duration":"519.052264ms","start":"2026-02-25T02:12:12.997425Z","end":"2026-02-25T02:12:13.516477Z","steps":["trace[825549110] 'read index received' (duration: 516.919456ms)","trace[825549110] 'applied index is now lower than readState.Index' (duration: 2.130808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:12:13.516854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.321941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-dq99k\" ","response":"range_response_count:1 size:12336"} {"level":"info","ts":"2026-02-25T02:12:13.516907Z","caller":"traceutil/trace.go:171","msg":"trace[1061568934] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-dq99k; range_end:; response_count:1; response_revision:1003; }","duration":"296.423383ms","start":"2026-02-25T02:12:13.220465Z","end":"2026-02-25T02:12:13.516889Z","steps":["trace[1061568934] 'agreement among raft nodes before linearized reading' (duration: 296.238429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:13.516902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.183258ms","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-25T02:12:13.517003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.556934ms","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-25T02:12:13.517006Z","caller":"traceutil/trace.go:171","msg":"trace[976966485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"519.33613ms","start":"2026-02-25T02:12:12.997641Z","end":"2026-02-25T02:12:13.516977Z","steps":["trace[976966485] 'agreement among raft nodes before linearized reading' (duration: 519.055404ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:13.51705Z","caller":"traceutil/trace.go:171","msg":"trace[598009854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"519.656696ms","start":"2026-02-25T02:12:12.997379Z","end":"2026-02-25T02:12:13.517036Z","steps":["trace[598009854] 'agreement among raft nodes before linearized reading' (duration: 519.239637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:13.517061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:12.997618Z","time spent":"519.432852ms","remote":"127.0.0.1:57634","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-25T02:12:13.517096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:12.997355Z","time spent":"519.733477ms","remote":"127.0.0.1:57634","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-25T02:13:12.01179Z","caller":"traceutil/trace.go:171","msg":"trace[1420796409] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"189.004332ms","start":"2026-02-25T02:13:11.822745Z","end":"2026-02-25T02:13:12.011749Z","steps":["trace[1420796409] 'process raft request' (duration: 110.857093ms)","trace[1420796409] 'compare' (duration: 77.773622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:18.927708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.231903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:18.927814Z","caller":"traceutil/trace.go:171","msg":"trace[1444430455] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1877; }","duration":"159.288637ms","start":"2026-02-25T02:15:18.768507Z","end":"2026-02-25T02:15:18.927796Z","steps":["trace[1444430455] 'read index received' (duration: 10.121374ms)","trace[1444430455] 'applied index is now lower than readState.Index' (duration: 149.165382ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:18.927903Z","caller":"traceutil/trace.go:171","msg":"trace[1448406365] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"195.850389ms","start":"2026-02-25T02:15:18.732041Z","end":"2026-02-25T02:15:18.927892Z","steps":["trace[1448406365] 'process raft request' (duration: 67.3437ms)","trace[1448406365] 'compare' (duration: 127.993619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:18.928272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.762225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:18.928371Z","caller":"traceutil/trace.go:171","msg":"trace[1422869368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1797; }","duration":"159.923268ms","start":"2026-02-25T02:15:18.768422Z","end":"2026-02-25T02:15:18.928345Z","steps":["trace[1422869368] 'agreement among raft nodes before linearized reading' (duration: 159.804116ms)"],"step_count":1}