{"level":"warn","ts":"2026-02-11T10:15:40.554349Z","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-11T10:15:40.555129Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-11T10:15:40.55523Z","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-11T10:15:40.556555Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-11T10:15:40.556641Z","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-11T10:15:40.557556Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-02-11T10:15:40.562015Z","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":2,"max-cpu-available":2,"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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-11T10:15:40.567127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.850196ms"} {"level":"info","ts":"2026-02-11T10:15:40.574727Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-02-11T10:15:40.574902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T10:15:40.574948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-02-11T10:15:40.574972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T10:15:40.574992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-02-11T10:15:40.575049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-02-11T10:15:40.589912Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T10:15:40.608365Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T10:15:40.611239Z","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-11T10:15:40.61483Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T10:15:40.620061Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T10:15:40.620223Z","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-11T10:15:40.620248Z","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-11T10:15:40.620269Z","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-11T10:15:40.63437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-02-11T10:15:40.634536Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-11T10:15:40.635343Z","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-11T10:15:40.635498Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-11T10:15:40.63929Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-11T10:15:40.640212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T10:15:40.640274Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T10:15:41.476239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T10:15:41.476283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T10:15:41.476332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-02-11T10:15:41.476343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-02-11T10:15:41.476349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-11T10:15:41.476357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-02-11T10:15:41.476364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-11T10:15:41.478498Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T10:15:41.478521Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T10:15:41.478792Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T10:15:41.484993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T10:15:41.485062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T10:15:41.486004Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T10:15:41.486153Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T10:15:41.486172Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T10:15:41.486554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-02-11T10:15:41.495953Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T10:15:41.495969Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T10:15:57.780728Z","caller":"traceutil/trace.go:171","msg":"trace[2132108135] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"107.882447ms","start":"2026-02-11T10:15:57.672827Z","end":"2026-02-11T10:15:57.780709Z","steps":["trace[2132108135] 'process raft request' (duration: 105.626244ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:15:57.782832Z","caller":"traceutil/trace.go:171","msg":"trace[1157703516] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"106.479064ms","start":"2026-02-11T10:15:57.676336Z","end":"2026-02-11T10:15:57.782815Z","steps":["trace[1157703516] 'process raft request' (duration: 106.120185ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:15:57.783251Z","caller":"traceutil/trace.go:171","msg":"trace[246745868] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"103.226356ms","start":"2026-02-11T10:15:57.680014Z","end":"2026-02-11T10:15:57.783241Z","steps":["trace[246745868] 'process raft request' (duration: 102.520789ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:15:57.783278Z","caller":"traceutil/trace.go:171","msg":"trace[1404072734] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"101.850372ms","start":"2026-02-11T10:15:57.681421Z","end":"2026-02-11T10:15:57.783271Z","steps":["trace[1404072734] 'process raft request' (duration: 101.142106ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:15:57.783293Z","caller":"traceutil/trace.go:171","msg":"trace[1715546682] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"101.789641ms","start":"2026-02-11T10:15:57.6815Z","end":"2026-02-11T10:15:57.78329Z","steps":["trace[1715546682] 'process raft request' (duration: 101.089204ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:03.726405Z","caller":"traceutil/trace.go:171","msg":"trace[1945697210] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"252.048516ms","start":"2026-02-11T10:16:03.474339Z","end":"2026-02-11T10:16:03.726388Z","steps":["trace[1945697210] 'process raft request' (duration: 251.959424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:03.91405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.704406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:16:03.914188Z","caller":"traceutil/trace.go:171","msg":"trace[249727137] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"201.410703ms","start":"2026-02-11T10:16:03.712749Z","end":"2026-02-11T10:16:03.91416Z","steps":["trace[249727137] 'process raft request' (duration: 50.97212ms)","trace[249727137] 'compare' (duration: 149.44339ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:08.048201Z","caller":"traceutil/trace.go:171","msg":"trace[794991698] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"115.269118ms","start":"2026-02-11T10:16:07.932906Z","end":"2026-02-11T10:16:08.048175Z","steps":["trace[794991698] 'read index received' (duration: 115.115645ms)","trace[794991698] 'applied index is now lower than readState.Index' (duration: 152.553µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:08.048326Z","caller":"traceutil/trace.go:171","msg":"trace[1867305441] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"306.205772ms","start":"2026-02-11T10:16:07.74211Z","end":"2026-02-11T10:16:08.048316Z","steps":["trace[1867305441] 'process raft request' (duration: 305.927705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:08.048423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:07.74209Z","time spent":"306.261622ms","remote":"127.0.0.1:43810","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-11T10:16:08.048612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.667867ms","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-11T10:16:08.048678Z","caller":"traceutil/trace.go:171","msg":"trace[283758692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"115.792281ms","start":"2026-02-11T10:16:07.932866Z","end":"2026-02-11T10:16:08.048659Z","steps":["trace[283758692] 'agreement among raft nodes before linearized reading' (duration: 115.675598ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:12.198544Z","caller":"traceutil/trace.go:171","msg":"trace[1403305822] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"111.11849ms","start":"2026-02-11T10:16:12.087412Z","end":"2026-02-11T10:16:12.198531Z","steps":["trace[1403305822] 'read index received' (duration: 110.969826ms)","trace[1403305822] 'applied index is now lower than readState.Index' (duration: 148.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:12.198625Z","caller":"traceutil/trace.go:171","msg":"trace[2146067692] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"128.349108ms","start":"2026-02-11T10:16:12.07027Z","end":"2026-02-11T10:16:12.198619Z","steps":["trace[2146067692] 'process raft request' (duration: 128.150483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:12.198788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.364735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-11T10:16:12.198811Z","caller":"traceutil/trace.go:171","msg":"trace[2002019997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"111.406696ms","start":"2026-02-11T10:16:12.087397Z","end":"2026-02-11T10:16:12.198804Z","steps":["trace[2002019997] 'agreement among raft nodes before linearized reading' (duration: 111.353795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:13.430885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.260354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:13.430949Z","caller":"traceutil/trace.go:171","msg":"trace[1201829657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"146.402717ms","start":"2026-02-11T10:16:13.284532Z","end":"2026-02-11T10:16:13.430935Z","steps":["trace[1201829657] 'range keys from in-memory index tree' (duration: 146.213092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:14.683426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997258636,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:16:14.79713Z","caller":"traceutil/trace.go:171","msg":"trace[740168918] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"803.260837ms","start":"2026-02-11T10:16:13.993856Z","end":"2026-02-11T10:16:14.797117Z","steps":["trace[740168918] 'process raft request' (duration: 803.192085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:14.797219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:13.993837Z","time spent":"803.34549ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T10:16:15.45246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.167952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:16:15.452618Z","caller":"traceutil/trace.go:171","msg":"trace[566191280] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:564; }","duration":"1.269796547s","start":"2026-02-11T10:16:14.182806Z","end":"2026-02-11T10:16:15.452602Z","steps":["trace[566191280] 'read index received' (duration: 614.381492ms)","trace[566191280] 'applied index is now lower than readState.Index' (duration: 655.414085ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:15.452812Z","caller":"traceutil/trace.go:171","msg":"trace[1396005271] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.454096654s","start":"2026-02-11T10:16:13.998702Z","end":"2026-02-11T10:16:15.452799Z","steps":["trace[1396005271] 'process raft request' (duration: 1.315515152s)","trace[1396005271] 'compare' (duration: 137.722611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:15.45288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:13.998695Z","time spent":"1.454153756s","remote":"127.0.0.1:43810","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-11T10:16:15.453104Z","caller":"traceutil/trace.go:171","msg":"trace[2138103809] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.261229284s","start":"2026-02-11T10:16:14.191865Z","end":"2026-02-11T10:16:15.453095Z","steps":["trace[2138103809] 'process raft request' (duration: 1.260679041s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.453157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.191816Z","time spent":"1.261320686s","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T10:16:15.453287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270477603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:15.45332Z","caller":"traceutil/trace.go:171","msg":"trace[1076641328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"1.270531294s","start":"2026-02-11T10:16:14.18278Z","end":"2026-02-11T10:16:15.453311Z","steps":["trace[1076641328] 'agreement among raft nodes before linearized reading' (duration: 1.270482513s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.453353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.182767Z","time spent":"1.270578736s","remote":"127.0.0.1:43530","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-11T10:16:15.685656Z","caller":"traceutil/trace.go:171","msg":"trace[406158026] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"232.968653ms","start":"2026-02-11T10:16:15.452644Z","end":"2026-02-11T10:16:15.685613Z","steps":["trace[406158026] 'read index received' (duration: 232.796889ms)","trace[406158026] 'applied index is now lower than readState.Index' (duration: 171.344µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:15.686039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095767784s","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-11T10:16:15.686114Z","caller":"traceutil/trace.go:171","msg":"trace[1801444157] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:547; }","duration":"1.095900757s","start":"2026-02-11T10:16:14.590194Z","end":"2026-02-11T10:16:15.686094Z","steps":["trace[1801444157] 'agreement among raft nodes before linearized reading' (duration: 1.095750353s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.686135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110923504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-11T10:16:15.686159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.59018Z","time spent":"1.095969819s","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-11T10:16:15.686185Z","caller":"traceutil/trace.go:171","msg":"trace[1176230036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"1.111113979s","start":"2026-02-11T10:16:14.575061Z","end":"2026-02-11T10:16:15.686175Z","steps":["trace[1176230036] 'agreement among raft nodes before linearized reading' (duration: 1.110989646s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.686232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.575042Z","time spent":"1.11118051s","remote":"127.0.0.1:43810","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-11T10:16:15.686505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297450454s","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-11T10:16:15.686572Z","caller":"traceutil/trace.go:171","msg":"trace[2131438785] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:547; }","duration":"1.297474375s","start":"2026-02-11T10:16:14.389047Z","end":"2026-02-11T10:16:15.686521Z","steps":["trace[2131438785] 'agreement among raft nodes before linearized reading' (duration: 1.297427664s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.686625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.389002Z","time spent":"1.297588118s","remote":"127.0.0.1:43570","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":"info","ts":"2026-02-11T10:16:15.686734Z","caller":"traceutil/trace.go:171","msg":"trace[832301865] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"886.544685ms","start":"2026-02-11T10:16:14.800174Z","end":"2026-02-11T10:16:15.686718Z","steps":["trace[832301865] 'process raft request' (duration: 885.327236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.686861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.800161Z","time spent":"886.627037ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T10:16:15.686926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451442661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-11T10:16:15.68697Z","caller":"traceutil/trace.go:171","msg":"trace[498960563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"1.451502862s","start":"2026-02-11T10:16:14.235459Z","end":"2026-02-11T10:16:15.686962Z","steps":["trace[498960563] 'agreement among raft nodes before linearized reading' (duration: 1.45142083s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.686997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.235451Z","time spent":"1.451534833s","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T10:16:15.687096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.286327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-11T10:16:15.687131Z","caller":"traceutil/trace.go:171","msg":"trace[446287559] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"296.354279ms","start":"2026-02-11T10:16:15.390764Z","end":"2026-02-11T10:16:15.687118Z","steps":["trace[446287559] 'agreement among raft nodes before linearized reading' (duration: 296.235956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.687309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478134746s","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-11T10:16:15.687355Z","caller":"traceutil/trace.go:171","msg":"trace[1542897019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"1.478233618s","start":"2026-02-11T10:16:14.209094Z","end":"2026-02-11T10:16:15.687327Z","steps":["trace[1542897019] 'agreement among raft nodes before linearized reading' (duration: 1.478134146s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.687377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.209083Z","time spent":"1.478288499s","remote":"127.0.0.1:43810","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-11T10:16:15.687446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.011688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-11T10:16:15.687478Z","caller":"traceutil/trace.go:171","msg":"trace[1860432447] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"698.074049ms","start":"2026-02-11T10:16:14.989394Z","end":"2026-02-11T10:16:15.687468Z","steps":["trace[1860432447] 'agreement among raft nodes before linearized reading' (duration: 697.933046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.687601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.98938Z","time spent":"698.11916ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-11T10:16:15.687667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484038926s","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-11T10:16:15.687711Z","caller":"traceutil/trace.go:171","msg":"trace[1646021740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"1.484114497s","start":"2026-02-11T10:16:14.203589Z","end":"2026-02-11T10:16:15.687704Z","steps":["trace[1646021740] 'agreement among raft nodes before linearized reading' (duration: 1.483975444s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.687731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.203572Z","time spent":"1.484153629s","remote":"127.0.0.1:43810","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-11T10:16:15.687955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.774493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T10:16:15.687847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.403756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-11T10:16:15.687979Z","caller":"traceutil/trace.go:171","msg":"trace[1024883145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"225.816453ms","start":"2026-02-11T10:16:15.462154Z","end":"2026-02-11T10:16:15.687971Z","steps":["trace[1024883145] 'agreement among raft nodes before linearized reading' (duration: 225.753182ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:15.688003Z","caller":"traceutil/trace.go:171","msg":"trace[683392980] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"898.583121ms","start":"2026-02-11T10:16:14.789408Z","end":"2026-02-11T10:16:15.687992Z","steps":["trace[683392980] 'agreement among raft nodes before linearized reading' (duration: 898.373686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.688066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.789395Z","time spent":"898.659462ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-11T10:16:15.687308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.750573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-11T10:16:15.689316Z","caller":"traceutil/trace.go:171","msg":"trace[678616532] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"499.7868ms","start":"2026-02-11T10:16:15.189517Z","end":"2026-02-11T10:16:15.689304Z","steps":["trace[678616532] 'agreement among raft nodes before linearized reading' (duration: 497.726881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.689351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:15.189504Z","time spent":"499.836202ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-11T10:16:15.689593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.010126ms","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-11T10:16:15.689635Z","caller":"traceutil/trace.go:171","msg":"trace[1348113543] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"100.171759ms","start":"2026-02-11T10:16:15.589457Z","end":"2026-02-11T10:16:15.689629Z","steps":["trace[1348113543] 'agreement among raft nodes before linearized reading' (duration: 100.081307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.688448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001794623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T10:16:15.689826Z","caller":"traceutil/trace.go:171","msg":"trace[1370447989] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:547; }","duration":"1.003222577s","start":"2026-02-11T10:16:14.686597Z","end":"2026-02-11T10:16:15.689819Z","steps":["trace[1370447989] 'agreement among raft nodes before linearized reading' (duration: 1.001808453s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.689884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.686569Z","time spent":"1.003307229s","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-02-11T10:16:15.688508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053979952s","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-11T10:16:15.690159Z","caller":"traceutil/trace.go:171","msg":"trace[1779272585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:547; }","duration":"1.055659261s","start":"2026-02-11T10:16:14.634493Z","end":"2026-02-11T10:16:15.690152Z","steps":["trace[1779272585] 'agreement among raft nodes before linearized reading' (duration: 1.053989742s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:15.690211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:14.634479Z","time spent":"1.055722494s","remote":"127.0.0.1:43810","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-11T10:16:16.027722Z","caller":"traceutil/trace.go:171","msg":"trace[803745950] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"245.11964ms","start":"2026-02-11T10:16:15.782567Z","end":"2026-02-11T10:16:16.027687Z","steps":["trace[803745950] 'read index received' (duration: 244.896804ms)","trace[803745950] 'applied index is now lower than readState.Index' (duration: 221.776µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:16.027828Z","caller":"traceutil/trace.go:171","msg":"trace[1749979916] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"289.711871ms","start":"2026-02-11T10:16:15.738106Z","end":"2026-02-11T10:16:16.027818Z","steps":["trace[1749979916] 'process raft request' (duration: 289.438984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:16.028032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.104334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-11T10:16:16.028074Z","caller":"traceutil/trace.go:171","msg":"trace[21508088] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"238.168616ms","start":"2026-02-11T10:16:15.789894Z","end":"2026-02-11T10:16:16.028063Z","steps":["trace[21508088] 'agreement among raft nodes before linearized reading' (duration: 238.042883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:16.02817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.602662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T10:16:16.028182Z","caller":"traceutil/trace.go:171","msg":"trace[1624524633] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:555; }","duration":"245.637872ms","start":"2026-02-11T10:16:15.78254Z","end":"2026-02-11T10:16:16.028178Z","steps":["trace[1624524633] 'agreement among raft nodes before linearized reading' (duration: 245.599482ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:17.41692Z","caller":"traceutil/trace.go:171","msg":"trace[258519936] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"319.968799ms","start":"2026-02-11T10:16:17.096936Z","end":"2026-02-11T10:16:17.416905Z","steps":["trace[258519936] 'process raft request' (duration: 319.641142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:17.417019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:17.096911Z","time spent":"320.066122ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:16:17.417199Z","caller":"traceutil/trace.go:171","msg":"trace[1872008292] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"240.296027ms","start":"2026-02-11T10:16:17.176896Z","end":"2026-02-11T10:16:17.417192Z","steps":["trace[1872008292] 'read index received' (duration: 239.748204ms)","trace[1872008292] 'applied index is now lower than readState.Index' (duration: 547.273µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:17.417237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.341168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:17.417251Z","caller":"traceutil/trace.go:171","msg":"trace[1686906701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"240.38487ms","start":"2026-02-11T10:16:17.176862Z","end":"2026-02-11T10:16:17.417246Z","steps":["trace[1686906701] 'agreement among raft nodes before linearized reading' (duration: 240.359789ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:17.612774Z","caller":"traceutil/trace.go:171","msg":"trace[1496554011] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"195.545044ms","start":"2026-02-11T10:16:17.417213Z","end":"2026-02-11T10:16:17.612758Z","steps":["trace[1496554011] 'read index received' (duration: 195.316048ms)","trace[1496554011] 'applied index is now lower than readState.Index' (duration: 228.376µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:17.612854Z","caller":"traceutil/trace.go:171","msg":"trace[1870906761] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"419.886282ms","start":"2026-02-11T10:16:17.192962Z","end":"2026-02-11T10:16:17.612848Z","steps":["trace[1870906761] 'process raft request' (duration: 419.623176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:17.612931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:17.192951Z","time spent":"419.917863ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T10:16:17.613052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.053695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:17.6131Z","caller":"traceutil/trace.go:171","msg":"trace[2033069154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"193.124927ms","start":"2026-02-11T10:16:17.419962Z","end":"2026-02-11T10:16:17.613087Z","steps":["trace[2033069154] 'agreement among raft nodes before linearized reading' (duration: 193.053385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:17.613253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.269956ms","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-11T10:16:17.613286Z","caller":"traceutil/trace.go:171","msg":"trace[254311117] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:564; }","duration":"424.313857ms","start":"2026-02-11T10:16:17.188965Z","end":"2026-02-11T10:16:17.613279Z","steps":["trace[254311117] 'agreement among raft nodes before linearized reading' (duration: 424.210225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:17.613304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:17.188959Z","time spent":"424.339778ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-11T10:16:23.679349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997258758,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:16:23.751395Z","caller":"traceutil/trace.go:171","msg":"trace[1566012278] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"572.403715ms","start":"2026-02-11T10:16:23.178972Z","end":"2026-02-11T10:16:23.751376Z","steps":["trace[1566012278] 'read index received' (duration: 572.262021ms)","trace[1566012278] 'applied index is now lower than readState.Index' (duration: 141.054µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:23.751476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.495867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:23.751496Z","caller":"traceutil/trace.go:171","msg":"trace[249986170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"572.565588ms","start":"2026-02-11T10:16:23.178923Z","end":"2026-02-11T10:16:23.751489Z","steps":["trace[249986170] 'agreement among raft nodes before linearized reading' (duration: 572.503777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:23.751518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:23.178905Z","time spent":"572.60792ms","remote":"127.0.0.1:43520","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-11T10:16:23.751659Z","caller":"traceutil/trace.go:171","msg":"trace[1696038784] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"993.845924ms","start":"2026-02-11T10:16:22.757806Z","end":"2026-02-11T10:16:23.751652Z","steps":["trace[1696038784] 'process raft request' (duration: 993.420394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:23.751715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:22.757786Z","time spent":"993.891596ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:16:31.167906Z","caller":"traceutil/trace.go:171","msg":"trace[1558089872] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"348.725071ms","start":"2026-02-11T10:16:30.819163Z","end":"2026-02-11T10:16:31.167888Z","steps":["trace[1558089872] 'process raft request' (duration: 348.607659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:31.168007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:30.819144Z","time spent":"348.805064ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4426,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:16:31.171499Z","caller":"traceutil/trace.go:171","msg":"trace[18655275] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"349.754746ms","start":"2026-02-11T10:16:30.821732Z","end":"2026-02-11T10:16:31.171486Z","steps":["trace[18655275] 'process raft request' (duration: 349.52018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:31.171567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:30.821725Z","time spent":"349.808847ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":614,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T10:16:31.171857Z","caller":"traceutil/trace.go:171","msg":"trace[460279458] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"350.055382ms","start":"2026-02-11T10:16:30.821793Z","end":"2026-02-11T10:16:31.171849Z","steps":["trace[460279458] 'process raft request' (duration: 349.51355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:31.171896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:30.82179Z","time spent":"350.083384ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:16:35.087304Z","caller":"traceutil/trace.go:171","msg":"trace[576490508] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"235.667628ms","start":"2026-02-11T10:16:34.851623Z","end":"2026-02-11T10:16:35.08729Z","steps":["trace[576490508] 'process raft request' (duration: 229.362238ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:35.32453Z","caller":"traceutil/trace.go:171","msg":"trace[992055861] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"146.235224ms","start":"2026-02-11T10:16:35.178274Z","end":"2026-02-11T10:16:35.324509Z","steps":["trace[992055861] 'read index received' (duration: 136.533713ms)","trace[992055861] 'applied index is now lower than readState.Index' (duration: 9.7006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:35.32465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.357336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:35.325203Z","caller":"traceutil/trace.go:171","msg":"trace[1102603479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"146.926279ms","start":"2026-02-11T10:16:35.178244Z","end":"2026-02-11T10:16:35.32517Z","steps":["trace[1102603479] 'agreement among raft nodes before linearized reading' (duration: 146.344185ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:35.3283Z","caller":"traceutil/trace.go:171","msg":"trace[1735046043] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"427.049263ms","start":"2026-02-11T10:16:34.90123Z","end":"2026-02-11T10:16:35.328279Z","steps":["trace[1735046043] 'process raft request' (duration: 413.614414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:35.328408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:34.901212Z","time spent":"427.142275ms","remote":"127.0.0.1:50526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T10:16:36.433124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997258932,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:16:36.55551Z","caller":"traceutil/trace.go:171","msg":"trace[253843493] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"636.085559ms","start":"2026-02-11T10:16:35.9194Z","end":"2026-02-11T10:16:36.555486Z","steps":["trace[253843493] 'process raft request' (duration: 635.976436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:36.555779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:35.919391Z","time spent":"636.311094ms","remote":"127.0.0.1:43810","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-11T10:16:37.616913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.201981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:16:37.616974Z","caller":"traceutil/trace.go:171","msg":"trace[54761106] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"1.684304641s","start":"2026-02-11T10:16:35.932659Z","end":"2026-02-11T10:16:37.616964Z","steps":["trace[54761106] 'read index received' (duration: 624.611396ms)","trace[54761106] 'applied index is now lower than readState.Index' (duration: 1.059692625s)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:37.617032Z","caller":"traceutil/trace.go:171","msg":"trace[1642539316] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.573381646s","start":"2026-02-11T10:16:36.043629Z","end":"2026-02-11T10:16:37.617011Z","steps":["trace[1642539316] 'process raft request' (duration: 1.096030221s)","trace[1642539316] 'compare' (duration: 476.905794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:37.617086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684422804s","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-11T10:16:37.617103Z","caller":"traceutil/trace.go:171","msg":"trace[413855791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"1.684449764s","start":"2026-02-11T10:16:35.932648Z","end":"2026-02-11T10:16:37.617097Z","steps":["trace[413855791] 'agreement among raft nodes before linearized reading' (duration: 1.684364992s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:37.617119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:35.932643Z","time spent":"1.684472805s","remote":"127.0.0.1:43810","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-11T10:16:37.61713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:36.043613Z","time spent":"1.573462649s","remote":"127.0.0.1:43810","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-11T10:16:37.617245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677574311s","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-11T10:16:37.617259Z","caller":"traceutil/trace.go:171","msg":"trace[275332448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.677606872s","start":"2026-02-11T10:16:35.939649Z","end":"2026-02-11T10:16:37.617255Z","steps":["trace[275332448] 'agreement among raft nodes before linearized reading' (duration: 1.677557141s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:37.617272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:35.939644Z","time spent":"1.677625682s","remote":"127.0.0.1:43810","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-11T10:16:37.617322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438511453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:37.617345Z","caller":"traceutil/trace.go:171","msg":"trace[892376159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"1.438550404s","start":"2026-02-11T10:16:36.178789Z","end":"2026-02-11T10:16:37.617339Z","steps":["trace[892376159] 'agreement among raft nodes before linearized reading' (duration: 1.438511433s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:37.617361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640819518s","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":"warn","ts":"2026-02-11T10:16:37.617362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:36.178779Z","time spent":"1.438579464s","remote":"127.0.0.1:43520","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-11T10:16:37.617375Z","caller":"traceutil/trace.go:171","msg":"trace[2139673007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.640849949s","start":"2026-02-11T10:16:35.976521Z","end":"2026-02-11T10:16:37.617371Z","steps":["trace[2139673007] 'agreement among raft nodes before linearized reading' (duration: 1.640805398s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:37.617389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:35.976517Z","time spent":"1.640869149s","remote":"127.0.0.1:43810","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":"warn","ts":"2026-02-11T10:16:37.617476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642735684s","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-11T10:16:37.617488Z","caller":"traceutil/trace.go:171","msg":"trace[115877089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.642757424s","start":"2026-02-11T10:16:35.974727Z","end":"2026-02-11T10:16:37.617484Z","steps":["trace[115877089] 'agreement among raft nodes before linearized reading' (duration: 1.642707273s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:37.617499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:35.974721Z","time spent":"1.642775175s","remote":"127.0.0.1:43810","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-11T10:16:38.13758Z","caller":"traceutil/trace.go:171","msg":"trace[957583055] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"514.5094ms","start":"2026-02-11T10:16:37.623042Z","end":"2026-02-11T10:16:38.137552Z","steps":["trace[957583055] 'process raft request' (duration: 416.40637ms)","trace[957583055] 'compare' (duration: 97.727751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:38.137759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:37.623033Z","time spent":"514.636354ms","remote":"127.0.0.1:43810","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-11T10:16:38.25598Z","caller":"traceutil/trace.go:171","msg":"trace[740279168] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"632.81365ms","start":"2026-02-11T10:16:37.623148Z","end":"2026-02-11T10:16:38.255962Z","steps":["trace[740279168] 'process raft request' (duration: 625.671371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:38.256064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:37.623144Z","time spent":"632.877772ms","remote":"127.0.0.1:43810","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-11T10:16:38.257382Z","caller":"traceutil/trace.go:171","msg":"trace[2045638917] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:697; }","duration":"629.441011ms","start":"2026-02-11T10:16:37.627924Z","end":"2026-02-11T10:16:38.257365Z","steps":["trace[2045638917] 'read index received' (duration: 411.533925ms)","trace[2045638917] 'applied index is now lower than readState.Index' (duration: 217.906506ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:38.257469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.526523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:38.257468Z","caller":"traceutil/trace.go:171","msg":"trace[1900591245] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"634.286615ms","start":"2026-02-11T10:16:37.623172Z","end":"2026-02-11T10:16:38.257459Z","steps":["trace[1900591245] 'process raft request' (duration: 632.599615ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:38.25749Z","caller":"traceutil/trace.go:171","msg":"trace[1431044800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"629.562444ms","start":"2026-02-11T10:16:37.627921Z","end":"2026-02-11T10:16:38.257484Z","steps":["trace[1431044800] 'agreement among raft nodes before linearized reading' (duration: 629.512592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:38.257514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:37.62317Z","time spent":"634.318936ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:16:38.257541Z","caller":"traceutil/trace.go:171","msg":"trace[2106172650] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"634.334007ms","start":"2026-02-11T10:16:37.623201Z","end":"2026-02-11T10:16:38.257535Z","steps":["trace[2106172650] 'process raft request' (duration: 634.115632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:38.257515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:37.627898Z","time spent":"629.612254ms","remote":"127.0.0.1:43520","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-11T10:16:38.257598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:37.623198Z","time spent":"634.359727ms","remote":"127.0.0.1:43810","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-11T10:16:39.123656Z","caller":"traceutil/trace.go:171","msg":"trace[1926447911] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"254.873124ms","start":"2026-02-11T10:16:38.868758Z","end":"2026-02-11T10:16:39.123631Z","steps":["trace[1926447911] 'process raft request' (duration: 254.750081ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:39.15394Z","caller":"traceutil/trace.go:171","msg":"trace[467169340] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"280.709307ms","start":"2026-02-11T10:16:38.873216Z","end":"2026-02-11T10:16:39.153925Z","steps":["trace[467169340] 'process raft request' (duration: 280.622435ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:39.306161Z","caller":"traceutil/trace.go:171","msg":"trace[565155921] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:715; }","duration":"129.555696ms","start":"2026-02-11T10:16:39.176584Z","end":"2026-02-11T10:16:39.30614Z","steps":["trace[565155921] 'read index received' (duration: 3.032072ms)","trace[565155921] 'applied index is now lower than readState.Index' (duration: 126.522704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:16:39.306353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.748931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:16:39.3069Z","caller":"traceutil/trace.go:171","msg":"trace[1469038478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"130.334585ms","start":"2026-02-11T10:16:39.176557Z","end":"2026-02-11T10:16:39.306891Z","steps":["trace[1469038478] 'agreement among raft nodes before linearized reading' (duration: 129.736351ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:39.306665Z","caller":"traceutil/trace.go:171","msg":"trace[211392056] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"146.11745ms","start":"2026-02-11T10:16:39.160533Z","end":"2026-02-11T10:16:39.30665Z","steps":["trace[211392056] 'process raft request' (duration: 145.202199ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:39.306814Z","caller":"traceutil/trace.go:171","msg":"trace[774649422] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"142.828132ms","start":"2026-02-11T10:16:39.163976Z","end":"2026-02-11T10:16:39.306804Z","steps":["trace[774649422] 'process raft request' (duration: 142.103225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.673774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997259072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:16:48.716307Z","caller":"traceutil/trace.go:171","msg":"trace[1507738374] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"653.383087ms","start":"2026-02-11T10:16:48.0629Z","end":"2026-02-11T10:16:48.716283Z","steps":["trace[1507738374] 'process raft request' (duration: 653.262744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.716453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:48.062879Z","time spent":"653.48574ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:16:48.738098Z","caller":"traceutil/trace.go:171","msg":"trace[1705276533] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"565.116501ms","start":"2026-02-11T10:16:48.172962Z","end":"2026-02-11T10:16:48.738078Z","steps":["trace[1705276533] 'read index received' (duration: 543.942038ms)","trace[1705276533] 'applied index is now lower than readState.Index' (duration: 21.173793ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:16:48.738185Z","caller":"traceutil/trace.go:171","msg":"trace[1132718685] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"166.190096ms","start":"2026-02-11T10:16:48.571986Z","end":"2026-02-11T10:16:48.738176Z","steps":["trace[1132718685] 'process raft request' (duration: 165.855808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.738354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.383417ms","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-11T10:16:48.738377Z","caller":"traceutil/trace.go:171","msg":"trace[298005658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"565.443499ms","start":"2026-02-11T10:16:48.172925Z","end":"2026-02-11T10:16:48.738369Z","steps":["trace[298005658] 'agreement among raft nodes before linearized reading' (duration: 565.340046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.738395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:48.172908Z","time spent":"565.482709ms","remote":"127.0.0.1:43810","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-11T10:16:48.738453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.111843ms","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-11T10:16:48.738481Z","caller":"traceutil/trace.go:171","msg":"trace[496028875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"443.172584ms","start":"2026-02-11T10:16:48.2953Z","end":"2026-02-11T10:16:48.738472Z","steps":["trace[496028875] 'agreement among raft nodes before linearized reading' (duration: 443.086862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.738508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:48.295284Z","time spent":"443.218906ms","remote":"127.0.0.1:43810","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":"warn","ts":"2026-02-11T10:16:48.738661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.700688ms","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-11T10:16:48.738707Z","caller":"traceutil/trace.go:171","msg":"trace[1929198714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"438.78978ms","start":"2026-02-11T10:16:48.299911Z","end":"2026-02-11T10:16:48.738701Z","steps":["trace[1929198714] 'agreement among raft nodes before linearized reading' (duration: 438.707329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.738726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:48.299895Z","time spent":"438.825611ms","remote":"127.0.0.1:43810","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-11T10:16:48.738758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.249898ms","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-11T10:16:48.738778Z","caller":"traceutil/trace.go:171","msg":"trace[1419064120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"438.283128ms","start":"2026-02-11T10:16:48.300489Z","end":"2026-02-11T10:16:48.738772Z","steps":["trace[1419064120] 'agreement among raft nodes before linearized reading' (duration: 438.231777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:16:48.738795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:16:48.300482Z","time spent":"438.30853ms","remote":"127.0.0.1:43810","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-11T10:16:48.914443Z","caller":"traceutil/trace.go:171","msg":"trace[24601170] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"170.543351ms","start":"2026-02-11T10:16:48.743884Z","end":"2026-02-11T10:16:48.914427Z","steps":["trace[24601170] 'process raft request' (duration: 170.454849ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:48.943601Z","caller":"traceutil/trace.go:171","msg":"trace[448974569] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"195.320059ms","start":"2026-02-11T10:16:48.748262Z","end":"2026-02-11T10:16:48.943582Z","steps":["trace[448974569] 'process raft request' (duration: 195.294538ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:48.943933Z","caller":"traceutil/trace.go:171","msg":"trace[859345257] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"197.505041ms","start":"2026-02-11T10:16:48.74642Z","end":"2026-02-11T10:16:48.943925Z","steps":["trace[859345257] 'process raft request' (duration: 197.000919ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:48.944013Z","caller":"traceutil/trace.go:171","msg":"trace[1309131101] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"197.126342ms","start":"2026-02-11T10:16:48.746881Z","end":"2026-02-11T10:16:48.944007Z","steps":["trace[1309131101] 'process raft request' (duration: 196.62883ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:16:48.944078Z","caller":"traceutil/trace.go:171","msg":"trace[1120036281] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"195.854591ms","start":"2026-02-11T10:16:48.748218Z","end":"2026-02-11T10:16:48.944073Z","steps":["trace[1120036281] 'process raft request' (duration: 195.316698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:01.50512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997259176,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:17:01.597849Z","caller":"traceutil/trace.go:171","msg":"trace[1312152075] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"592.914483ms","start":"2026-02-11T10:17:01.004919Z","end":"2026-02-11T10:17:01.597834Z","steps":["trace[1312152075] 'read index received' (duration: 592.78583ms)","trace[1312152075] 'applied index is now lower than readState.Index' (duration: 127.933µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:01.598032Z","caller":"traceutil/trace.go:171","msg":"trace[1490367433] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"597.122862ms","start":"2026-02-11T10:17:01.000902Z","end":"2026-02-11T10:17:01.598025Z","steps":["trace[1490367433] 'process raft request' (duration: 596.841295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:01.598101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:01.000893Z","time spent":"597.158353ms","remote":"127.0.0.1:43810","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-11T10:17:01.598211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.421563ms","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-11T10:17:01.598275Z","caller":"traceutil/trace.go:171","msg":"trace[1979117766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"590.506776ms","start":"2026-02-11T10:17:01.00775Z","end":"2026-02-11T10:17:01.598257Z","steps":["trace[1979117766] 'agreement among raft nodes before linearized reading' (duration: 590.291371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:01.598316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:01.007746Z","time spent":"590.562627ms","remote":"127.0.0.1:43810","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-11T10:17:01.598356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.59905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:01.598405Z","caller":"traceutil/trace.go:171","msg":"trace[790801305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"420.659421ms","start":"2026-02-11T10:17:01.177715Z","end":"2026-02-11T10:17:01.598374Z","steps":["trace[790801305] 'agreement among raft nodes before linearized reading' (duration: 420.620271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:01.598425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:01.177689Z","time spent":"420.731503ms","remote":"127.0.0.1:43520","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-11T10:17:01.598574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.338261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-11T10:17:01.598605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.681941ms","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-11T10:17:01.598606Z","caller":"traceutil/trace.go:171","msg":"trace[1360473141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"590.380452ms","start":"2026-02-11T10:17:01.008217Z","end":"2026-02-11T10:17:01.598598Z","steps":["trace[1360473141] 'agreement among raft nodes before linearized reading' (duration: 590.28668ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:01.598628Z","caller":"traceutil/trace.go:171","msg":"trace[237211593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"593.714951ms","start":"2026-02-11T10:17:01.004908Z","end":"2026-02-11T10:17:01.598623Z","steps":["trace[237211593] 'agreement among raft nodes before linearized reading' (duration: 593.586718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:01.598634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:01.008213Z","time spent":"590.415583ms","remote":"127.0.0.1:43810","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-11T10:17:01.598647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:01.004903Z","time spent":"593.739902ms","remote":"127.0.0.1:43810","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":"warn","ts":"2026-02-11T10:17:03.294721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.243443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:03.294775Z","caller":"traceutil/trace.go:171","msg":"trace[1768893249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"115.347766ms","start":"2026-02-11T10:17:03.179413Z","end":"2026-02-11T10:17:03.294761Z","steps":["trace[1768893249] 'range keys from in-memory index tree' (duration: 115.156091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:04.113713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997259190,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:17:04.215281Z","caller":"traceutil/trace.go:171","msg":"trace[1177627292] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"601.860682ms","start":"2026-02-11T10:17:03.613399Z","end":"2026-02-11T10:17:04.21526Z","steps":["trace[1177627292] 'read index received' (duration: 601.661558ms)","trace[1177627292] 'applied index is now lower than readState.Index' (duration: 198.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:04.215842Z","caller":"traceutil/trace.go:171","msg":"trace[1248050790] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"608.958388ms","start":"2026-02-11T10:17:03.606871Z","end":"2026-02-11T10:17:04.215829Z","steps":["trace[1248050790] 'process raft request' (duration: 608.271672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:04.216062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:03.60686Z","time spent":"609.094231ms","remote":"127.0.0.1:43810","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-11T10:17:04.216968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.558722ms","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-11T10:17:04.217105Z","caller":"traceutil/trace.go:171","msg":"trace[1634103448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"603.708355ms","start":"2026-02-11T10:17:03.613384Z","end":"2026-02-11T10:17:04.217092Z","steps":["trace[1634103448] 'agreement among raft nodes before linearized reading' (duration: 603.455849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:04.21722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:03.613377Z","time spent":"603.811417ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T10:17:04.217585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.788524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T10:17:04.217686Z","caller":"traceutil/trace.go:171","msg":"trace[1792938616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"602.901987ms","start":"2026-02-11T10:17:03.614773Z","end":"2026-02-11T10:17:04.217675Z","steps":["trace[1792938616] 'agreement among raft nodes before linearized reading' (duration: 602.703212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:04.217928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:03.614765Z","time spent":"603.149722ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T10:17:04.21844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.830315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-11T10:17:04.219524Z","caller":"traceutil/trace.go:171","msg":"trace[963899115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"603.890639ms","start":"2026-02-11T10:17:03.615585Z","end":"2026-02-11T10:17:04.219475Z","steps":["trace[963899115] 'agreement among raft nodes before linearized reading' (duration: 602.734983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:04.219745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:03.615576Z","time spent":"604.154025ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T10:17:04.6637Z","caller":"traceutil/trace.go:171","msg":"trace[1572305794] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"436.794399ms","start":"2026-02-11T10:17:04.226834Z","end":"2026-02-11T10:17:04.663629Z","steps":["trace[1572305794] 'process raft request' (duration: 436.165704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:04.663827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:04.226815Z","time spent":"436.942012ms","remote":"127.0.0.1:43810","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-11T10:17:05.189598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997259193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T10:17:05.435884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.616166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:05.436207Z","caller":"traceutil/trace.go:171","msg":"trace[218596881] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"747.278168ms","start":"2026-02-11T10:17:04.688911Z","end":"2026-02-11T10:17:05.43619Z","steps":["trace[218596881] 'read index received' (duration: 511.204101ms)","trace[218596881] 'applied index is now lower than readState.Index' (duration: 236.073037ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:05.436832Z","caller":"traceutil/trace.go:171","msg":"trace[625333485] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.209808445s","start":"2026-02-11T10:17:04.227004Z","end":"2026-02-11T10:17:05.436812Z","steps":["trace[625333485] 'process raft request' (duration: 973.170296ms)","trace[625333485] 'compare' (duration: 235.37642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:05.437194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:04.226997Z","time spent":"1.210104082s","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:17:05.437631Z","caller":"traceutil/trace.go:171","msg":"trace[1996781519] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.209786775s","start":"2026-02-11T10:17:04.227811Z","end":"2026-02-11T10:17:05.437597Z","steps":["trace[1996781519] 'process raft request' (duration: 1.20830372s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:05.437797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:04.227803Z","time spent":"1.209905808s","remote":"127.0.0.1:43810","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-11T10:17:05.438081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.163122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T10:17:05.438125Z","caller":"traceutil/trace.go:171","msg":"trace[1587792155] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:795; }","duration":"749.245984ms","start":"2026-02-11T10:17:04.688868Z","end":"2026-02-11T10:17:05.438114Z","steps":["trace[1587792155] 'agreement among raft nodes before linearized reading' (duration: 749.07806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:05.438163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:04.688851Z","time spent":"749.301295ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-02-11T10:17:05.439368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.841535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:05.439535Z","caller":"traceutil/trace.go:171","msg":"trace[1014534869] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:795; }","duration":"405.063989ms","start":"2026-02-11T10:17:05.034456Z","end":"2026-02-11T10:17:05.43952Z","steps":["trace[1014534869] 'agreement among raft nodes before linearized reading' (duration: 404.819494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:05.439587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:05.034436Z","time spent":"405.138862ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-11T10:17:05.441008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.247395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:05.441062Z","caller":"traceutil/trace.go:171","msg":"trace[297625058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"262.340047ms","start":"2026-02-11T10:17:05.178709Z","end":"2026-02-11T10:17:05.441049Z","steps":["trace[297625058] 'agreement among raft nodes before linearized reading' (duration: 262.236384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:05.44255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.871405ms","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-11T10:17:05.442609Z","caller":"traceutil/trace.go:171","msg":"trace[2027459585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"407.965568ms","start":"2026-02-11T10:17:05.034618Z","end":"2026-02-11T10:17:05.442584Z","steps":["trace[2027459585] 'agreement among raft nodes before linearized reading' (duration: 407.818894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:05.443339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:05.034612Z","time spent":"408.707475ms","remote":"127.0.0.1:43810","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-11T10:17:05.708816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.863042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:05.709985Z","caller":"traceutil/trace.go:171","msg":"trace[763103967] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"183.994084ms","start":"2026-02-11T10:17:05.525978Z","end":"2026-02-11T10:17:05.709972Z","steps":["trace[763103967] 'process raft request' (duration: 183.939742ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:05.710183Z","caller":"traceutil/trace.go:171","msg":"trace[1821338815] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"261.089108ms","start":"2026-02-11T10:17:05.449084Z","end":"2026-02-11T10:17:05.710174Z","steps":["trace[1821338815] 'process raft request' (duration: 93.783043ms)","trace[1821338815] 'compare' (duration: 165.738389ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:18.427712Z","caller":"traceutil/trace.go:171","msg":"trace[1260281138] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"114.749392ms","start":"2026-02-11T10:17:18.312947Z","end":"2026-02-11T10:17:18.427696Z","steps":["trace[1260281138] 'process raft request' (duration: 114.65632ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:18.429534Z","caller":"traceutil/trace.go:171","msg":"trace[1275216378] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"101.955824ms","start":"2026-02-11T10:17:18.32756Z","end":"2026-02-11T10:17:18.429515Z","steps":["trace[1275216378] 'process raft request' (duration: 101.76539ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:20.568871Z","caller":"traceutil/trace.go:171","msg":"trace[331580158] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"135.560216ms","start":"2026-02-11T10:17:20.433294Z","end":"2026-02-11T10:17:20.568854Z","steps":["trace[331580158] 'process raft request' (duration: 135.453063ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:22.193538Z","caller":"traceutil/trace.go:171","msg":"trace[1822866868] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"657.413516ms","start":"2026-02-11T10:17:21.536109Z","end":"2026-02-11T10:17:22.193523Z","steps":["trace[1822866868] 'process raft request' (duration: 657.323054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:22.193634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:21.536089Z","time spent":"657.488217ms","remote":"127.0.0.1:43810","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-11T10:17:22.251431Z","caller":"traceutil/trace.go:171","msg":"trace[1081852862] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"408.103301ms","start":"2026-02-11T10:17:21.843315Z","end":"2026-02-11T10:17:22.251418Z","steps":["trace[1081852862] 'read index received' (duration: 350.52783ms)","trace[1081852862] 'applied index is now lower than readState.Index' (duration: 57.574901ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:22.251647Z","caller":"traceutil/trace.go:171","msg":"trace[1294525978] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"713.728677ms","start":"2026-02-11T10:17:21.537911Z","end":"2026-02-11T10:17:22.25164Z","steps":["trace[1294525978] 'process raft request' (duration: 713.392619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:22.251724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:21.537898Z","time spent":"713.763727ms","remote":"127.0.0.1:43810","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-11T10:17:22.251958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.652194ms","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-11T10:17:22.25198Z","caller":"traceutil/trace.go:171","msg":"trace[1166291780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"408.672065ms","start":"2026-02-11T10:17:21.843301Z","end":"2026-02-11T10:17:22.251973Z","steps":["trace[1166291780] 'agreement among raft nodes before linearized reading' (duration: 408.523151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:22.251992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:21.843232Z","time spent":"408.757056ms","remote":"127.0.0.1:43810","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-11T10:17:22.361621Z","caller":"traceutil/trace.go:171","msg":"trace[1714108536] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"106.723825ms","start":"2026-02-11T10:17:22.254876Z","end":"2026-02-11T10:17:22.3616Z","steps":["trace[1714108536] 'process raft request' (duration: 39.07356ms)","trace[1714108536] 'compare' (duration: 67.554253ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:22.742353Z","caller":"traceutil/trace.go:171","msg":"trace[1595727329] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"167.993531ms","start":"2026-02-11T10:17:22.574344Z","end":"2026-02-11T10:17:22.742337Z","steps":["trace[1595727329] 'process raft request' (duration: 167.891058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:23.296033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.976487ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:23.296125Z","caller":"traceutil/trace.go:171","msg":"trace[673480284] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:894; }","duration":"420.085039ms","start":"2026-02-11T10:17:22.876027Z","end":"2026-02-11T10:17:23.296112Z","steps":["trace[673480284] 'range keys from in-memory index tree' (duration: 419.961997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:23.296552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.566979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:23.296635Z","caller":"traceutil/trace.go:171","msg":"trace[445646368] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"117.440334ms","start":"2026-02-11T10:17:23.179187Z","end":"2026-02-11T10:17:23.296627Z","steps":["trace[445646368] 'read index received' (duration: 47.111µs)","trace[445646368] 'applied index is now lower than readState.Index' (duration: 117.392523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:23.296684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.502726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:23.2967Z","caller":"traceutil/trace.go:171","msg":"trace[1722212219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"117.546347ms","start":"2026-02-11T10:17:23.179148Z","end":"2026-02-11T10:17:23.296694Z","steps":["trace[1722212219] 'agreement among raft nodes before linearized reading' (duration: 117.501746ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:23.296895Z","caller":"traceutil/trace.go:171","msg":"trace[1415379639] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"548.611352ms","start":"2026-02-11T10:17:22.748275Z","end":"2026-02-11T10:17:23.296887Z","steps":["trace[1415379639] 'process raft request' (duration: 283.698635ms)","trace[1415379639] 'compare' (duration: 264.296013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:23.296935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:22.748268Z","time spent":"548.645073ms","remote":"127.0.0.1:43810","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-11T10:17:24.351479Z","caller":"traceutil/trace.go:171","msg":"trace[1673533186] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"119.194285ms","start":"2026-02-11T10:17:24.232271Z","end":"2026-02-11T10:17:24.351466Z","steps":["trace[1673533186] 'process raft request' (duration: 119.014901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:24.586988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.015722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:24.587049Z","caller":"traceutil/trace.go:171","msg":"trace[1065195022] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"191.15916ms","start":"2026-02-11T10:17:24.39588Z","end":"2026-02-11T10:17:24.587039Z","steps":["trace[1065195022] 'process raft request' (duration: 86.173136ms)","trace[1065195022] 'compare' (duration: 103.94014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:24.790712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.53164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T10:17:24.790766Z","caller":"traceutil/trace.go:171","msg":"trace[627023035] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:898; }","duration":"100.626563ms","start":"2026-02-11T10:17:24.690126Z","end":"2026-02-11T10:17:24.790753Z","steps":["trace[627023035] 'range keys from in-memory index tree' (duration: 100.407987ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:25.269589Z","caller":"traceutil/trace.go:171","msg":"trace[1005489113] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"446.633899ms","start":"2026-02-11T10:17:24.822935Z","end":"2026-02-11T10:17:25.269569Z","steps":["trace[1005489113] 'process raft request' (duration: 359.835568ms)","trace[1005489113] 'compare' (duration: 86.613316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:25.26969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:24.822922Z","time spent":"446.71612ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T10:17:27.989308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997259468,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:17:28.152361Z","caller":"traceutil/trace.go:171","msg":"trace[1849720931] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"664.02011ms","start":"2026-02-11T10:17:27.488326Z","end":"2026-02-11T10:17:28.152346Z","steps":["trace[1849720931] 'read index received' (duration: 663.903107ms)","trace[1849720931] 'applied index is now lower than readState.Index' (duration: 116.483µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:28.152431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.091701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:28.152447Z","caller":"traceutil/trace.go:171","msg":"trace[371398392] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:906; }","duration":"664.121072ms","start":"2026-02-11T10:17:27.488321Z","end":"2026-02-11T10:17:28.152442Z","steps":["trace[371398392] 'agreement among raft nodes before linearized reading' (duration: 664.079831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:28.152465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:27.48828Z","time spent":"664.179213ms","remote":"127.0.0.1:43858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-11T10:17:28.152524Z","caller":"traceutil/trace.go:171","msg":"trace[1002512343] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"773.094138ms","start":"2026-02-11T10:17:27.379414Z","end":"2026-02-11T10:17:28.152508Z","steps":["trace[1002512343] 'process raft request' (duration: 772.859773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:28.152606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:27.379404Z","time spent":"773.15363ms","remote":"127.0.0.1:43810","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-11T10:17:28.543376Z","caller":"traceutil/trace.go:171","msg":"trace[417606061] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"100.640543ms","start":"2026-02-11T10:17:28.442711Z","end":"2026-02-11T10:17:28.543352Z","steps":["trace[417606061] 'process raft request' (duration: 62.448673ms)","trace[417606061] 'compare' (duration: 38.097338ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:28.724026Z","caller":"traceutil/trace.go:171","msg":"trace[1192286050] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"127.347335ms","start":"2026-02-11T10:17:28.596653Z","end":"2026-02-11T10:17:28.724001Z","steps":["trace[1192286050] 'process raft request' (duration: 116.687096ms)","trace[1192286050] 'compare' (duration: 10.30979ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:34.943716Z","caller":"traceutil/trace.go:171","msg":"trace[523196945] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"256.442321ms","start":"2026-02-11T10:17:34.687259Z","end":"2026-02-11T10:17:34.943701Z","steps":["trace[523196945] 'read index received' (duration: 175.893095ms)","trace[523196945] 'applied index is now lower than readState.Index' (duration: 80.548656ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:34.943869Z","caller":"traceutil/trace.go:171","msg":"trace[363782694] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"382.185207ms","start":"2026-02-11T10:17:34.561677Z","end":"2026-02-11T10:17:34.943863Z","steps":["trace[363782694] 'process raft request' (duration: 301.457628ms)","trace[363782694] 'compare' (duration: 80.297749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:34.943935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:34.561667Z","time spent":"382.215438ms","remote":"127.0.0.1:43810","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-11T10:17:34.944056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.797949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T10:17:34.944091Z","caller":"traceutil/trace.go:171","msg":"trace[1308085616] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:925; }","duration":"256.871161ms","start":"2026-02-11T10:17:34.687212Z","end":"2026-02-11T10:17:34.944083Z","steps":["trace[1308085616] 'agreement among raft nodes before linearized reading' (duration: 256.816839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:34.944237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.607514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T10:17:34.944255Z","caller":"traceutil/trace.go:171","msg":"trace[1394595722] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:925; }","duration":"253.667546ms","start":"2026-02-11T10:17:34.690583Z","end":"2026-02-11T10:17:34.944251Z","steps":["trace[1394595722] 'agreement among raft nodes before linearized reading' (duration: 253.606574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:34.944436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.954515ms","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-11T10:17:34.944456Z","caller":"traceutil/trace.go:171","msg":"trace[583120610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"205.995716ms","start":"2026-02-11T10:17:34.738456Z","end":"2026-02-11T10:17:34.944452Z","steps":["trace[583120610] 'agreement among raft nodes before linearized reading' (duration: 205.953395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:35.669945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.280067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:35.670136Z","caller":"traceutil/trace.go:171","msg":"trace[1153233515] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:966; }","duration":"493.640193ms","start":"2026-02-11T10:17:35.176485Z","end":"2026-02-11T10:17:35.670125Z","steps":["trace[1153233515] 'read index received' (duration: 176.169162ms)","trace[1153233515] 'applied index is now lower than readState.Index' (duration: 317.470171ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:35.670257Z","caller":"traceutil/trace.go:171","msg":"trace[462650662] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"720.877253ms","start":"2026-02-11T10:17:34.949371Z","end":"2026-02-11T10:17:35.670248Z","steps":["trace[462650662] 'process raft request' (duration: 199.247439ms)","trace[462650662] 'compare' (duration: 521.168883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:35.670287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:34.949359Z","time spent":"720.911704ms","remote":"127.0.0.1:43810","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-11T10:17:35.670375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:34.949552Z","time spent":"720.821882ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T10:17:35.670444Z","caller":"traceutil/trace.go:171","msg":"trace[1412231701] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"720.833753ms","start":"2026-02-11T10:17:34.949605Z","end":"2026-02-11T10:17:35.670439Z","steps":["trace[1412231701] 'process raft request' (duration: 720.476324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:35.670475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:34.949601Z","time spent":"720.860673ms","remote":"127.0.0.1:43810","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-11T10:17:35.670541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.055773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:35.670554Z","caller":"traceutil/trace.go:171","msg":"trace[1946862427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"494.093133ms","start":"2026-02-11T10:17:35.176456Z","end":"2026-02-11T10:17:35.670549Z","steps":["trace[1946862427] 'agreement among raft nodes before linearized reading' (duration: 494.070393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:35.670569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:35.176442Z","time spent":"494.124445ms","remote":"127.0.0.1:43520","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-11T10:17:35.670638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.203757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:35.670649Z","caller":"traceutil/trace.go:171","msg":"trace[1099193552] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:927; }","duration":"464.238168ms","start":"2026-02-11T10:17:35.206407Z","end":"2026-02-11T10:17:35.670646Z","steps":["trace[1099193552] 'agreement among raft nodes before linearized reading' (duration: 464.217448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:35.67066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:35.206394Z","time spent":"464.263049ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T10:17:35.670789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.390345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:35.670801Z","caller":"traceutil/trace.go:171","msg":"trace[344801123] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:927; }","duration":"416.403875ms","start":"2026-02-11T10:17:35.254394Z","end":"2026-02-11T10:17:35.670798Z","steps":["trace[344801123] 'agreement among raft nodes before linearized reading' (duration: 416.383924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:35.670815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:35.25436Z","time spent":"416.449566ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T10:17:36.333168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:35.889451Z","time spent":"443.71184ms","remote":"127.0.0.1:50520","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T10:17:36.333753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.89938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:36.333794Z","caller":"traceutil/trace.go:171","msg":"trace[901801621] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"660.966148ms","start":"2026-02-11T10:17:35.672818Z","end":"2026-02-11T10:17:36.333784Z","steps":["trace[901801621] 'read index received' (duration: 187.944645ms)","trace[901801621] 'applied index is now lower than readState.Index' (duration: 473.020793ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:36.333846Z","caller":"traceutil/trace.go:171","msg":"trace[56877369] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"661.375517ms","start":"2026-02-11T10:17:35.672463Z","end":"2026-02-11T10:17:36.333839Z","steps":["trace[56877369] 'process raft request' (duration: 188.336444ms)","trace[56877369] 'compare' (duration: 472.310736ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:36.333899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:35.672453Z","time spent":"661.428349ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T10:17:36.334049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.203714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:36.33407Z","caller":"traceutil/trace.go:171","msg":"trace[236053530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"661.259715ms","start":"2026-02-11T10:17:35.672804Z","end":"2026-02-11T10:17:36.334063Z","steps":["trace[236053530] 'agreement among raft nodes before linearized reading' (duration: 661.182053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:36.334089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:35.672797Z","time spent":"661.289116ms","remote":"127.0.0.1:43530","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-11T10:17:36.334215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.430571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-11T10:17:36.334232Z","caller":"traceutil/trace.go:171","msg":"trace[120477328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"104.482192ms","start":"2026-02-11T10:17:36.229745Z","end":"2026-02-11T10:17:36.334227Z","steps":["trace[120477328] 'agreement among raft nodes before linearized reading' (duration: 104.419761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:36.744628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.791496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:36.744701Z","caller":"traceutil/trace.go:171","msg":"trace[1173605593] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"397.506145ms","start":"2026-02-11T10:17:36.347175Z","end":"2026-02-11T10:17:36.744681Z","steps":["trace[1173605593] 'read index received' (duration: 226.532384ms)","trace[1173605593] 'applied index is now lower than readState.Index' (duration: 170.973101ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:36.744841Z","caller":"traceutil/trace.go:171","msg":"trace[777687150] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"402.784757ms","start":"2026-02-11T10:17:36.342049Z","end":"2026-02-11T10:17:36.744834Z","steps":["trace[777687150] 'process raft request' (duration: 231.723455ms)","trace[777687150] 'compare' (duration: 170.705934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:36.74487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:36.342039Z","time spent":"402.816239ms","remote":"127.0.0.1:43810","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-11T10:17:36.744963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.785941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:36.744976Z","caller":"traceutil/trace.go:171","msg":"trace[2142964582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"397.811772ms","start":"2026-02-11T10:17:36.347159Z","end":"2026-02-11T10:17:36.744971Z","steps":["trace[2142964582] 'agreement among raft nodes before linearized reading' (duration: 397.787112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:36.744989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:36.347153Z","time spent":"397.834273ms","remote":"127.0.0.1:43520","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-11T10:17:36.745099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.936913ms","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-11T10:17:36.745111Z","caller":"traceutil/trace.go:171","msg":"trace[1038479431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"223.978335ms","start":"2026-02-11T10:17:36.521129Z","end":"2026-02-11T10:17:36.745108Z","steps":["trace[1038479431] 'agreement among raft nodes before linearized reading' (duration: 223.927473ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:37.118513Z","caller":"traceutil/trace.go:171","msg":"trace[1537089744] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"365.267694ms","start":"2026-02-11T10:17:36.753219Z","end":"2026-02-11T10:17:37.118486Z","steps":["trace[1537089744] 'process raft request' (duration: 364.896435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:37.11865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:36.753206Z","time spent":"365.373266ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:17:37.118983Z","caller":"traceutil/trace.go:171","msg":"trace[1323819832] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"169.111507ms","start":"2026-02-11T10:17:36.949198Z","end":"2026-02-11T10:17:37.11831Z","steps":["trace[1323819832] 'read index received' (duration: 168.835871ms)","trace[1323819832] 'applied index is now lower than readState.Index' (duration: 274.526µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:37.119107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.916936ms","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-11T10:17:37.119134Z","caller":"traceutil/trace.go:171","msg":"trace[48147765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"169.982877ms","start":"2026-02-11T10:17:36.949142Z","end":"2026-02-11T10:17:37.119125Z","steps":["trace[48147765] 'agreement among raft nodes before linearized reading' (duration: 169.872014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:38.109569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.936158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:38.109749Z","caller":"traceutil/trace.go:171","msg":"trace[272167543] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"986.644659ms","start":"2026-02-11T10:17:37.123081Z","end":"2026-02-11T10:17:38.109725Z","steps":["trace[272167543] 'process raft request' (duration: 164.284555ms)","trace[272167543] 'compare' (duration: 819.809935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:38.109882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:37.123061Z","time spent":"986.752662ms","remote":"127.0.0.1:43810","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-11T10:17:38.109922Z","caller":"traceutil/trace.go:171","msg":"trace[725636956] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"433.968553ms","start":"2026-02-11T10:17:37.675709Z","end":"2026-02-11T10:17:38.109677Z","steps":["trace[725636956] 'read index received' (duration: 22.05µs)","trace[725636956] 'applied index is now lower than readState.Index' (duration: 433.944713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:38.110122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.300181ms","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-11T10:17:38.110145Z","caller":"traceutil/trace.go:171","msg":"trace[1643858115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"434.334912ms","start":"2026-02-11T10:17:37.675804Z","end":"2026-02-11T10:17:38.110138Z","steps":["trace[1643858115] 'agreement among raft nodes before linearized reading' (duration: 434.25126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:38.110145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.426614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-11T10:17:38.110162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:37.675799Z","time spent":"434.358792ms","remote":"127.0.0.1:43810","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-11T10:17:38.110171Z","caller":"traceutil/trace.go:171","msg":"trace[948744805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"434.457904ms","start":"2026-02-11T10:17:37.675705Z","end":"2026-02-11T10:17:38.110163Z","steps":["trace[948744805] 'agreement among raft nodes before linearized reading' (duration: 434.314881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:38.110197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:37.675667Z","time spent":"434.524206ms","remote":"127.0.0.1:43810","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":"info","ts":"2026-02-11T10:17:40.094213Z","caller":"traceutil/trace.go:171","msg":"trace[1856240178] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"169.387554ms","start":"2026-02-11T10:17:39.924802Z","end":"2026-02-11T10:17:40.09419Z","steps":["trace[1856240178] 'process raft request' (duration: 169.22843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:40.750653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.819494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:40.750713Z","caller":"traceutil/trace.go:171","msg":"trace[237882542] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"586.385682ms","start":"2026-02-11T10:17:40.164317Z","end":"2026-02-11T10:17:40.750703Z","steps":["trace[237882542] 'read index received' (duration: 291.367624ms)","trace[237882542] 'applied index is now lower than readState.Index' (duration: 295.016098ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:40.75082Z","caller":"traceutil/trace.go:171","msg":"trace[875371640] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"630.820557ms","start":"2026-02-11T10:17:40.119994Z","end":"2026-02-11T10:17:40.750814Z","steps":["trace[875371640] 'process raft request' (duration: 335.784678ms)","trace[875371640] 'compare' (duration: 294.570758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:40.750851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:40.119976Z","time spent":"630.859537ms","remote":"127.0.0.1:43810","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-11T10:17:40.751013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.69373ms","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-11T10:17:40.751028Z","caller":"traceutil/trace.go:171","msg":"trace[572176366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"586.731511ms","start":"2026-02-11T10:17:40.16429Z","end":"2026-02-11T10:17:40.751022Z","steps":["trace[572176366] 'agreement among raft nodes before linearized reading' (duration: 586.672249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:40.751049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:40.164276Z","time spent":"586.762831ms","remote":"127.0.0.1:43810","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-11T10:17:41.364319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.304471ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T10:17:41.364478Z","caller":"traceutil/trace.go:171","msg":"trace[535933089] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"613.740258ms","start":"2026-02-11T10:17:40.750726Z","end":"2026-02-11T10:17:41.364466Z","steps":["trace[535933089] 'read index received' (duration: 75.6163ms)","trace[535933089] 'applied index is now lower than readState.Index' (duration: 538.123138ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:41.364728Z","caller":"traceutil/trace.go:171","msg":"trace[829923570] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"609.883829ms","start":"2026-02-11T10:17:40.754834Z","end":"2026-02-11T10:17:41.364718Z","steps":["trace[829923570] 'process raft request' (duration: 609.558591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:41.364804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:40.754797Z","time spent":"609.951009ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T10:17:41.687099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.079364ms","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-11T10:17:41.687188Z","caller":"traceutil/trace.go:171","msg":"trace[1458904927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"903.203787ms","start":"2026-02-11T10:17:40.783964Z","end":"2026-02-11T10:17:41.687168Z","steps":["trace[1458904927] 'agreement among raft nodes before linearized reading' (duration: 580.740249ms)","trace[1458904927] 'range keys from in-memory index tree' (duration: 322.267412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:41.68723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:40.783949Z","time spent":"903.272539ms","remote":"127.0.0.1:43810","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-11T10:17:41.687444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508632153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:41.687472Z","caller":"traceutil/trace.go:171","msg":"trace[2012763511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"1.508678814s","start":"2026-02-11T10:17:40.178784Z","end":"2026-02-11T10:17:41.687462Z","steps":["trace[2012763511] 'agreement among raft nodes before linearized reading' (duration: 1.185880009s)","trace[2012763511] 'range keys from in-memory index tree' (duration: 322.728573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:41.687498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:40.178773Z","time spent":"1.508719874s","remote":"127.0.0.1:43520","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-11T10:17:41.687662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522636369s","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-11T10:17:41.687691Z","caller":"traceutil/trace.go:171","msg":"trace[1478598939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:939; }","duration":"1.52268017s","start":"2026-02-11T10:17:40.165Z","end":"2026-02-11T10:17:41.68768Z","steps":["trace[1478598939] 'agreement among raft nodes before linearized reading' (duration: 1.19967317s)","trace[1478598939] 'range keys from in-memory index tree' (duration: 322.936628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:41.687717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:40.164991Z","time spent":"1.52272019s","remote":"127.0.0.1:43810","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-11T10:17:41.687936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.952818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T10:17:41.687964Z","caller":"traceutil/trace.go:171","msg":"trace[1307294995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"515.01313ms","start":"2026-02-11T10:17:41.172942Z","end":"2026-02-11T10:17:41.687955Z","steps":["trace[1307294995] 'agreement among raft nodes before linearized reading' (duration: 191.98343ms)","trace[1307294995] 'range keys from in-memory index tree' (duration: 322.963759ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:41.687994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:41.172919Z","time spent":"515.069082ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-11T10:17:41.830387Z","caller":"traceutil/trace.go:171","msg":"trace[592706962] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"128.265077ms","start":"2026-02-11T10:17:41.702101Z","end":"2026-02-11T10:17:41.830366Z","steps":["trace[592706962] 'process raft request' (duration: 128.137824ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:41.983365Z","caller":"traceutil/trace.go:171","msg":"trace[1486870447] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"275.32287ms","start":"2026-02-11T10:17:41.708021Z","end":"2026-02-11T10:17:41.983344Z","steps":["trace[1486870447] 'read index received' (duration: 122.370129ms)","trace[1486870447] 'applied index is now lower than readState.Index' (duration: 152.951691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:41.983523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.461313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:41.983556Z","caller":"traceutil/trace.go:171","msg":"trace[1329628815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"275.544665ms","start":"2026-02-11T10:17:41.708002Z","end":"2026-02-11T10:17:41.983546Z","steps":["trace[1329628815] 'agreement among raft nodes before linearized reading' (duration: 275.429182ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:41.983906Z","caller":"traceutil/trace.go:171","msg":"trace[749774869] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"281.568356ms","start":"2026-02-11T10:17:41.702322Z","end":"2026-02-11T10:17:41.983891Z","steps":["trace[749774869] 'process raft request' (duration: 257.009794ms)","trace[749774869] 'compare' (duration: 23.62993ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:41.984039Z","caller":"traceutil/trace.go:171","msg":"trace[873726610] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"280.727466ms","start":"2026-02-11T10:17:41.7033Z","end":"2026-02-11T10:17:41.984027Z","steps":["trace[873726610] 'process raft request' (duration: 279.996269ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:43.017631Z","caller":"traceutil/trace.go:171","msg":"trace[954427307] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"258.345685ms","start":"2026-02-11T10:17:42.759258Z","end":"2026-02-11T10:17:43.017604Z","steps":["trace[954427307] 'process raft request' (duration: 258.163371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:43.584523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.939488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:43.584573Z","caller":"traceutil/trace.go:171","msg":"trace[1959056564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"405.03325ms","start":"2026-02-11T10:17:43.179528Z","end":"2026-02-11T10:17:43.584561Z","steps":["trace[1959056564] 'range keys from in-memory index tree' (duration: 404.871296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:43.584598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:43.179514Z","time spent":"405.07876ms","remote":"127.0.0.1:43520","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-11T10:17:43.584796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.743776ms","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-11T10:17:43.58485Z","caller":"traceutil/trace.go:171","msg":"trace[143464103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"216.828738ms","start":"2026-02-11T10:17:43.368004Z","end":"2026-02-11T10:17:43.584832Z","steps":["trace[143464103] 'range keys from in-memory index tree' (duration: 216.622674ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:43.766116Z","caller":"traceutil/trace.go:171","msg":"trace[1271474994] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"178.569857ms","start":"2026-02-11T10:17:43.58753Z","end":"2026-02-11T10:17:43.7661Z","steps":["trace[1271474994] 'process raft request' (duration: 178.450694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:44.849365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015984863s","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-11T10:17:44.849437Z","caller":"traceutil/trace.go:171","msg":"trace[1162349019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"1.016085686s","start":"2026-02-11T10:17:43.833332Z","end":"2026-02-11T10:17:44.849418Z","steps":["trace[1162349019] 'range keys from in-memory index tree' (duration: 1.015832039s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:44.849455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.744849ms","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-11T10:17:44.849478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:43.83332Z","time spent":"1.016148407s","remote":"127.0.0.1:43810","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":"info","ts":"2026-02-11T10:17:44.849507Z","caller":"traceutil/trace.go:171","msg":"trace[206135734] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:944; }","duration":"226.82392ms","start":"2026-02-11T10:17:44.622667Z","end":"2026-02-11T10:17:44.849491Z","steps":["trace[206135734] 'count revisions from in-memory index tree' (duration: 226.688448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:44.849724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.051023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T10:17:44.849757Z","caller":"traceutil/trace.go:171","msg":"trace[944824245] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:944; }","duration":"159.118974ms","start":"2026-02-11T10:17:44.690629Z","end":"2026-02-11T10:17:44.849748Z","steps":["trace[944824245] 'range keys from in-memory index tree' (duration: 158.94877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:44.849808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.414771ms","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-11T10:17:44.849897Z","caller":"traceutil/trace.go:171","msg":"trace[464453650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"860.525794ms","start":"2026-02-11T10:17:43.989361Z","end":"2026-02-11T10:17:44.849887Z","steps":["trace[464453650] 'range keys from in-memory index tree' (duration: 860.322769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:44.849926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:43.989347Z","time spent":"860.571794ms","remote":"127.0.0.1:43810","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-11T10:17:44.850101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.771796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T10:17:44.850136Z","caller":"traceutil/trace.go:171","msg":"trace[1783088634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"859.832477ms","start":"2026-02-11T10:17:43.990293Z","end":"2026-02-11T10:17:44.850126Z","steps":["trace[1783088634] 'range keys from in-memory index tree' (duration: 859.668613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:44.850162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:43.990278Z","time spent":"859.878549ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T10:17:46.318954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.374732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:46.319109Z","caller":"traceutil/trace.go:171","msg":"trace[1108699262] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"1.296868333s","start":"2026-02-11T10:17:45.022229Z","end":"2026-02-11T10:17:46.319097Z","steps":["trace[1108699262] 'read index received' (duration: 40.297758ms)","trace[1108699262] 'applied index is now lower than readState.Index' (duration: 1.256569795s)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:46.319272Z","caller":"traceutil/trace.go:171","msg":"trace[396526008] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.462383827s","start":"2026-02-11T10:17:44.856871Z","end":"2026-02-11T10:17:46.319254Z","steps":["trace[396526008] 'process raft request' (duration: 811.655427ms)","trace[396526008] 'compare' (duration: 650.012663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:46.319314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:44.856857Z","time spent":"1.462434878s","remote":"127.0.0.1:43810","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-11T10:17:46.319456Z","caller":"traceutil/trace.go:171","msg":"trace[1087355786] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.462512139s","start":"2026-02-11T10:17:44.856933Z","end":"2026-02-11T10:17:46.319445Z","steps":["trace[1087355786] 'process raft request' (duration: 1.46208547s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.319507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:44.856927Z","time spent":"1.4625531s","remote":"127.0.0.1:43810","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-11T10:17:46.320087Z","caller":"traceutil/trace.go:171","msg":"trace[882821423] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.462165211s","start":"2026-02-11T10:17:44.857912Z","end":"2026-02-11T10:17:46.320077Z","steps":["trace[882821423] 'process raft request' (duration: 1.461157278s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.320233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29800769s","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-11T10:17:46.320258Z","caller":"traceutil/trace.go:171","msg":"trace[1095951784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"1.29803522s","start":"2026-02-11T10:17:45.022216Z","end":"2026-02-11T10:17:46.320251Z","steps":["trace[1095951784] 'agreement among raft nodes before linearized reading' (duration: 1.297948778s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.320282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:45.02216Z","time spent":"1.298117522s","remote":"127.0.0.1:43810","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-11T10:17:46.320381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:44.857903Z","time spent":"1.462213593s","remote":"127.0.0.1:43810","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-11T10:17:46.90612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.820667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:46.906337Z","caller":"traceutil/trace.go:171","msg":"trace[1628147777] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"581.063368ms","start":"2026-02-11T10:17:46.325219Z","end":"2026-02-11T10:17:46.906283Z","steps":["trace[1628147777] 'process raft request' (duration: 581.003147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.906407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:46.325205Z","time spent":"581.16976ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:17:46.906567Z","caller":"traceutil/trace.go:171","msg":"trace[1548664033] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.843588692s","start":"2026-02-11T10:17:45.062967Z","end":"2026-02-11T10:17:46.906556Z","steps":["trace[1548664033] 'process raft request' (duration: 1.413243092s)","trace[1548664033] 'compare' (duration: 429.432518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:46.906624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:45.062953Z","time spent":"1.843644523s","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:17:46.906731Z","caller":"traceutil/trace.go:171","msg":"trace[409452726] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"587.59452ms","start":"2026-02-11T10:17:46.319129Z","end":"2026-02-11T10:17:46.906723Z","steps":["trace[409452726] 'read index received' (duration: 157.175289ms)","trace[409452726] 'applied index is now lower than readState.Index' (duration: 430.418391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:46.9068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72925923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:46.906829Z","caller":"traceutil/trace.go:171","msg":"trace[2052144182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"1.729287041s","start":"2026-02-11T10:17:45.177534Z","end":"2026-02-11T10:17:46.906821Z","steps":["trace[2052144182] 'agreement among raft nodes before linearized reading' (duration: 1.729224749s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.906858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:45.177506Z","time spent":"1.729346762s","remote":"127.0.0.1:43520","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-11T10:17:46.907113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136576891s","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-11T10:17:46.907146Z","caller":"traceutil/trace.go:171","msg":"trace[824597645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"1.136631673s","start":"2026-02-11T10:17:45.770507Z","end":"2026-02-11T10:17:46.907139Z","steps":["trace[824597645] 'agreement among raft nodes before linearized reading' (duration: 1.136534541s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.907176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:45.770493Z","time spent":"1.136677754s","remote":"127.0.0.1:43810","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-11T10:17:46.907303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329652977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T10:17:46.907337Z","caller":"traceutil/trace.go:171","msg":"trace[2051390485] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:949; }","duration":"1.329726768s","start":"2026-02-11T10:17:45.577603Z","end":"2026-02-11T10:17:46.907329Z","steps":["trace[2051390485] 'agreement among raft nodes before linearized reading' (duration: 1.329675567s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:46.907368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:45.577547Z","time spent":"1.32981429s","remote":"127.0.0.1:43910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T10:17:47.422483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.306681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:47.42255Z","caller":"traceutil/trace.go:171","msg":"trace[485851557] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"407.273712ms","start":"2026-02-11T10:17:47.015263Z","end":"2026-02-11T10:17:47.422537Z","steps":["trace[485851557] 'process raft request' (duration: 210.868ms)","trace[485851557] 'compare' (duration: 196.206278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:47.422606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:47.015246Z","time spent":"407.320343ms","remote":"127.0.0.1:43810","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-11T10:17:48.553691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.028507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:48.553766Z","caller":"traceutil/trace.go:171","msg":"trace[305952706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"377.143851ms","start":"2026-02-11T10:17:48.176605Z","end":"2026-02-11T10:17:48.553749Z","steps":["trace[305952706] 'range keys from in-memory index tree' (duration: 376.980967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:48.553805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:48.176592Z","time spent":"377.204072ms","remote":"127.0.0.1:43520","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-11T10:17:48.554263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.81422ms","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-11T10:17:48.554306Z","caller":"traceutil/trace.go:171","msg":"trace[536788427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"229.889082ms","start":"2026-02-11T10:17:48.324407Z","end":"2026-02-11T10:17:48.554296Z","steps":["trace[536788427] 'range keys from in-memory index tree' (duration: 229.664757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:48.554493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.656827ms","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-11T10:17:48.554527Z","caller":"traceutil/trace.go:171","msg":"trace[1491336922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:950; }","duration":"229.707928ms","start":"2026-02-11T10:17:48.32481Z","end":"2026-02-11T10:17:48.554518Z","steps":["trace[1491336922] 'range keys from in-memory index tree' (duration: 229.579696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:48.554739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.697624ms","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-11T10:17:48.554775Z","caller":"traceutil/trace.go:171","msg":"trace[2052006887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:950; }","duration":"228.750206ms","start":"2026-02-11T10:17:48.326016Z","end":"2026-02-11T10:17:48.554767Z","steps":["trace[2052006887] 'range keys from in-memory index tree' (duration: 228.577632ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:49.004878Z","caller":"traceutil/trace.go:171","msg":"trace[526702067] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"439.287347ms","start":"2026-02-11T10:17:48.565569Z","end":"2026-02-11T10:17:49.004857Z","steps":["trace[526702067] 'process raft request' (duration: 439.049302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:49.005027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:48.565558Z","time spent":"439.39559ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:17:49.074429Z","caller":"traceutil/trace.go:171","msg":"trace[1896530621] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"161.169042ms","start":"2026-02-11T10:17:48.913239Z","end":"2026-02-11T10:17:49.074408Z","steps":["trace[1896530621] 'read index received' (duration: 92.167356ms)","trace[1896530621] 'applied index is now lower than readState.Index' (duration: 69.000716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:49.07462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.357517ms","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-11T10:17:49.07467Z","caller":"traceutil/trace.go:171","msg":"trace[559505195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"161.447409ms","start":"2026-02-11T10:17:48.913213Z","end":"2026-02-11T10:17:49.07466Z","steps":["trace[559505195] 'agreement among raft nodes before linearized reading' (duration: 161.276985ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:49.075165Z","caller":"traceutil/trace.go:171","msg":"trace[548549025] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"509.390829ms","start":"2026-02-11T10:17:48.565737Z","end":"2026-02-11T10:17:49.075128Z","steps":["trace[548549025] 'process raft request' (duration: 498.402583ms)","trace[548549025] 'compare' (duration: 10.150476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:49.075265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:48.565732Z","time spent":"509.476771ms","remote":"127.0.0.1:43810","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-11T10:17:49.075418Z","caller":"traceutil/trace.go:171","msg":"trace[503153148] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"508.98505ms","start":"2026-02-11T10:17:48.566423Z","end":"2026-02-11T10:17:49.075408Z","steps":["trace[503153148] 'process raft request' (duration: 507.947625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:49.075546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:48.566416Z","time spent":"509.042442ms","remote":"127.0.0.1:43810","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-11T10:17:50.771432Z","caller":"traceutil/trace.go:171","msg":"trace[1599803845] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"126.416143ms","start":"2026-02-11T10:17:50.644996Z","end":"2026-02-11T10:17:50.771412Z","steps":["trace[1599803845] 'read index received' (duration: 126.250879ms)","trace[1599803845] 'applied index is now lower than readState.Index' (duration: 164.674µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:50.771562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.532386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T10:17:50.771581Z","caller":"traceutil/trace.go:171","msg":"trace[5375784] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:961; }","duration":"126.586787ms","start":"2026-02-11T10:17:50.644989Z","end":"2026-02-11T10:17:50.771576Z","steps":["trace[5375784] 'agreement among raft nodes before linearized reading' (duration: 126.504395ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:50.771713Z","caller":"traceutil/trace.go:171","msg":"trace[2016211619] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"392.58712ms","start":"2026-02-11T10:17:50.3791Z","end":"2026-02-11T10:17:50.771688Z","steps":["trace[2016211619] 'process raft request' (duration: 392.19354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:17:50.771838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:17:50.379082Z","time spent":"392.684742ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T10:17:51.206287Z","caller":"traceutil/trace.go:171","msg":"trace[210911329] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"106.840357ms","start":"2026-02-11T10:17:51.099426Z","end":"2026-02-11T10:17:51.206267Z","steps":["trace[210911329] 'read index received' (duration: 54.895318ms)","trace[210911329] 'applied index is now lower than readState.Index' (duration: 51.944019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:51.206631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.184995ms","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-11T10:17:51.207343Z","caller":"traceutil/trace.go:171","msg":"trace[1380732083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"107.928322ms","start":"2026-02-11T10:17:51.099408Z","end":"2026-02-11T10:17:51.207336Z","steps":["trace[1380732083] 'agreement among raft nodes before linearized reading' (duration: 107.079973ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:51.206795Z","caller":"traceutil/trace.go:171","msg":"trace[288326233] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"115.598311ms","start":"2026-02-11T10:17:51.091177Z","end":"2026-02-11T10:17:51.206775Z","steps":["trace[288326233] 'process raft request' (duration: 106.781586ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:52.367053Z","caller":"traceutil/trace.go:171","msg":"trace[700225714] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"163.086467ms","start":"2026-02-11T10:17:52.203946Z","end":"2026-02-11T10:17:52.367033Z","steps":["trace[700225714] 'process raft request' (duration: 162.888422ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:53.27915Z","caller":"traceutil/trace.go:171","msg":"trace[1242767118] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"103.09284ms","start":"2026-02-11T10:17:53.176032Z","end":"2026-02-11T10:17:53.279125Z","steps":["trace[1242767118] 'read index received' (duration: 35.480586ms)","trace[1242767118] 'applied index is now lower than readState.Index' (duration: 67.610854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:53.279275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.229053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:17:53.279294Z","caller":"traceutil/trace.go:171","msg":"trace[2037006780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"103.260364ms","start":"2026-02-11T10:17:53.176026Z","end":"2026-02-11T10:17:53.279286Z","steps":["trace[2037006780] 'agreement among raft nodes before linearized reading' (duration: 103.192342ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:17:53.279265Z","caller":"traceutil/trace.go:171","msg":"trace[909523835] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"118.613151ms","start":"2026-02-11T10:17:53.160613Z","end":"2026-02-11T10:17:53.279226Z","steps":["trace[909523835] 'process raft request' (duration: 50.906475ms)","trace[909523835] 'compare' (duration: 67.260206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:53.485501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.339622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:53.486215Z","caller":"traceutil/trace.go:171","msg":"trace[1039607993] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"195.560093ms","start":"2026-02-11T10:17:53.290636Z","end":"2026-02-11T10:17:53.486196Z","steps":["trace[1039607993] 'process raft request' (duration: 89.448373ms)","trace[1039607993] 'compare' (duration: 105.25842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:17:55.580663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.441531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:17:55.580746Z","caller":"traceutil/trace.go:171","msg":"trace[1654874814] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"179.222613ms","start":"2026-02-11T10:17:55.401509Z","end":"2026-02-11T10:17:55.580731Z","steps":["trace[1654874814] 'process raft request' (duration: 62.637179ms)","trace[1654874814] 'compare' (duration: 116.279877ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:17:55.581949Z","caller":"traceutil/trace.go:171","msg":"trace[2045227288] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"180.274497ms","start":"2026-02-11T10:17:55.401656Z","end":"2026-02-11T10:17:55.581931Z","steps":["trace[2045227288] 'process raft request' (duration: 180.066172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:00.616555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.767258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-11T10:18:03.722468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763339997259755,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T10:18:03.728238Z","caller":"traceutil/trace.go:171","msg":"trace[2036088011] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"506.740208ms","start":"2026-02-11T10:18:03.221474Z","end":"2026-02-11T10:18:03.728214Z","steps":["trace[2036088011] 'read index received' (duration: 506.547473ms)","trace[2036088011] 'applied index is now lower than readState.Index' (duration: 191.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:18:03.728391Z","caller":"traceutil/trace.go:171","msg":"trace[394286879] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"508.838047ms","start":"2026-02-11T10:18:03.219541Z","end":"2026-02-11T10:18:03.728379Z","steps":["trace[394286879] 'process raft request' (duration: 508.54277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:03.728536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.219528Z","time spent":"508.953259ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T10:18:03.72887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.383353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-11T10:18:03.7292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.227119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:18:03.729241Z","caller":"traceutil/trace.go:171","msg":"trace[1288915403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"443.30268ms","start":"2026-02-11T10:18:03.285931Z","end":"2026-02-11T10:18:03.729234Z","steps":["trace[1288915403] 'agreement among raft nodes before linearized reading' (duration: 443.248209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:03.729285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.285844Z","time spent":"443.436314ms","remote":"127.0.0.1:43520","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-11T10:18:03.729547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.16071ms","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-11T10:18:03.72961Z","caller":"traceutil/trace.go:171","msg":"trace[1609713146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1025; }","duration":"331.270922ms","start":"2026-02-11T10:18:03.398333Z","end":"2026-02-11T10:18:03.729604Z","steps":["trace[1609713146] 'agreement among raft nodes before linearized reading' (duration: 331.18358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:03.729667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.398312Z","time spent":"331.337524ms","remote":"127.0.0.1:43810","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-11T10:18:03.729044Z","caller":"traceutil/trace.go:171","msg":"trace[1667044558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"507.447705ms","start":"2026-02-11T10:18:03.221458Z","end":"2026-02-11T10:18:03.728906Z","steps":["trace[1667044558] 'agreement among raft nodes before linearized reading' (duration: 507.306671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:03.731093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.221452Z","time spent":"509.347229ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T10:18:04.261001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.239302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:18:04.261135Z","caller":"traceutil/trace.go:171","msg":"trace[461277969] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"526.071728ms","start":"2026-02-11T10:18:03.735019Z","end":"2026-02-11T10:18:04.261091Z","steps":["trace[461277969] 'process raft request' (duration: 191.626302ms)","trace[461277969] 'compare' (duration: 333.915483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:18:04.261224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.735001Z","time spent":"526.18106ms","remote":"127.0.0.1:43810","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-11T10:18:04.316665Z","caller":"traceutil/trace.go:171","msg":"trace[52772652] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1073; }","duration":"512.86748ms","start":"2026-02-11T10:18:03.803778Z","end":"2026-02-11T10:18:04.316646Z","steps":["trace[52772652] 'read index received' (duration: 122.808459ms)","trace[52772652] 'applied index is now lower than readState.Index' (duration: 390.058291ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:18:04.316889Z","caller":"traceutil/trace.go:171","msg":"trace[1574329172] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"581.634702ms","start":"2026-02-11T10:18:03.735243Z","end":"2026-02-11T10:18:04.316878Z","steps":["trace[1574329172] 'process raft request' (duration: 581.237082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:04.317705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.735225Z","time spent":"581.696323ms","remote":"127.0.0.1:43810","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-11T10:18:04.318005Z","caller":"traceutil/trace.go:171","msg":"trace[1909923624] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"581.468708ms","start":"2026-02-11T10:18:03.736522Z","end":"2026-02-11T10:18:04.317991Z","steps":["trace[1909923624] 'process raft request' (duration: 580.054755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:04.318115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.736514Z","time spent":"581.521569ms","remote":"127.0.0.1:43810","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-11T10:18:04.318353Z","caller":"traceutil/trace.go:171","msg":"trace[1330195130] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"580.855113ms","start":"2026-02-11T10:18:03.737487Z","end":"2026-02-11T10:18:04.318342Z","steps":["trace[1330195130] 'process raft request' (duration: 579.129843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:04.318469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.737479Z","time spent":"580.905375ms","remote":"127.0.0.1:43810","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-11T10:18:04.318781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.99658ms","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-11T10:18:04.31882Z","caller":"traceutil/trace.go:171","msg":"trace[77942175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1029; }","duration":"515.071112ms","start":"2026-02-11T10:18:03.803739Z","end":"2026-02-11T10:18:04.31881Z","steps":["trace[77942175] 'agreement among raft nodes before linearized reading' (duration: 514.941939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:04.318944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:03.803725Z","time spent":"515.171764ms","remote":"127.0.0.1:43810","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-11T10:18:04.319149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.197417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:18:04.319194Z","caller":"traceutil/trace.go:171","msg":"trace[303625611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"138.239568ms","start":"2026-02-11T10:18:04.180942Z","end":"2026-02-11T10:18:04.319182Z","steps":["trace[303625611] 'agreement among raft nodes before linearized reading' (duration: 138.180987ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:18:07.334452Z","caller":"traceutil/trace.go:171","msg":"trace[578013083] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"249.177522ms","start":"2026-02-11T10:18:07.085252Z","end":"2026-02-11T10:18:07.334429Z","steps":["trace[578013083] 'process raft request' (duration: 248.873454ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:18:10.206371Z","caller":"traceutil/trace.go:171","msg":"trace[1330946982] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"111.285989ms","start":"2026-02-11T10:18:10.095058Z","end":"2026-02-11T10:18:10.206344Z","steps":["trace[1330946982] 'process raft request' (duration: 111.161806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:11.570241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.081723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:18:11.570305Z","caller":"traceutil/trace.go:171","msg":"trace[1619074096] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1064; }","duration":"596.185535ms","start":"2026-02-11T10:18:10.974105Z","end":"2026-02-11T10:18:11.57029Z","steps":["trace[1619074096] 'range keys from in-memory index tree' (duration: 596.028741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:11.57034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:10.974091Z","time spent":"596.242036ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-11T10:18:11.570393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.689661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:18:11.570446Z","caller":"traceutil/trace.go:171","msg":"trace[1138580102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"390.753613ms","start":"2026-02-11T10:18:11.179679Z","end":"2026-02-11T10:18:11.570433Z","steps":["trace[1138580102] 'range keys from in-memory index tree' (duration: 390.612989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:18:11.570469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:18:11.179611Z","time spent":"390.853555ms","remote":"127.0.0.1:43520","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-11T10:18:11.570671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.785992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T10:18:11.57069Z","caller":"traceutil/trace.go:171","msg":"trace[973929232] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1064; }","duration":"106.829004ms","start":"2026-02-11T10:18:11.463853Z","end":"2026-02-11T10:18:11.570682Z","steps":["trace[973929232] 'count revisions from in-memory index tree' (duration: 106.732481ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:18:11.611076Z","caller":"traceutil/trace.go:171","msg":"trace[1003781574] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"133.839538ms","start":"2026-02-11T10:18:11.477218Z","end":"2026-02-11T10:18:11.611057Z","steps":["trace[1003781574] 'process raft request' (duration: 133.705755ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:18:28.949811Z","caller":"traceutil/trace.go:171","msg":"trace[1671981290] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"102.545902ms","start":"2026-02-11T10:18:28.847244Z","end":"2026-02-11T10:18:28.949789Z","steps":["trace[1671981290] 'process raft request' (duration: 61.556537ms)","trace[1671981290] 'compare' (duration: 40.614606ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:18:34.981527Z","caller":"traceutil/trace.go:171","msg":"trace[1159616192] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"118.255702ms","start":"2026-02-11T10:18:34.863252Z","end":"2026-02-11T10:18:34.981508Z","steps":["trace[1159616192] 'process raft request' (duration: 114.37194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:21:26.319691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.689316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T10:21:26.335245Z","caller":"traceutil/trace.go:171","msg":"trace[415052852] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"577.065994ms","start":"2026-02-11T10:21:25.758161Z","end":"2026-02-11T10:21:26.335227Z","steps":["trace[415052852] 'read index received' (duration: 71.571µs)","trace[415052852] 'applied index is now lower than readState.Index' (duration: 576.992773ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:21:26.335569Z","caller":"traceutil/trace.go:171","msg":"trace[2776528] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"789.805354ms","start":"2026-02-11T10:21:25.545548Z","end":"2026-02-11T10:21:26.335353Z","steps":["trace[2776528] 'process raft request' (duration: 88.352742ms)","trace[2776528] 'compare' (duration: 685.358518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T10:21:26.335645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:21:25.545531Z","time spent":"790.078561ms","remote":"127.0.0.1:43810","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-11T10:21:26.336314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.146469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-11T10:21:26.336366Z","caller":"traceutil/trace.go:171","msg":"trace[498018817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1898; }","duration":"578.262932ms","start":"2026-02-11T10:21:25.758089Z","end":"2026-02-11T10:21:26.336352Z","steps":["trace[498018817] 'agreement among raft nodes before linearized reading' (duration: 578.113369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:21:26.336401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:21:25.758062Z","time spent":"578.331024ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T10:21:26.338247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.9912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-11T10:21:26.338867Z","caller":"traceutil/trace.go:171","msg":"trace[1368150869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"403.683776ms","start":"2026-02-11T10:21:25.935151Z","end":"2026-02-11T10:21:26.338835Z","steps":["trace[1368150869] 'agreement among raft nodes before linearized reading' (duration: 402.884177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:21:26.339103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T10:21:25.935131Z","time spent":"403.915252ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T10:21:26.339625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.54604ms","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-11T10:21:26.339767Z","caller":"traceutil/trace.go:171","msg":"trace[221925616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1898; }","duration":"247.733944ms","start":"2026-02-11T10:21:26.092023Z","end":"2026-02-11T10:21:26.339757Z","steps":["trace[221925616] 'agreement among raft nodes before linearized reading' (duration: 247.53491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:21:26.34002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.00965ms","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-11T10:21:26.340156Z","caller":"traceutil/trace.go:171","msg":"trace[858072036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"248.147643ms","start":"2026-02-11T10:21:26.091998Z","end":"2026-02-11T10:21:26.340145Z","steps":["trace[858072036] 'agreement among raft nodes before linearized reading' (duration: 247.964469ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:21:26.526171Z","caller":"traceutil/trace.go:171","msg":"trace[1841232514] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1988; }","duration":"133.894798ms","start":"2026-02-11T10:21:26.392255Z","end":"2026-02-11T10:21:26.52615Z","steps":["trace[1841232514] 'read index received' (duration: 9.690835ms)","trace[1841232514] 'applied index is now lower than readState.Index' (duration: 124.202673ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T10:21:26.526413Z","caller":"traceutil/trace.go:171","msg":"trace[889871691] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"178.037534ms","start":"2026-02-11T10:21:26.34836Z","end":"2026-02-11T10:21:26.526398Z","steps":["trace[889871691] 'process raft request' (duration: 177.603894ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:21:26.526555Z","caller":"traceutil/trace.go:171","msg":"trace[395519151] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"177.318396ms","start":"2026-02-11T10:21:26.349225Z","end":"2026-02-11T10:21:26.526543Z","steps":["trace[395519151] 'process raft request' (duration: 176.862356ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:21:26.526679Z","caller":"traceutil/trace.go:171","msg":"trace[975743634] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"133.242913ms","start":"2026-02-11T10:21:26.393426Z","end":"2026-02-11T10:21:26.526669Z","steps":["trace[975743634] 'process raft request' (duration: 132.692061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T10:21:26.526847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.569194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T10:21:26.52688Z","caller":"traceutil/trace.go:171","msg":"trace[1105935638] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1902; }","duration":"134.677777ms","start":"2026-02-11T10:21:26.392193Z","end":"2026-02-11T10:21:26.52687Z","steps":["trace[1105935638] 'agreement among raft nodes before linearized reading' (duration: 134.596095ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:21:27.434002Z","caller":"traceutil/trace.go:171","msg":"trace[920454259] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"135.031906ms","start":"2026-02-11T10:21:27.298947Z","end":"2026-02-11T10:21:27.433979Z","steps":["trace[920454259] 'process raft request' (duration: 134.416902ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T10:21:28.551688Z","caller":"traceutil/trace.go:171","msg":"trace[1230123609] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"115.909763ms","start":"2026-02-11T10:21:28.435756Z","end":"2026-02-11T10:21:28.551665Z","steps":["trace[1230123609] 'process raft request' (duration: 64.58079ms)","trace[1230123609] 'compare' (duration: 50.823681ms)"],"step_count":2}