{"level":"warn","ts":"2026-04-13T14:30:38.61507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:30:38.615198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.191:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.191:2380","--initial-cluster=instance=https://199.19.213.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.191: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-04-13T14:30:38.615289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:30:38.615307Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-04-13T14:30:38.615334Z","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-04-13T14:30:38.616265Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"]} {"level":"info","ts":"2026-04-13T14:30:38.616349Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.191: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-04-13T14:30:38.620681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.106142ms"} {"level":"info","ts":"2026-04-13T14:30:38.627159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e892d50a3a4fc0f4","cluster-id":"21eccdda20ff4e92"} {"level":"info","ts":"2026-04-13T14:30:38.627248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T14:30:38.627285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 0"} {"level":"info","ts":"2026-04-13T14:30:38.627324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e892d50a3a4fc0f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T14:30:38.627364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 1"} {"level":"info","ts":"2026-04-13T14:30:38.62741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"warn","ts":"2026-04-13T14:30:38.638807Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T14:30:38.640227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T14:30:38.64102Z","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-04-13T14:30:38.643241Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e892d50a3a4fc0f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T14:30:38.64344Z","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-04-13T14:30:38.643544Z","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-04-13T14:30:38.643584Z","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-04-13T14:30:38.643854Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e892d50a3a4fc0f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T14:30:38.646465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"info","ts":"2026-04-13T14:30:38.647096Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","added-peer-id":"e892d50a3a4fc0f4","added-peer-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-04-13T14:30:38.649727Z","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-04-13T14:30:38.649954Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e892d50a3a4fc0f4","initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T14:30:38.649999Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T14:30:38.65006Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-04-13T14:30:38.650113Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-04-13T14:30:39.227595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T14:30:39.22768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T14:30:39.22774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgPreVoteResp from e892d50a3a4fc0f4 at term 1"} {"level":"info","ts":"2026-04-13T14:30:39.227761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became candidate at term 2"} {"level":"info","ts":"2026-04-13T14:30:39.227886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgVoteResp from e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-04-13T14:30:39.227927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became leader at term 2"} {"level":"info","ts":"2026-04-13T14:30:39.227986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e892d50a3a4fc0f4 elected leader e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-04-13T14:30:39.539328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:39.661901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e892d50a3a4fc0f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.191:2379]}","request-path":"/0/members/e892d50a3a4fc0f4/attributes","cluster-id":"21eccdda20ff4e92","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T14:30:39.661978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:30:39.66205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:30:39.662373Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T14:30:39.662477Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T14:30:39.663477Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:39.663555Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:39.663583Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:30:39.66599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T14:30:39.6668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.191:2379"} {"level":"info","ts":"2026-04-13T14:30:50.704909Z","caller":"traceutil/trace.go:171","msg":"trace[1025729981] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"103.477174ms","start":"2026-04-13T14:30:50.601398Z","end":"2026-04-13T14:30:50.704875Z","steps":["trace[1025729981] 'process raft request' (duration: 103.282749ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:30:55.289202Z","caller":"traceutil/trace.go:171","msg":"trace[769153328] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"139.49262ms","start":"2026-04-13T14:30:55.149654Z","end":"2026-04-13T14:30:55.289147Z","steps":["trace[769153328] 'process raft request' (duration: 85.004729ms)","trace[769153328] 'compare' (duration: 54.119892ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:30:56.077705Z","caller":"traceutil/trace.go:171","msg":"trace[2037353274] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"119.073957ms","start":"2026-04-13T14:30:55.958607Z","end":"2026-04-13T14:30:56.077681Z","steps":["trace[2037353274] 'process raft request' (duration: 118.910903ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:05.436324Z","caller":"traceutil/trace.go:171","msg":"trace[1299653020] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"185.117014ms","start":"2026-04-13T14:31:05.251176Z","end":"2026-04-13T14:31:05.436293Z","steps":["trace[1299653020] 'read index received' (duration: 184.871128ms)","trace[1299653020] 'applied index is now lower than readState.Index' (duration: 244.276µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:31:05.43643Z","caller":"traceutil/trace.go:171","msg":"trace[1976456324] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"259.821715ms","start":"2026-04-13T14:31:05.176568Z","end":"2026-04-13T14:31:05.43639Z","steps":["trace[1976456324] 'process raft request' (duration: 259.515338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:05.436551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.346619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T14:31:05.436645Z","caller":"traceutil/trace.go:171","msg":"trace[1620019412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"185.510903ms","start":"2026-04-13T14:31:05.251121Z","end":"2026-04-13T14:31:05.436632Z","steps":["trace[1620019412] 'agreement among raft nodes before linearized reading' (duration: 185.349169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:05.436669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.288308ms","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-04-13T14:31:05.43674Z","caller":"traceutil/trace.go:171","msg":"trace[1685694854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"102.438343ms","start":"2026-04-13T14:31:05.334283Z","end":"2026-04-13T14:31:05.436722Z","steps":["trace[1685694854] 'agreement among raft nodes before linearized reading' (duration: 102.31659ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:05.549366Z","caller":"traceutil/trace.go:171","msg":"trace[1104566596] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"108.21891ms","start":"2026-04-13T14:31:05.44112Z","end":"2026-04-13T14:31:05.549339Z","steps":["trace[1104566596] 'process raft request' (duration: 103.98409ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:05.550606Z","caller":"traceutil/trace.go:171","msg":"trace[656023384] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"109.305576ms","start":"2026-04-13T14:31:05.441275Z","end":"2026-04-13T14:31:05.55058Z","steps":["trace[656023384] 'process raft request' (duration: 109.257175ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:05.550813Z","caller":"traceutil/trace.go:171","msg":"trace[193968672] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"109.598993ms","start":"2026-04-13T14:31:05.441202Z","end":"2026-04-13T14:31:05.550801Z","steps":["trace[193968672] 'process raft request' (duration: 109.209084ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:14.193679Z","caller":"traceutil/trace.go:171","msg":"trace[374402984] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"427.721489ms","start":"2026-04-13T14:31:13.765938Z","end":"2026-04-13T14:31:14.19366Z","steps":["trace[374402984] 'process raft request' (duration: 427.574645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.194038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.765919Z","time spent":"427.831351ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.778842Z","caller":"traceutil/trace.go:171","msg":"trace[1817751517] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.010195197s","start":"2026-04-13T14:31:13.768627Z","end":"2026-04-13T14:31:14.778822Z","steps":["trace[1817751517] 'process raft request' (duration: 1.00989361s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.778951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.768613Z","time spent":"1.010292399s","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.779024Z","caller":"traceutil/trace.go:171","msg":"trace[338954929] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"989.583194ms","start":"2026-04-13T14:31:13.789418Z","end":"2026-04-13T14:31:14.779001Z","steps":["trace[338954929] 'process raft request' (duration: 989.39231ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:14.77904Z","caller":"traceutil/trace.go:171","msg":"trace[654163875] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"989.689137ms","start":"2026-04-13T14:31:13.789336Z","end":"2026-04-13T14:31:14.779025Z","steps":["trace[654163875] 'process raft request' (duration: 989.40708ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:14.779073Z","caller":"traceutil/trace.go:171","msg":"trace[1498183271] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"982.555918ms","start":"2026-04-13T14:31:13.796511Z","end":"2026-04-13T14:31:14.779067Z","steps":["trace[1498183271] 'process raft request' (duration: 982.372824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.779102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.789318Z","time spent":"989.755838ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:31:14.779122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.796494Z","time spent":"982.601539ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:31:14.779147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.789411Z","time spent":"989.659776ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.779032Z","caller":"traceutil/trace.go:171","msg":"trace[942970899] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"989.240127ms","start":"2026-04-13T14:31:13.789774Z","end":"2026-04-13T14:31:14.779014Z","steps":["trace[942970899] 'process raft request' (duration: 989.065233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.77919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.789762Z","time spent":"989.416121ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.779246Z","caller":"traceutil/trace.go:171","msg":"trace[1652541256] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"982.608639ms","start":"2026-04-13T14:31:13.796627Z","end":"2026-04-13T14:31:14.779236Z","steps":["trace[1652541256] 'process raft request' (duration: 982.287031ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:14.77928Z","caller":"traceutil/trace.go:171","msg":"trace[1984628477] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.010224537s","start":"2026-04-13T14:31:13.768772Z","end":"2026-04-13T14:31:14.778997Z","steps":["trace[1984628477] 'process raft request' (duration: 1.009862079s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.779296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.796608Z","time spent":"982.66711ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.779325Z","caller":"traceutil/trace.go:171","msg":"trace[298090500] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"968.134844ms","start":"2026-04-13T14:31:13.811183Z","end":"2026-04-13T14:31:14.779318Z","steps":["trace[298090500] 'process raft request' (duration: 967.785916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.779356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.768767Z","time spent":"1.010545856s","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:31:14.77936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.811166Z","time spent":"968.178304ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.779561Z","caller":"traceutil/trace.go:171","msg":"trace[467677372] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"965.389997ms","start":"2026-04-13T14:31:13.814158Z","end":"2026-04-13T14:31:14.779548Z","steps":["trace[467677372] 'process raft request' (duration: 965.06602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.779622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.814149Z","time spent":"965.450788ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.780195Z","caller":"traceutil/trace.go:171","msg":"trace[598961858] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:546; }","duration":"913.284506ms","start":"2026-04-13T14:31:13.866897Z","end":"2026-04-13T14:31:14.780182Z","steps":["trace[598961858] 'read index received' (duration: 326.928256ms)","trace[598961858] 'applied index is now lower than readState.Index' (duration: 586.35549ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:31:14.780258Z","caller":"traceutil/trace.go:171","msg":"trace[1107477839] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"937.097853ms","start":"2026-04-13T14:31:13.843152Z","end":"2026-04-13T14:31:14.78025Z","steps":["trace[1107477839] 'process raft request' (duration: 936.13497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.780302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.843105Z","time spent":"937.171364ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.780423Z","caller":"traceutil/trace.go:171","msg":"trace[335974527] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"849.374452ms","start":"2026-04-13T14:31:13.931029Z","end":"2026-04-13T14:31:14.780404Z","steps":["trace[335974527] 'process raft request' (duration: 849.099186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.78046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.931003Z","time spent":"849.439193ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:31:14.780597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.701957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:31:14.780626Z","caller":"traceutil/trace.go:171","msg":"trace[1401375238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"913.767188ms","start":"2026-04-13T14:31:13.86685Z","end":"2026-04-13T14:31:14.780617Z","steps":["trace[1401375238] 'agreement among raft nodes before linearized reading' (duration: 913.697867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.780648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:13.866812Z","time spent":"913.83065ms","remote":"127.0.0.1:38112","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-04-13T14:31:14.825507Z","caller":"traceutil/trace.go:171","msg":"trace[2010351736] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"625.70492ms","start":"2026-04-13T14:31:14.19976Z","end":"2026-04-13T14:31:14.825465Z","steps":["trace[2010351736] 'process raft request' (duration: 625.523854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.825638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:14.199743Z","time spent":"625.830192ms","remote":"127.0.0.1:38572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:31:14.82571Z","caller":"traceutil/trace.go:171","msg":"trace[1881795453] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"625.368572ms","start":"2026-04-13T14:31:14.200324Z","end":"2026-04-13T14:31:14.825692Z","steps":["trace[1881795453] 'process raft request' (duration: 625.080655ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:14.825928Z","caller":"traceutil/trace.go:171","msg":"trace[426809462] transaction","detail":"{read_only:false; number_of_response:1; response_revision:538; }","duration":"624.750686ms","start":"2026-04-13T14:31:14.201162Z","end":"2026-04-13T14:31:14.825913Z","steps":["trace[426809462] 'process raft request' (duration: 624.286585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.826269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.879342ms","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-04-13T14:31:14.826318Z","caller":"traceutil/trace.go:171","msg":"trace[1796123106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"670.23549ms","start":"2026-04-13T14:31:14.15607Z","end":"2026-04-13T14:31:14.826305Z","steps":["trace[1796123106] 'agreement among raft nodes before linearized reading' (duration: 669.883282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:14.826351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:14.15604Z","time spent":"670.303361ms","remote":"127.0.0.1:38324","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-04-13T14:31:14.826771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:14.200316Z","time spent":"625.450924ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:31:14.826833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:14.201154Z","time spent":"624.810907ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:31:21.594325Z","caller":"traceutil/trace.go:171","msg":"trace[1028908371] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"241.388755ms","start":"2026-04-13T14:31:21.352914Z","end":"2026-04-13T14:31:21.594303Z","steps":["trace[1028908371] 'process raft request' (duration: 240.678179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:22.172407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911153826343033,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:31:22.48066Z","caller":"traceutil/trace.go:171","msg":"trace[694703688] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"857.403804ms","start":"2026-04-13T14:31:21.623217Z","end":"2026-04-13T14:31:22.480621Z","steps":["trace[694703688] 'process raft request' (duration: 822.223445ms)","trace[694703688] 'compare' (duration: 34.700038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:31:22.480806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:21.623143Z","time spent":"857.589107ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:31:22.494917Z","caller":"traceutil/trace.go:171","msg":"trace[916527706] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"823.056915ms","start":"2026-04-13T14:31:21.671832Z","end":"2026-04-13T14:31:22.494889Z","steps":["trace[916527706] 'read index received' (duration: 773.594116ms)","trace[916527706] 'applied index is now lower than readState.Index' (duration: 49.461649ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:31:22.495022Z","caller":"traceutil/trace.go:171","msg":"trace[590386565] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"844.502966ms","start":"2026-04-13T14:31:21.650511Z","end":"2026-04-13T14:31:22.495014Z","steps":["trace[590386565] 'process raft request' (duration: 844.207429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:22.495181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:21.65048Z","time spent":"844.640438ms","remote":"127.0.0.1:38304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:31:22.495315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.472534ms","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-04-13T14:31:22.495409Z","caller":"traceutil/trace.go:171","msg":"trace[663578699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"823.596208ms","start":"2026-04-13T14:31:21.67179Z","end":"2026-04-13T14:31:22.495386Z","steps":["trace[663578699] 'agreement among raft nodes before linearized reading' (duration: 823.251289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:22.495451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:21.671772Z","time spent":"823.669809ms","remote":"127.0.0.1:38324","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-04-13T14:31:22.495763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.756353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:31:22.495855Z","caller":"traceutil/trace.go:171","msg":"trace[742405065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"628.902486ms","start":"2026-04-13T14:31:21.866929Z","end":"2026-04-13T14:31:22.495831Z","steps":["trace[742405065] 'agreement among raft nodes before linearized reading' (duration: 628.708021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:22.495871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.252026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T14:31:22.495906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:21.866912Z","time spent":"628.982667ms","remote":"127.0.0.1:38118","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-04-13T14:31:22.495911Z","caller":"traceutil/trace.go:171","msg":"trace[183792406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"788.320748ms","start":"2026-04-13T14:31:21.707579Z","end":"2026-04-13T14:31:22.4959Z","steps":["trace[183792406] 'agreement among raft nodes before linearized reading' (duration: 788.185944ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:22.495915Z","caller":"traceutil/trace.go:171","msg":"trace[93873478] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"207.797726ms","start":"2026-04-13T14:31:22.288069Z","end":"2026-04-13T14:31:22.495867Z","steps":["trace[93873478] 'process raft request' (duration: 206.766041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:22.495962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:21.70756Z","time spent":"788.388969ms","remote":"127.0.0.1:38324","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-04-13T14:31:22.496274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.004324ms","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-04-13T14:31:22.496327Z","caller":"traceutil/trace.go:171","msg":"trace[1245403737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"823.080625ms","start":"2026-04-13T14:31:21.673235Z","end":"2026-04-13T14:31:22.496315Z","steps":["trace[1245403737] 'agreement among raft nodes before linearized reading' (duration: 822.170483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:31:22.496362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:31:21.673225Z","time spent":"823.128656ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:31:23.936137Z","caller":"traceutil/trace.go:171","msg":"trace[488834224] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"189.183251ms","start":"2026-04-13T14:31:23.746931Z","end":"2026-04-13T14:31:23.936115Z","steps":["trace[488834224] 'process raft request' (duration: 189.071498ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:23.939166Z","caller":"traceutil/trace.go:171","msg":"trace[1998302121] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"191.337301ms","start":"2026-04-13T14:31:23.747816Z","end":"2026-04-13T14:31:23.939153Z","steps":["trace[1998302121] 'process raft request' (duration: 191.050745ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:31:35.923284Z","caller":"traceutil/trace.go:171","msg":"trace[345170972] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"133.843412ms","start":"2026-04-13T14:31:35.78939Z","end":"2026-04-13T14:31:35.923233Z","steps":["trace[345170972] 'process raft request' (duration: 133.610917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.008837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:12.658897Z","time spent":"349.935885ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T14:32:13.477804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.092263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:32:13.47799Z","caller":"traceutil/trace.go:171","msg":"trace[587809459] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"467.885025ms","start":"2026-04-13T14:32:13.010088Z","end":"2026-04-13T14:32:13.477973Z","steps":["trace[587809459] 'process raft request' (duration: 467.818653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.478053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:13.01007Z","time spent":"467.951947ms","remote":"127.0.0.1:38146","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-04-13T14:32:13.478048Z","caller":"traceutil/trace.go:171","msg":"trace[1248859455] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"646.206287ms","start":"2026-04-13T14:32:12.831794Z","end":"2026-04-13T14:32:13.478Z","steps":["trace[1248859455] 'process raft request' (duration: 284.663232ms)","trace[1248859455] 'compare' (duration: 360.921428ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:13.478122Z","caller":"traceutil/trace.go:171","msg":"trace[1835599719] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"643.399976ms","start":"2026-04-13T14:32:12.834662Z","end":"2026-04-13T14:32:13.478062Z","steps":["trace[1835599719] 'read index received' (duration: 174.031143ms)","trace[1835599719] 'applied index is now lower than readState.Index' (duration: 469.363413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:13.478225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:12.831751Z","time spent":"646.382081ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:32:13.478538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.734865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T14:32:13.478534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.841987ms","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-04-13T14:32:13.478574Z","caller":"traceutil/trace.go:171","msg":"trace[1770442749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"637.801627ms","start":"2026-04-13T14:32:12.840761Z","end":"2026-04-13T14:32:13.478563Z","steps":["trace[1770442749] 'agreement among raft nodes before linearized reading' (duration: 637.658473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.478557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.868168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:32:13.478609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:12.840749Z","time spent":"637.854468ms","remote":"127.0.0.1:38324","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-04-13T14:32:13.478609Z","caller":"traceutil/trace.go:171","msg":"trace[817584609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"643.961871ms","start":"2026-04-13T14:32:12.834608Z","end":"2026-04-13T14:32:13.47857Z","steps":["trace[817584609] 'agreement among raft nodes before linearized reading' (duration: 643.672474ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:13.478648Z","caller":"traceutil/trace.go:171","msg":"trace[1529358322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"611.982461ms","start":"2026-04-13T14:32:12.866639Z","end":"2026-04-13T14:32:13.478621Z","steps":["trace[1529358322] 'agreement among raft nodes before linearized reading' (duration: 611.805247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.478666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:12.834586Z","time spent":"644.066983ms","remote":"127.0.0.1:38324","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-04-13T14:32:13.4787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.875512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T14:32:13.47872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:12.86663Z","time spent":"612.081443ms","remote":"127.0.0.1:38112","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-04-13T14:32:13.478781Z","caller":"traceutil/trace.go:171","msg":"trace[673878657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"263.997264ms","start":"2026-04-13T14:32:13.214749Z","end":"2026-04-13T14:32:13.478746Z","steps":["trace[673878657] 'agreement among raft nodes before linearized reading' (duration: 263.784279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.478857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.232891ms","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-04-13T14:32:13.478902Z","caller":"traceutil/trace.go:171","msg":"trace[1370137035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"633.276773ms","start":"2026-04-13T14:32:12.845613Z","end":"2026-04-13T14:32:13.478889Z","steps":["trace[1370137035] 'agreement among raft nodes before linearized reading' (duration: 633.119369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.47894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:12.845541Z","time spent":"633.391506ms","remote":"127.0.0.1:38324","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-04-13T14:32:13.771575Z","caller":"traceutil/trace.go:171","msg":"trace[560976459] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"288.96117ms","start":"2026-04-13T14:32:13.482593Z","end":"2026-04-13T14:32:13.771555Z","steps":["trace[560976459] 'process raft request' (duration: 288.824346ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:13.771886Z","caller":"traceutil/trace.go:171","msg":"trace[698982001] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1057; }","duration":"287.573613ms","start":"2026-04-13T14:32:13.484256Z","end":"2026-04-13T14:32:13.771829Z","steps":["trace[698982001] 'read index received' (duration: 71.840436ms)","trace[698982001] 'applied index is now lower than readState.Index' (duration: 215.732277ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:13.771928Z","caller":"traceutil/trace.go:171","msg":"trace[1705010879] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"289.079502ms","start":"2026-04-13T14:32:13.48282Z","end":"2026-04-13T14:32:13.7719Z","steps":["trace[1705010879] 'process raft request' (duration: 288.892547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:13.771985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.716039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-13T14:32:13.772024Z","caller":"traceutil/trace.go:171","msg":"trace[423735840] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1022; }","duration":"287.78263ms","start":"2026-04-13T14:32:13.484231Z","end":"2026-04-13T14:32:13.772013Z","steps":["trace[423735840] 'agreement among raft nodes before linearized reading' (duration: 287.687807ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:13.772719Z","caller":"traceutil/trace.go:171","msg":"trace[1395018216] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"196.375363ms","start":"2026-04-13T14:32:13.576331Z","end":"2026-04-13T14:32:13.772707Z","steps":["trace[1395018216] 'process raft request' (duration: 196.322752ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:13.773149Z","caller":"traceutil/trace.go:171","msg":"trace[1127554308] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"289.00041ms","start":"2026-04-13T14:32:13.482789Z","end":"2026-04-13T14:32:13.771789Z","steps":["trace[1127554308] 'process raft request' (duration: 288.735834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:17.931248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.671959ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T14:32:17.931337Z","caller":"traceutil/trace.go:171","msg":"trace[1539617800] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"146.270339ms","start":"2026-04-13T14:32:17.785041Z","end":"2026-04-13T14:32:17.931312Z","steps":["trace[1539617800] 'read index received' (duration: 9.465107ms)","trace[1539617800] 'applied index is now lower than readState.Index' (duration: 136.804162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:17.931479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.434423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T14:32:17.931478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.622088ms","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-04-13T14:32:17.931501Z","caller":"traceutil/trace.go:171","msg":"trace[649156606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"146.491665ms","start":"2026-04-13T14:32:17.785002Z","end":"2026-04-13T14:32:17.931494Z","steps":["trace[649156606] 'agreement among raft nodes before linearized reading' (duration: 146.381402ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:17.931523Z","caller":"traceutil/trace.go:171","msg":"trace[1524940778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"142.675379ms","start":"2026-04-13T14:32:17.788834Z","end":"2026-04-13T14:32:17.931509Z","steps":["trace[1524940778] 'agreement among raft nodes before linearized reading' (duration: 142.569626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:17.931612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.158556ms","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-04-13T14:32:17.931683Z","caller":"traceutil/trace.go:171","msg":"trace[446143279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"142.272858ms","start":"2026-04-13T14:32:17.789395Z","end":"2026-04-13T14:32:17.931667Z","steps":["trace[446143279] 'agreement among raft nodes before linearized reading' (duration: 142.070403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:36.305908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.183901ms","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-04-13T14:32:36.306035Z","caller":"traceutil/trace.go:171","msg":"trace[424427070] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1091; }","duration":"153.321095ms","start":"2026-04-13T14:32:36.152695Z","end":"2026-04-13T14:32:36.306017Z","steps":["trace[424427070] 'range keys from in-memory index tree' (duration: 153.169021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:36.3063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.700614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:32:36.30642Z","caller":"traceutil/trace.go:171","msg":"trace[2583871] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"439.758901ms","start":"2026-04-13T14:32:35.866641Z","end":"2026-04-13T14:32:36.3064Z","steps":["trace[2583871] 'read index received' (duration: 20.958344ms)","trace[2583871] 'applied index is now lower than readState.Index' (duration: 418.799087ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:36.306508Z","caller":"traceutil/trace.go:171","msg":"trace[868660285] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"549.750734ms","start":"2026-04-13T14:32:35.756731Z","end":"2026-04-13T14:32:36.306482Z","steps":["trace[868660285] 'process raft request' (duration: 130.756132ms)","trace[868660285] 'compare' (duration: 418.582741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:36.306563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.908825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:32:36.306631Z","caller":"traceutil/trace.go:171","msg":"trace[2076240892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"440.013777ms","start":"2026-04-13T14:32:35.866603Z","end":"2026-04-13T14:32:36.306616Z","steps":["trace[2076240892] 'agreement among raft nodes before linearized reading' (duration: 439.893164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:36.30665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.876102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T14:32:36.306732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.47938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:32:36.306752Z","caller":"traceutil/trace.go:171","msg":"trace[950920581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"236.068986ms","start":"2026-04-13T14:32:36.070668Z","end":"2026-04-13T14:32:36.306737Z","steps":["trace[950920581] 'agreement among raft nodes before linearized reading' (duration: 235.862061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:36.306749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:35.756708Z","time spent":"549.957009ms","remote":"127.0.0.1:38324","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-04-13T14:32:36.306788Z","caller":"traceutil/trace.go:171","msg":"trace[1031279892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1092; }","duration":"161.555022ms","start":"2026-04-13T14:32:36.145201Z","end":"2026-04-13T14:32:36.306756Z","steps":["trace[1031279892] 'agreement among raft nodes before linearized reading' (duration: 161.506141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:36.306664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:35.866583Z","time spent":"440.073109ms","remote":"127.0.0.1:38118","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-04-13T14:32:36.306988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.184598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T14:32:36.307032Z","caller":"traceutil/trace.go:171","msg":"trace[695672947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"218.26369ms","start":"2026-04-13T14:32:36.088754Z","end":"2026-04-13T14:32:36.307018Z","steps":["trace[695672947] 'agreement among raft nodes before linearized reading' (duration: 218.129077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:36.307133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.797359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T14:32:36.307188Z","caller":"traceutil/trace.go:171","msg":"trace[1153633227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"217.875221ms","start":"2026-04-13T14:32:36.089298Z","end":"2026-04-13T14:32:36.307173Z","steps":["trace[1153633227] 'agreement among raft nodes before linearized reading' (duration: 217.705927ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:36.51783Z","caller":"traceutil/trace.go:171","msg":"trace[2070189336] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"115.810767ms","start":"2026-04-13T14:32:36.401887Z","end":"2026-04-13T14:32:36.517698Z","steps":["trace[2070189336] 'process raft request' (duration: 85.245262ms)","trace[2070189336] 'compare' (duration: 30.38292ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:38.087283Z","caller":"traceutil/trace.go:171","msg":"trace[1569619828] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"329.227055ms","start":"2026-04-13T14:32:37.758035Z","end":"2026-04-13T14:32:38.087262Z","steps":["trace[1569619828] 'process raft request' (duration: 329.098262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:38.087411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:37.758006Z","time spent":"329.334978ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:32:38.171706Z","caller":"traceutil/trace.go:171","msg":"trace[1916519331] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1139; }","duration":"304.313172ms","start":"2026-04-13T14:32:37.867377Z","end":"2026-04-13T14:32:38.17169Z","steps":["trace[1916519331] 'read index received' (duration: 219.643164ms)","trace[1916519331] 'applied index is now lower than readState.Index' (duration: 84.669478ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:38.171754Z","caller":"traceutil/trace.go:171","msg":"trace[756671535] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"350.252362ms","start":"2026-04-13T14:32:37.82148Z","end":"2026-04-13T14:32:38.171733Z","steps":["trace[756671535] 'process raft request' (duration: 350.118219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:38.172023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:37.82145Z","time spent":"350.355434ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:32:38.197184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.759059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:32:38.19743Z","caller":"traceutil/trace.go:171","msg":"trace[2016983567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"330.067467ms","start":"2026-04-13T14:32:37.867337Z","end":"2026-04-13T14:32:38.197404Z","steps":["trace[2016983567] 'agreement among raft nodes before linearized reading' (duration: 304.424715ms)","trace[2016983567] 'range keys from in-memory index tree' (duration: 25.334684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:32:38.197663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:37.867323Z","time spent":"330.306653ms","remote":"127.0.0.1:38112","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-04-13T14:32:38.455594Z","caller":"traceutil/trace.go:171","msg":"trace[1138964006] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"123.032778ms","start":"2026-04-13T14:32:38.332522Z","end":"2026-04-13T14:32:38.455555Z","steps":["trace[1138964006] 'process raft request' (duration: 113.936459ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:38.455749Z","caller":"traceutil/trace.go:171","msg":"trace[751097281] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1143; }","duration":"122.735581ms","start":"2026-04-13T14:32:38.33298Z","end":"2026-04-13T14:32:38.455715Z","steps":["trace[751097281] 'read index received' (duration: 8.087992ms)","trace[751097281] 'applied index is now lower than readState.Index' (duration: 114.644898ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:38.45599Z","caller":"traceutil/trace.go:171","msg":"trace[600828982] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"122.905945ms","start":"2026-04-13T14:32:38.333063Z","end":"2026-04-13T14:32:38.455968Z","steps":["trace[600828982] 'process raft request' (duration: 122.404542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:38.456002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.917605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:32:38.456123Z","caller":"traceutil/trace.go:171","msg":"trace[902523725] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1102; }","duration":"123.139011ms","start":"2026-04-13T14:32:38.332961Z","end":"2026-04-13T14:32:38.4561Z","steps":["trace[902523725] 'agreement among raft nodes before linearized reading' (duration: 122.842574ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:44.550106Z","caller":"traceutil/trace.go:171","msg":"trace[1413460721] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"153.762167ms","start":"2026-04-13T14:32:44.396317Z","end":"2026-04-13T14:32:44.550079Z","steps":["trace[1413460721] 'process raft request' (duration: 122.68428ms)","trace[1413460721] 'compare' (duration: 30.981225ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:44.551256Z","caller":"traceutil/trace.go:171","msg":"trace[1989995814] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"154.524816ms","start":"2026-04-13T14:32:44.39671Z","end":"2026-04-13T14:32:44.551235Z","steps":["trace[1989995814] 'process raft request' (duration: 154.239499ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:44.820276Z","caller":"traceutil/trace.go:171","msg":"trace[589717818] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"265.00184ms","start":"2026-04-13T14:32:44.555254Z","end":"2026-04-13T14:32:44.820256Z","steps":["trace[589717818] 'process raft request' (duration: 184.405213ms)","trace[589717818] 'compare' (duration: 80.416352ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:44.820269Z","caller":"traceutil/trace.go:171","msg":"trace[312599753] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1165; }","duration":"256.453036ms","start":"2026-04-13T14:32:44.563794Z","end":"2026-04-13T14:32:44.820247Z","steps":["trace[312599753] 'read index received' (duration: 175.961443ms)","trace[312599753] 'applied index is now lower than readState.Index' (duration: 80.490733ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:32:44.820439Z","caller":"traceutil/trace.go:171","msg":"trace[541152141] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"264.646801ms","start":"2026-04-13T14:32:44.555783Z","end":"2026-04-13T14:32:44.82043Z","steps":["trace[541152141] 'process raft request' (duration: 264.417255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:44.820493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.674082ms","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-04-13T14:32:44.820585Z","caller":"traceutil/trace.go:171","msg":"trace[1226350146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1122; }","duration":"256.774524ms","start":"2026-04-13T14:32:44.563758Z","end":"2026-04-13T14:32:44.820533Z","steps":["trace[1226350146] 'agreement among raft nodes before linearized reading' (duration: 256.553779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:51.633749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.696215ms","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-04-13T14:32:51.633846Z","caller":"traceutil/trace.go:171","msg":"trace[483264794] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1194; }","duration":"135.846449ms","start":"2026-04-13T14:32:51.497975Z","end":"2026-04-13T14:32:51.633821Z","steps":["trace[483264794] 'count revisions from in-memory index tree' (duration: 135.604922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:53.117381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.27406ms","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-04-13T14:32:53.117451Z","caller":"traceutil/trace.go:171","msg":"trace[567944044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1195; }","duration":"134.396033ms","start":"2026-04-13T14:32:52.983039Z","end":"2026-04-13T14:32:53.117435Z","steps":["trace[567944044] 'range keys from in-memory index tree' (duration: 134.120944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:53.117445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.801621ms","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-04-13T14:32:53.117445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.179889ms","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-04-13T14:32:53.117494Z","caller":"traceutil/trace.go:171","msg":"trace[1828935281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"249.887322ms","start":"2026-04-13T14:32:52.867596Z","end":"2026-04-13T14:32:53.117483Z","steps":["trace[1828935281] 'range keys from in-memory index tree' (duration: 249.684318ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:32:53.117517Z","caller":"traceutil/trace.go:171","msg":"trace[755969535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"220.291402ms","start":"2026-04-13T14:32:52.897213Z","end":"2026-04-13T14:32:53.117505Z","steps":["trace[755969535] 'range keys from in-memory index tree' (duration: 220.065336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:53.117697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.437154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T14:32:53.117719Z","caller":"traceutil/trace.go:171","msg":"trace[506052913] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1195; }","duration":"459.499916ms","start":"2026-04-13T14:32:52.658214Z","end":"2026-04-13T14:32:53.117714Z","steps":["trace[506052913] 'range keys from in-memory index tree' (duration: 459.31351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:53.117734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:52.658185Z","time spent":"459.544566ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.191\" "} {"level":"warn","ts":"2026-04-13T14:32:53.117676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.865291ms","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-04-13T14:32:53.117791Z","caller":"traceutil/trace.go:171","msg":"trace[1398372956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"535.022615ms","start":"2026-04-13T14:32:52.582759Z","end":"2026-04-13T14:32:53.117782Z","steps":["trace[1398372956] 'range keys from in-memory index tree' (duration: 534.651586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:53.117811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.033328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T14:32:53.117826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:52.582689Z","time spent":"535.126607ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:32:53.117852Z","caller":"traceutil/trace.go:171","msg":"trace[1974693137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"693.10343ms","start":"2026-04-13T14:32:52.424734Z","end":"2026-04-13T14:32:53.117838Z","steps":["trace[1974693137] 'range keys from in-memory index tree' (duration: 692.928086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:32:53.117886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:32:52.424714Z","time spent":"693.165192ms","remote":"127.0.0.1:38324","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-04-13T14:33:09.333394Z","caller":"traceutil/trace.go:171","msg":"trace[680519288] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"253.532293ms","start":"2026-04-13T14:33:09.079826Z","end":"2026-04-13T14:33:09.333358Z","steps":["trace[680519288] 'process raft request' (duration: 253.345478ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:09.333554Z","caller":"traceutil/trace.go:171","msg":"trace[1777211016] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1319; }","duration":"170.147677ms","start":"2026-04-13T14:33:09.163381Z","end":"2026-04-13T14:33:09.333529Z","steps":["trace[1777211016] 'read index received' (duration: 169.735617ms)","trace[1777211016] 'applied index is now lower than readState.Index' (duration: 410.04µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:09.333643Z","caller":"traceutil/trace.go:171","msg":"trace[61573783] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"248.491787ms","start":"2026-04-13T14:33:09.085113Z","end":"2026-04-13T14:33:09.333605Z","steps":["trace[61573783] 'process raft request' (duration: 248.168329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:09.333803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.397574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:33:09.333846Z","caller":"traceutil/trace.go:171","msg":"trace[1750270803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1269; }","duration":"170.525236ms","start":"2026-04-13T14:33:09.163309Z","end":"2026-04-13T14:33:09.333834Z","steps":["trace[1750270803] 'agreement among raft nodes before linearized reading' (duration: 170.386573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:09.334353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.358807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:33:09.334347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.140354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T14:33:09.334382Z","caller":"traceutil/trace.go:171","msg":"trace[1769073597] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1269; }","duration":"165.416379ms","start":"2026-04-13T14:33:09.168959Z","end":"2026-04-13T14:33:09.334375Z","steps":["trace[1769073597] 'agreement among raft nodes before linearized reading' (duration: 165.224084ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:09.334395Z","caller":"traceutil/trace.go:171","msg":"trace[236553015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"100.218927ms","start":"2026-04-13T14:33:09.234163Z","end":"2026-04-13T14:33:09.334382Z","steps":["trace[236553015] 'agreement among raft nodes before linearized reading' (duration: 100.076233ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:09.445218Z","caller":"traceutil/trace.go:171","msg":"trace[2031204562] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"106.475094ms","start":"2026-04-13T14:33:09.338722Z","end":"2026-04-13T14:33:09.445197Z","steps":["trace[2031204562] 'process raft request' (duration: 89.766226ms)","trace[2031204562] 'compare' (duration: 16.566515ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:09.445544Z","caller":"traceutil/trace.go:171","msg":"trace[1800258005] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"106.573307ms","start":"2026-04-13T14:33:09.338963Z","end":"2026-04-13T14:33:09.445536Z","steps":["trace[1800258005] 'process raft request' (duration: 106.196487ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:09.445599Z","caller":"traceutil/trace.go:171","msg":"trace[512647961] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"106.571487ms","start":"2026-04-13T14:33:09.339003Z","end":"2026-04-13T14:33:09.445574Z","steps":["trace[512647961] 'process raft request' (duration: 106.519725ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:09.445792Z","caller":"traceutil/trace.go:171","msg":"trace[2008116814] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"106.808362ms","start":"2026-04-13T14:33:09.338971Z","end":"2026-04-13T14:33:09.44578Z","steps":["trace[2008116814] 'process raft request' (duration: 106.450993ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:10.427857Z","caller":"traceutil/trace.go:171","msg":"trace[2041874199] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"216.050965ms","start":"2026-04-13T14:33:10.211786Z","end":"2026-04-13T14:33:10.427837Z","steps":["trace[2041874199] 'process raft request' (duration: 215.916591ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:11.477247Z","caller":"traceutil/trace.go:171","msg":"trace[816793074] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"330.491818ms","start":"2026-04-13T14:33:11.146721Z","end":"2026-04-13T14:33:11.477213Z","steps":["trace[816793074] 'process raft request' (duration: 303.006021ms)","trace[816793074] 'compare' (duration: 27.347623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:11.4774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.146704Z","time spent":"330.626571ms","remote":"127.0.0.1:38178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:33:12.19683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.225775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:33:12.196965Z","caller":"traceutil/trace.go:171","msg":"trace[1763601562] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"1.029842574s","start":"2026-04-13T14:33:11.167098Z","end":"2026-04-13T14:33:12.19694Z","steps":["trace[1763601562] 'read index received' (duration: 282.611531ms)","trace[1763601562] 'applied index is now lower than readState.Index' (duration: 747.229603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:12.197461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.197208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T14:33:12.197441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.013497ms","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-04-13T14:33:12.197507Z","caller":"traceutil/trace.go:171","msg":"trace[1646481415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"748.291771ms","start":"2026-04-13T14:33:11.449203Z","end":"2026-04-13T14:33:12.197495Z","steps":["trace[1646481415] 'agreement among raft nodes before linearized reading' (duration: 748.120566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:12.197511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030395937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:33:12.19755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.449178Z","time spent":"748.364093ms","remote":"127.0.0.1:38324","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-04-13T14:33:12.197551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.981435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:33:12.197555Z","caller":"traceutil/trace.go:171","msg":"trace[1928820190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"745.168312ms","start":"2026-04-13T14:33:11.452363Z","end":"2026-04-13T14:33:12.197531Z","steps":["trace[1928820190] 'agreement among raft nodes before linearized reading' (duration: 744.837953ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.197557Z","caller":"traceutil/trace.go:171","msg":"trace[1131180739] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"1.05050676s","start":"2026-04-13T14:33:11.147028Z","end":"2026-04-13T14:33:12.197535Z","steps":["trace[1131180739] 'process raft request' (duration: 467.441023ms)","trace[1131180739] 'compare' (duration: 581.968829ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:12.197586Z","caller":"traceutil/trace.go:171","msg":"trace[986082184] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1280; }","duration":"1.030514811s","start":"2026-04-13T14:33:11.167055Z","end":"2026-04-13T14:33:12.19757Z","steps":["trace[986082184] 'agreement among raft nodes before linearized reading' (duration: 1.030392117s)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.197606Z","caller":"traceutil/trace.go:171","msg":"trace[594746889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"324.068898ms","start":"2026-04-13T14:33:11.87352Z","end":"2026-04-13T14:33:12.197589Z","steps":["trace[594746889] 'agreement among raft nodes before linearized reading' (duration: 323.985166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:12.197611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.452346Z","time spent":"745.255384ms","remote":"127.0.0.1:38324","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-04-13T14:33:12.197626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.167034Z","time spent":"1.030584562s","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:33:12.197618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.231164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T14:33:12.197648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.873502Z","time spent":"324.13642ms","remote":"127.0.0.1:38112","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-04-13T14:33:12.197671Z","caller":"traceutil/trace.go:171","msg":"trace[387495294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"747.301355ms","start":"2026-04-13T14:33:11.450354Z","end":"2026-04-13T14:33:12.197655Z","steps":["trace[387495294] 'agreement among raft nodes before linearized reading' (duration: 747.091579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:12.197674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.147008Z","time spent":"1.050611053s","remote":"127.0.0.1:38324","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-04-13T14:33:12.197711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.450345Z","time spent":"747.355437ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:33:12.197457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.527161ms","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-04-13T14:33:12.197766Z","caller":"traceutil/trace.go:171","msg":"trace[1092184735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"747.87363ms","start":"2026-04-13T14:33:11.44988Z","end":"2026-04-13T14:33:12.197754Z","steps":["trace[1092184735] 'agreement among raft nodes before linearized reading' (duration: 747.413897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:12.197813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.449869Z","time spent":"747.934581ms","remote":"127.0.0.1:38324","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-04-13T14:33:12.197344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.906236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T14:33:12.198087Z","caller":"traceutil/trace.go:171","msg":"trace[1013836102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"837.702197ms","start":"2026-04-13T14:33:11.360373Z","end":"2026-04-13T14:33:12.198075Z","steps":["trace[1013836102] 'agreement among raft nodes before linearized reading' (duration: 836.778953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:12.19813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:11.360355Z","time spent":"837.764159ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:33:12.40538Z","caller":"traceutil/trace.go:171","msg":"trace[1546766207] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"202.003094ms","start":"2026-04-13T14:33:12.203335Z","end":"2026-04-13T14:33:12.405338Z","steps":["trace[1546766207] 'process raft request' (duration: 169.776407ms)","trace[1546766207] 'compare' (duration: 31.96384ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:12.463046Z","caller":"traceutil/trace.go:171","msg":"trace[552967875] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"259.537482ms","start":"2026-04-13T14:33:12.203482Z","end":"2026-04-13T14:33:12.46302Z","steps":["trace[552967875] 'process raft request' (duration: 259.270095ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.463285Z","caller":"traceutil/trace.go:171","msg":"trace[719008527] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"259.497551ms","start":"2026-04-13T14:33:12.203778Z","end":"2026-04-13T14:33:12.463275Z","steps":["trace[719008527] 'process raft request' (duration: 259.202434ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.463617Z","caller":"traceutil/trace.go:171","msg":"trace[1734601535] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"259.667236ms","start":"2026-04-13T14:33:12.20392Z","end":"2026-04-13T14:33:12.463588Z","steps":["trace[1734601535] 'process raft request' (duration: 259.309327ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.463649Z","caller":"traceutil/trace.go:171","msg":"trace[712118165] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"259.480632ms","start":"2026-04-13T14:33:12.204148Z","end":"2026-04-13T14:33:12.463628Z","steps":["trace[712118165] 'process raft request' (duration: 259.334798ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.463803Z","caller":"traceutil/trace.go:171","msg":"trace[1541660724] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"258.754924ms","start":"2026-04-13T14:33:12.205035Z","end":"2026-04-13T14:33:12.46379Z","steps":["trace[1541660724] 'process raft request' (duration: 258.549938ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.463826Z","caller":"traceutil/trace.go:171","msg":"trace[399570675] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1332; }","duration":"259.935934ms","start":"2026-04-13T14:33:12.203871Z","end":"2026-04-13T14:33:12.463807Z","steps":["trace[399570675] 'read index received' (duration: 169.193603ms)","trace[399570675] 'applied index is now lower than readState.Index' (duration: 90.74026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:12.463995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.104118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-13T14:33:12.464037Z","caller":"traceutil/trace.go:171","msg":"trace[901781696] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1286; }","duration":"260.179809ms","start":"2026-04-13T14:33:12.203847Z","end":"2026-04-13T14:33:12.464026Z","steps":["trace[901781696] 'agreement among raft nodes before linearized reading' (duration: 260.035495ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:12.593228Z","caller":"traceutil/trace.go:171","msg":"trace[37720300] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"123.243693ms","start":"2026-04-13T14:33:12.469967Z","end":"2026-04-13T14:33:12.59321Z","steps":["trace[37720300] 'process raft request' (duration: 123.13611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:12.806717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.76169ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-13T14:33:22.676755Z","caller":"traceutil/trace.go:171","msg":"trace[699898519] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"116.516581ms","start":"2026-04-13T14:33:22.560214Z","end":"2026-04-13T14:33:22.67673Z","steps":["trace[699898519] 'read index received' (duration: 116.365147ms)","trace[699898519] 'applied index is now lower than readState.Index' (duration: 150.274µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:22.676802Z","caller":"traceutil/trace.go:171","msg":"trace[951818830] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"119.837804ms","start":"2026-04-13T14:33:22.556942Z","end":"2026-04-13T14:33:22.67678Z","steps":["trace[951818830] 'process raft request' (duration: 119.631279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:22.677089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.862209ms","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-04-13T14:33:22.677142Z","caller":"traceutil/trace.go:171","msg":"trace[1111956926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"116.932301ms","start":"2026-04-13T14:33:22.560196Z","end":"2026-04-13T14:33:22.677129Z","steps":["trace[1111956926] 'agreement among raft nodes before linearized reading' (duration: 116.667094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.599214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.342664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:33:23.59938Z","caller":"traceutil/trace.go:171","msg":"trace[2092692400] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.036691764s","start":"2026-04-13T14:33:22.562655Z","end":"2026-04-13T14:33:23.599347Z","steps":["trace[2092692400] 'process raft request' (duration: 370.113614ms)","trace[2092692400] 'compare' (duration: 666.048866ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:23.599442Z","caller":"traceutil/trace.go:171","msg":"trace[1420534015] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"922.604154ms","start":"2026-04-13T14:33:22.676829Z","end":"2026-04-13T14:33:23.599434Z","steps":["trace[1420534015] 'read index received' (duration: 255.953752ms)","trace[1420534015] 'applied index is now lower than readState.Index' (duration: 666.649432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:23.599531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.561977Z","time spent":"1.037475304s","remote":"127.0.0.1:38324","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-04-13T14:33:23.599405Z","caller":"traceutil/trace.go:171","msg":"trace[19402750] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"918.922392ms","start":"2026-04-13T14:33:22.680462Z","end":"2026-04-13T14:33:23.599385Z","steps":["trace[19402750] 'process raft request' (duration: 918.85742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.599752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.354556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:33:23.599774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.680442Z","time spent":"919.297551ms","remote":"127.0.0.1:38324","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-04-13T14:33:23.599793Z","caller":"traceutil/trace.go:171","msg":"trace[351390376] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1357; }","duration":"957.447208ms","start":"2026-04-13T14:33:22.642335Z","end":"2026-04-13T14:33:23.599782Z","steps":["trace[351390376] 'agreement among raft nodes before linearized reading' (duration: 957.390956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.599832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.64231Z","time spent":"957.51283ms","remote":"127.0.0.1:55610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:33:23.599708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.635956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T14:33:23.599937Z","caller":"traceutil/trace.go:171","msg":"trace[1849533077] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1357; }","duration":"939.911223ms","start":"2026-04-13T14:33:22.660013Z","end":"2026-04-13T14:33:23.599924Z","steps":["trace[1849533077] 'agreement among raft nodes before linearized reading' (duration: 939.598385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.599979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.659985Z","time spent":"939.984654ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.191\" "} {"level":"warn","ts":"2026-04-13T14:33:23.600084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031866744s","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-04-13T14:33:23.600118Z","caller":"traceutil/trace.go:171","msg":"trace[799459823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"1.031934047s","start":"2026-04-13T14:33:22.568176Z","end":"2026-04-13T14:33:23.60011Z","steps":["trace[799459823] 'agreement among raft nodes before linearized reading' (duration: 1.031822934s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.600149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.568153Z","time spent":"1.031989567s","remote":"127.0.0.1:38324","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-04-13T14:33:23.600338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033030154s","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-04-13T14:33:23.600389Z","caller":"traceutil/trace.go:171","msg":"trace[1711844781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.033110735s","start":"2026-04-13T14:33:22.567266Z","end":"2026-04-13T14:33:23.600377Z","steps":["trace[1711844781] 'agreement among raft nodes before linearized reading' (duration: 1.032984812s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.600422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.567255Z","time spent":"1.033159436s","remote":"127.0.0.1:38324","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-04-13T14:33:23.600549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034157981s","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-04-13T14:33:23.600585Z","caller":"traceutil/trace.go:171","msg":"trace[1304141877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.034195122s","start":"2026-04-13T14:33:22.566382Z","end":"2026-04-13T14:33:23.600577Z","steps":["trace[1304141877] 'agreement among raft nodes before linearized reading' (duration: 1.03410728s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.600642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.566296Z","time spent":"1.034339685s","remote":"127.0.0.1:38324","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-04-13T14:33:23.600652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.481148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-13T14:33:23.60069Z","caller":"traceutil/trace.go:171","msg":"trace[1776162415] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"909.55761ms","start":"2026-04-13T14:33:22.691122Z","end":"2026-04-13T14:33:23.60068Z","steps":["trace[1776162415] 'agreement among raft nodes before linearized reading' (duration: 909.427466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.60073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.691101Z","time spent":"909.621952ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T14:33:23.600833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.44776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:33:23.600861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.034517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-13T14:33:23.601007Z","caller":"traceutil/trace.go:171","msg":"trace[1791549560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"733.661535ms","start":"2026-04-13T14:33:22.867326Z","end":"2026-04-13T14:33:23.600988Z","steps":["trace[1791549560] 'agreement among raft nodes before linearized reading' (duration: 733.45861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.601139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.867304Z","time spent":"733.816978ms","remote":"127.0.0.1:38112","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-04-13T14:33:23.601058Z","caller":"traceutil/trace.go:171","msg":"trace[300067346] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"772.266422ms","start":"2026-04-13T14:33:22.828775Z","end":"2026-04-13T14:33:23.601041Z","steps":["trace[300067346] 'agreement among raft nodes before linearized reading' (duration: 772.022326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.601324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:22.828745Z","time spent":"772.56014ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-13T14:33:23.827211Z","caller":"traceutil/trace.go:171","msg":"trace[1882539080] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"222.811209ms","start":"2026-04-13T14:33:23.604376Z","end":"2026-04-13T14:33:23.827187Z","steps":["trace[1882539080] 'process raft request' (duration: 222.520072ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:23.827488Z","caller":"traceutil/trace.go:171","msg":"trace[1990079574] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"222.855141ms","start":"2026-04-13T14:33:23.604601Z","end":"2026-04-13T14:33:23.827456Z","steps":["trace[1990079574] 'process raft request' (duration: 222.502372ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:23.827889Z","caller":"traceutil/trace.go:171","msg":"trace[1495913353] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"223.105075ms","start":"2026-04-13T14:33:23.604774Z","end":"2026-04-13T14:33:23.82788Z","steps":["trace[1495913353] 'process raft request' (duration: 222.565382ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:23.827922Z","caller":"traceutil/trace.go:171","msg":"trace[1286298655] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1412; }","duration":"162.495191ms","start":"2026-04-13T14:33:23.665402Z","end":"2026-04-13T14:33:23.827897Z","steps":["trace[1286298655] 'read index received' (duration: 161.558439ms)","trace[1286298655] 'applied index is now lower than readState.Index' (duration: 935.492µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:33:23.82798Z","caller":"traceutil/trace.go:171","msg":"trace[1521516685] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"222.383638ms","start":"2026-04-13T14:33:23.605589Z","end":"2026-04-13T14:33:23.827973Z","steps":["trace[1521516685] 'process raft request' (duration: 222.218744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:23.828088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.653635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:33:23.828132Z","caller":"traceutil/trace.go:171","msg":"trace[1241558853] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1363; }","duration":"162.745928ms","start":"2026-04-13T14:33:23.665373Z","end":"2026-04-13T14:33:23.828119Z","steps":["trace[1241558853] 'agreement among raft nodes before linearized reading' (duration: 162.621145ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:23.828186Z","caller":"traceutil/trace.go:171","msg":"trace[908117875] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"223.156996ms","start":"2026-04-13T14:33:23.605021Z","end":"2026-04-13T14:33:23.828178Z","steps":["trace[908117875] 'process raft request' (duration: 222.679465ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:23.828322Z","caller":"traceutil/trace.go:171","msg":"trace[1167633530] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"179.304849ms","start":"2026-04-13T14:33:23.64901Z","end":"2026-04-13T14:33:23.828315Z","steps":["trace[1167633530] 'process raft request' (duration: 178.841967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:58.382992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911153826344954,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:33:58.409363Z","caller":"traceutil/trace.go:171","msg":"trace[657861114] linearizableReadLoop","detail":"{readStateIndex:1577; appliedIndex:1576; }","duration":"554.629172ms","start":"2026-04-13T14:33:57.854714Z","end":"2026-04-13T14:33:58.409343Z","steps":["trace[657861114] 'read index received' (duration: 528.421331ms)","trace[657861114] 'applied index is now lower than readState.Index' (duration: 26.206841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:58.409437Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":13903911153826344957,"received-request-id":13903911153826344954} {"level":"info","ts":"2026-04-13T14:33:58.40953Z","caller":"traceutil/trace.go:171","msg":"trace[1183617245] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"556.674503ms","start":"2026-04-13T14:33:57.852842Z","end":"2026-04-13T14:33:58.409517Z","steps":["trace[1183617245] 'process raft request' (duration: 529.896738ms)","trace[1183617245] 'compare' (duration: 26.455547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:58.409626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:57.852825Z","time spent":"556.733475ms","remote":"127.0.0.1:38324","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-04-13T14:33:58.433734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.039678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:33:58.433829Z","caller":"traceutil/trace.go:171","msg":"trace[146113296] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1516; }","duration":"579.190171ms","start":"2026-04-13T14:33:57.854607Z","end":"2026-04-13T14:33:58.433797Z","steps":["trace[146113296] 'agreement among raft nodes before linearized reading' (duration: 555.191546ms)","trace[146113296] 'count revisions from in-memory index tree' (duration: 23.868012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:33:58.43388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:57.854584Z","time spent":"579.279394ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:33:58.433887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.208434ms","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-04-13T14:33:58.433871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.212316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T14:33:58.433917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.53969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T14:33:58.433939Z","caller":"traceutil/trace.go:171","msg":"trace[552562521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1516; }","duration":"380.272075ms","start":"2026-04-13T14:33:58.053652Z","end":"2026-04-13T14:33:58.433924Z","steps":["trace[552562521] 'agreement among raft nodes before linearized reading' (duration: 380.171073ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:58.433947Z","caller":"traceutil/trace.go:171","msg":"trace[1190453009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1516; }","duration":"397.35807ms","start":"2026-04-13T14:33:58.03658Z","end":"2026-04-13T14:33:58.433938Z","steps":["trace[1190453009] 'agreement among raft nodes before linearized reading' (duration: 397.098753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:58.433973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:58.03656Z","time spent":"397.40694ms","remote":"127.0.0.1:38324","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-04-13T14:33:58.433974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:58.053644Z","time spent":"380.323186ms","remote":"127.0.0.1:38324","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-04-13T14:33:58.433971Z","caller":"traceutil/trace.go:171","msg":"trace[1559023225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1516; }","duration":"369.625302ms","start":"2026-04-13T14:33:58.064333Z","end":"2026-04-13T14:33:58.433959Z","steps":["trace[1559023225] 'agreement among raft nodes before linearized reading' (duration: 369.462758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:33:58.434003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:33:58.064322Z","time spent":"369.675503ms","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:33:58.568295Z","caller":"traceutil/trace.go:171","msg":"trace[799186268] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"129.990435ms","start":"2026-04-13T14:33:58.438282Z","end":"2026-04-13T14:33:58.568272Z","steps":["trace[799186268] 'process raft request' (duration: 121.093974ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:58.568577Z","caller":"traceutil/trace.go:171","msg":"trace[557764026] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"130.241252ms","start":"2026-04-13T14:33:58.438307Z","end":"2026-04-13T14:33:58.568548Z","steps":["trace[557764026] 'process raft request' (duration: 129.915494ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:33:58.569035Z","caller":"traceutil/trace.go:171","msg":"trace[748878316] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"130.678353ms","start":"2026-04-13T14:33:58.438338Z","end":"2026-04-13T14:33:58.569016Z","steps":["trace[748878316] 'process raft request' (duration: 130.115748ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:35:17.40978Z","caller":"traceutil/trace.go:171","msg":"trace[510044852] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"111.931479ms","start":"2026-04-13T14:35:17.297825Z","end":"2026-04-13T14:35:17.409757Z","steps":["trace[510044852] 'process raft request' (duration: 53.953835ms)","trace[510044852] 'compare' (duration: 57.794129ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:36:40.891744Z","caller":"traceutil/trace.go:171","msg":"trace[2063430607] linearizableReadLoop","detail":"{readStateIndex:2387; appliedIndex:2386; }","duration":"149.051809ms","start":"2026-04-13T14:36:40.742663Z","end":"2026-04-13T14:36:40.891715Z","steps":["trace[2063430607] 'read index received' (duration: 145.847709ms)","trace[2063430607] 'applied index is now lower than readState.Index' (duration: 3.20304ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:36:40.891951Z","caller":"traceutil/trace.go:171","msg":"trace[2043681337] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"215.119197ms","start":"2026-04-13T14:36:40.676805Z","end":"2026-04-13T14:36:40.891924Z","steps":["trace[2043681337] 'process raft request' (duration: 211.776873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:36:40.891979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.281355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:36:40.892105Z","caller":"traceutil/trace.go:171","msg":"trace[1261136132] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2292; }","duration":"149.46918ms","start":"2026-04-13T14:36:40.742605Z","end":"2026-04-13T14:36:40.892074Z","steps":["trace[1261136132] 'agreement among raft nodes before linearized reading' (duration: 149.263555ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:36:51.728732Z","caller":"traceutil/trace.go:171","msg":"trace[1707750649] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"117.541712ms","start":"2026-04-13T14:36:51.611155Z","end":"2026-04-13T14:36:51.728697Z","steps":["trace[1707750649] 'process raft request' (duration: 57.613988ms)","trace[1707750649] 'compare' (duration: 59.836212ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:37:17.180488Z","caller":"traceutil/trace.go:171","msg":"trace[608365280] linearizableReadLoop","detail":"{readStateIndex:2568; appliedIndex:2567; }","duration":"313.807998ms","start":"2026-04-13T14:37:16.86665Z","end":"2026-04-13T14:37:17.180458Z","steps":["trace[608365280] 'read index received' (duration: 313.597013ms)","trace[608365280] 'applied index is now lower than readState.Index' (duration: 210.085µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:37:17.180596Z","caller":"traceutil/trace.go:171","msg":"trace[1542305242] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"317.415888ms","start":"2026-04-13T14:37:16.863145Z","end":"2026-04-13T14:37:17.180561Z","steps":["trace[1542305242] 'process raft request' (duration: 317.160102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:37:17.180659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.997513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:37:17.180707Z","caller":"traceutil/trace.go:171","msg":"trace[1835595994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2465; }","duration":"314.054725ms","start":"2026-04-13T14:37:16.866637Z","end":"2026-04-13T14:37:17.180691Z","steps":["trace[1835595994] 'agreement among raft nodes before linearized reading' (duration: 313.970293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:37:17.180764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:37:16.863133Z","time spent":"317.546982ms","remote":"127.0.0.1:38324","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-04-13T14:37:17.180787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:37:16.866616Z","time spent":"314.144987ms","remote":"127.0.0.1:38112","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-04-13T14:37:17.180826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.769589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n7l4z\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-04-13T14:37:17.180869Z","caller":"traceutil/trace.go:171","msg":"trace[1523373803] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n7l4z; range_end:; response_count:1; response_revision:2465; }","duration":"285.840732ms","start":"2026-04-13T14:37:16.895015Z","end":"2026-04-13T14:37:17.180855Z","steps":["trace[1523373803] 'agreement among raft nodes before linearized reading' (duration: 285.711278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:37:17.181154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.364961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-13T14:37:17.181283Z","caller":"traceutil/trace.go:171","msg":"trace[1238236466] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2465; }","duration":"253.561776ms","start":"2026-04-13T14:37:16.927689Z","end":"2026-04-13T14:37:17.18125Z","steps":["trace[1238236466] 'agreement among raft nodes before linearized reading' (duration: 253.166906ms)"],"step_count":1}