{"level":"warn","ts":"2026-04-17T10:48:59.831126Z","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-17T10:48:59.831219Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.168: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.168:2380","--initial-cluster=instance=https://199.19.213.168:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.168:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.168: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-17T10:48:59.831304Z","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-17T10:48:59.831318Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-04-17T10:48:59.831341Z","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-17T10:48:59.831975Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"]} {"level":"info","ts":"2026-04-17T10:48:59.832043Z","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.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.168: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-17T10:48:59.836477Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.241894ms"} {"level":"info","ts":"2026-04-17T10:48:59.84333Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8ae391decac9b376","cluster-id":"d9b32c15a2c66ac"} {"level":"info","ts":"2026-04-17T10:48:59.843416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T10:48:59.843437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 0"} {"level":"info","ts":"2026-04-17T10:48:59.843455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8ae391decac9b376 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T10:48:59.843465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 1"} {"level":"info","ts":"2026-04-17T10:48:59.843509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"warn","ts":"2026-04-17T10:48:59.848308Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T10:48:59.849569Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T10:48:59.850697Z","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-17T10:48:59.853159Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8ae391decac9b376","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T10:48:59.853358Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8ae391decac9b376","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T10:48:59.853447Z","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-17T10:48:59.853531Z","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-17T10:48:59.853551Z","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-17T10:48:59.855884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"info","ts":"2026-04-17T10:48:59.856219Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","added-peer-id":"8ae391decac9b376","added-peer-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-04-17T10:48:59.858813Z","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-17T10:48:59.858917Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-04-17T10:48:59.858941Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-04-17T10:48:59.859953Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8ae391decac9b376","initial-advertise-peer-urls":["https://199.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T10:48:59.860003Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T10:49:00.043999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T10:49:00.044133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T10:49:00.044195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgPreVoteResp from 8ae391decac9b376 at term 1"} {"level":"info","ts":"2026-04-17T10:49:00.044246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became candidate at term 2"} {"level":"info","ts":"2026-04-17T10:49:00.04429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgVoteResp from 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-04-17T10:49:00.044347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became leader at term 2"} {"level":"info","ts":"2026-04-17T10:49:00.044395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8ae391decac9b376 elected leader 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-04-17T10:49:00.045585Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8ae391decac9b376","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.168:2379]}","request-path":"/0/members/8ae391decac9b376/attributes","cluster-id":"d9b32c15a2c66ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T10:49:00.045669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T10:49:00.04611Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T10:49:00.046832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T10:49:00.048114Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T10:49:00.048207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T10:49:00.048232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T10:49:00.048787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T10:49:00.051868Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T10:49:00.052017Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T10:49:00.05677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.168:2379"} {"level":"warn","ts":"2026-04-17T10:49:06.872926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.58284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-17T10:49:06.873052Z","caller":"traceutil/trace.go:171","msg":"trace[1270111625] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:317; }","duration":"101.797988ms","start":"2026-04-17T10:49:06.771232Z","end":"2026-04-17T10:49:06.87303Z","steps":["trace[1270111625] 'range keys from in-memory index tree' (duration: 101.441674ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:07.548022Z","caller":"traceutil/trace.go:171","msg":"trace[1328865674] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"576.63854ms","start":"2026-04-17T10:49:06.971357Z","end":"2026-04-17T10:49:07.547996Z","steps":["trace[1328865674] 'process raft request' (duration: 576.514964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:07.548444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:06.971344Z","time spent":"576.749324ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T10:49:07.700097Z","caller":"traceutil/trace.go:171","msg":"trace[6411438] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"113.941322ms","start":"2026-04-17T10:49:07.586107Z","end":"2026-04-17T10:49:07.700048Z","steps":["trace[6411438] 'process raft request' (duration: 54.116709ms)","trace[6411438] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; req_size:206; } (duration: 59.550212ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:09.675719Z","caller":"traceutil/trace.go:171","msg":"trace[371549682] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"204.509154ms","start":"2026-04-17T10:49:09.471162Z","end":"2026-04-17T10:49:09.675671Z","steps":["trace[371549682] 'process raft request' (duration: 204.262604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:09.885843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.976076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:09.885959Z","caller":"traceutil/trace.go:171","msg":"trace[153590121] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"138.664298ms","start":"2026-04-17T10:49:09.747279Z","end":"2026-04-17T10:49:09.885944Z","steps":["trace[153590121] 'read index received' (duration: 37.323709ms)","trace[153590121] 'applied index is now lower than readState.Index' (duration: 101.339509ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:09.886014Z","caller":"traceutil/trace.go:171","msg":"trace[1419048001] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"172.476929ms","start":"2026-04-17T10:49:09.71349Z","end":"2026-04-17T10:49:09.885967Z","steps":["trace[1419048001] 'process raft request' (duration: 71.164151ms)","trace[1419048001] 'compare' (duration: 100.802609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:09.886107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.836203ms","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-17T10:49:09.886131Z","caller":"traceutil/trace.go:171","msg":"trace[1317778387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:339; }","duration":"138.911506ms","start":"2026-04-17T10:49:09.747211Z","end":"2026-04-17T10:49:09.886123Z","steps":["trace[1317778387] 'agreement among raft nodes before linearized reading' (duration: 138.822103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:10.159867Z","caller":"traceutil/trace.go:171","msg":"trace[1260793198] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"184.355424ms","start":"2026-04-17T10:49:09.975493Z","end":"2026-04-17T10:49:10.159848Z","steps":["trace[1260793198] 'read index received' (duration: 184.268591ms)","trace[1260793198] 'applied index is now lower than readState.Index' (duration: 86.003µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:10.159914Z","caller":"traceutil/trace.go:171","msg":"trace[1256530463] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"189.371758ms","start":"2026-04-17T10:49:09.970524Z","end":"2026-04-17T10:49:10.159896Z","steps":["trace[1256530463] 'process raft request' (duration: 189.206752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:10.159988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.479438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-17T10:49:10.160007Z","caller":"traceutil/trace.go:171","msg":"trace[2117845521] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:343; }","duration":"184.543262ms","start":"2026-04-17T10:49:09.975458Z","end":"2026-04-17T10:49:10.160002Z","steps":["trace[2117845521] 'agreement among raft nodes before linearized reading' (duration: 184.488319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:10.160129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.956489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-17T10:49:10.1602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.276935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T10:49:10.160218Z","caller":"traceutil/trace.go:171","msg":"trace[1163265236] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"134.336158ms","start":"2026-04-17T10:49:10.025877Z","end":"2026-04-17T10:49:10.160213Z","steps":["trace[1163265236] 'agreement among raft nodes before linearized reading' (duration: 134.305507ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:10.160244Z","caller":"traceutil/trace.go:171","msg":"trace[1900491945] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"184.127476ms","start":"2026-04-17T10:49:09.976085Z","end":"2026-04-17T10:49:10.160213Z","steps":["trace[1900491945] 'agreement among raft nodes before linearized reading' (duration: 183.918179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:12.159605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.498118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:12.159742Z","caller":"traceutil/trace.go:171","msg":"trace[1241534761] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"311.420509ms","start":"2026-04-17T10:49:11.848292Z","end":"2026-04-17T10:49:12.159712Z","steps":["trace[1241534761] 'read index received' (duration: 143.561897ms)","trace[1241534761] 'applied index is now lower than readState.Index' (duration: 167.857212ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:12.159767Z","caller":"traceutil/trace.go:171","msg":"trace[1395495431] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"315.659661ms","start":"2026-04-17T10:49:11.844071Z","end":"2026-04-17T10:49:12.15973Z","steps":["trace[1395495431] 'process raft request' (duration: 147.768318ms)","trace[1395495431] 'compare' (duration: 167.293301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:12.159922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.624736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-04-17T10:49:12.159931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.137101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T10:49:12.159963Z","caller":"traceutil/trace.go:171","msg":"trace[1747414827] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:394; }","duration":"311.704298ms","start":"2026-04-17T10:49:11.848249Z","end":"2026-04-17T10:49:12.159953Z","steps":["trace[1747414827] 'agreement among raft nodes before linearized reading' (duration: 311.576054ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:12.159974Z","caller":"traceutil/trace.go:171","msg":"trace[2011912219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"239.405442ms","start":"2026-04-17T10:49:11.920553Z","end":"2026-04-17T10:49:12.159959Z","steps":["trace[2011912219] 'agreement among raft nodes before linearized reading' (duration: 239.323289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:12.159986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:11.848231Z","time spent":"311.75011ms","remote":"127.0.0.1:57272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-17T10:49:12.160002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:11.844046Z","time spent":"315.825357ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:49:12.160187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.448047ms","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-17T10:49:12.160207Z","caller":"traceutil/trace.go:171","msg":"trace[1357866848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"238.51482ms","start":"2026-04-17T10:49:11.921687Z","end":"2026-04-17T10:49:12.160202Z","steps":["trace[1357866848] 'agreement among raft nodes before linearized reading' (duration: 238.466487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:12.160245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.814999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-17T10:49:12.160283Z","caller":"traceutil/trace.go:171","msg":"trace[1650576109] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:394; }","duration":"236.885482ms","start":"2026-04-17T10:49:11.923387Z","end":"2026-04-17T10:49:12.160273Z","steps":["trace[1650576109] 'agreement among raft nodes before linearized reading' (duration: 236.792469ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:12.327985Z","caller":"traceutil/trace.go:171","msg":"trace[1744690729] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"151.424633ms","start":"2026-04-17T10:49:12.176538Z","end":"2026-04-17T10:49:12.327963Z","steps":["trace[1744690729] 'process raft request' (duration: 151.378851ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:12.327997Z","caller":"traceutil/trace.go:171","msg":"trace[673402200] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"160.764144ms","start":"2026-04-17T10:49:12.167193Z","end":"2026-04-17T10:49:12.327957Z","steps":["trace[673402200] 'process raft request' (duration: 110.924113ms)","trace[673402200] 'compare' (duration: 49.632114ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:12.328237Z","caller":"traceutil/trace.go:171","msg":"trace[1043405856] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"159.875824ms","start":"2026-04-17T10:49:12.168332Z","end":"2026-04-17T10:49:12.328208Z","steps":["trace[1043405856] 'process raft request' (duration: 159.536311ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:13.049494Z","caller":"traceutil/trace.go:171","msg":"trace[296295901] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"113.129115ms","start":"2026-04-17T10:49:12.936341Z","end":"2026-04-17T10:49:13.04947Z","steps":["trace[296295901] 'process raft request' (duration: 112.98133ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:13.307628Z","caller":"traceutil/trace.go:171","msg":"trace[400333829] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"247.488035ms","start":"2026-04-17T10:49:13.060121Z","end":"2026-04-17T10:49:13.307609Z","steps":["trace[400333829] 'process raft request' (duration: 247.087471ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:13.917311Z","caller":"traceutil/trace.go:171","msg":"trace[646747251] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"188.428778ms","start":"2026-04-17T10:49:13.728863Z","end":"2026-04-17T10:49:13.917292Z","steps":["trace[646747251] 'process raft request' (duration: 188.316664ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:14.738369Z","caller":"traceutil/trace.go:171","msg":"trace[1742055395] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"245.405725ms","start":"2026-04-17T10:49:14.492877Z","end":"2026-04-17T10:49:14.738282Z","steps":["trace[1742055395] 'process raft request' (duration: 245.154056ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:18.102478Z","caller":"traceutil/trace.go:171","msg":"trace[1356259900] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"166.454826ms","start":"2026-04-17T10:49:17.935998Z","end":"2026-04-17T10:49:18.102452Z","steps":["trace[1356259900] 'process raft request' (duration: 166.307561ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:19.24376Z","caller":"traceutil/trace.go:171","msg":"trace[1858165121] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"230.467923ms","start":"2026-04-17T10:49:19.013273Z","end":"2026-04-17T10:49:19.243741Z","steps":["trace[1858165121] 'process raft request' (duration: 230.355169ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:19.374083Z","caller":"traceutil/trace.go:171","msg":"trace[1189151529] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"120.404043ms","start":"2026-04-17T10:49:19.253659Z","end":"2026-04-17T10:49:19.374063Z","steps":["trace[1189151529] 'process raft request' (duration: 120.297949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:20.787174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696669336848077,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T10:49:21.004186Z","caller":"traceutil/trace.go:171","msg":"trace[769668740] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"717.434341ms","start":"2026-04-17T10:49:20.286735Z","end":"2026-04-17T10:49:21.004169Z","steps":["trace[769668740] 'read index received' (duration: 717.306477ms)","trace[769668740] 'applied index is now lower than readState.Index' (duration: 126.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:21.004259Z","caller":"traceutil/trace.go:171","msg":"trace[648023042] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"892.775733ms","start":"2026-04-17T10:49:20.111458Z","end":"2026-04-17T10:49:21.004234Z","steps":["trace[648023042] 'process raft request' (duration: 892.597177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:21.004359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.613327ms","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-17T10:49:21.004385Z","caller":"traceutil/trace.go:171","msg":"trace[1218164965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:451; }","duration":"717.685159ms","start":"2026-04-17T10:49:20.286692Z","end":"2026-04-17T10:49:21.004378Z","steps":["trace[1218164965] 'agreement among raft nodes before linearized reading' (duration: 717.573266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:21.004406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:20.286675Z","time spent":"717.725961ms","remote":"127.0.0.1:57066","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-17T10:49:21.00446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:20.111431Z","time spent":"892.896096ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:49:21.004477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.903096ms","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-17T10:49:21.004578Z","caller":"traceutil/trace.go:171","msg":"trace[1006149609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:451; }","duration":"525.073413ms","start":"2026-04-17T10:49:20.479482Z","end":"2026-04-17T10:49:21.004555Z","steps":["trace[1006149609] 'agreement among raft nodes before linearized reading' (duration: 524.843944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:21.004648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:20.479464Z","time spent":"525.170746ms","remote":"127.0.0.1:57066","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-17T10:49:21.004478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.299952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T10:49:21.004704Z","caller":"traceutil/trace.go:171","msg":"trace[1517977312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"227.635563ms","start":"2026-04-17T10:49:20.777061Z","end":"2026-04-17T10:49:21.004696Z","steps":["trace[1517977312] 'agreement among raft nodes before linearized reading' (duration: 227.273691ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:21.606792Z","caller":"traceutil/trace.go:171","msg":"trace[1102556448] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"597.261251ms","start":"2026-04-17T10:49:21.009508Z","end":"2026-04-17T10:49:21.606769Z","steps":["trace[1102556448] 'process raft request' (duration: 597.222989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:21.606932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:21.009493Z","time spent":"597.366904ms","remote":"127.0.0.1:57066","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-17T10:49:21.607106Z","caller":"traceutil/trace.go:171","msg":"trace[498920535] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"597.623072ms","start":"2026-04-17T10:49:21.009472Z","end":"2026-04-17T10:49:21.607095Z","steps":["trace[498920535] 'process raft request' (duration: 597.203489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:21.607162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:21.009459Z","time spent":"597.675324ms","remote":"127.0.0.1:57066","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-17T10:49:21.607168Z","caller":"traceutil/trace.go:171","msg":"trace[136319392] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"599.310507ms","start":"2026-04-17T10:49:21.007831Z","end":"2026-04-17T10:49:21.607141Z","steps":["trace[136319392] 'process raft request' (duration: 590.281034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:21.607283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:21.007807Z","time spent":"599.413121ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T10:49:26.59277Z","caller":"traceutil/trace.go:171","msg":"trace[295314167] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"196.223566ms","start":"2026-04-17T10:49:26.396514Z","end":"2026-04-17T10:49:26.592738Z","steps":["trace[295314167] 'process raft request' (duration: 195.918446ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:26.804354Z","caller":"traceutil/trace.go:171","msg":"trace[1428703782] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"169.255983ms","start":"2026-04-17T10:49:26.63507Z","end":"2026-04-17T10:49:26.804326Z","steps":["trace[1428703782] 'process raft request' (duration: 164.222458ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:26.804616Z","caller":"traceutil/trace.go:171","msg":"trace[72505065] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"167.049124ms","start":"2026-04-17T10:49:26.637549Z","end":"2026-04-17T10:49:26.804598Z","steps":["trace[72505065] 'process raft request' (duration: 166.961621ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:28.110693Z","caller":"traceutil/trace.go:171","msg":"trace[1528449921] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"104.666759ms","start":"2026-04-17T10:49:28.006Z","end":"2026-04-17T10:49:28.110667Z","steps":["trace[1528449921] 'process raft request' (duration: 104.496513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:28.719625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.457002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T10:49:28.719774Z","caller":"traceutil/trace.go:171","msg":"trace[1077289078] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"298.878376ms","start":"2026-04-17T10:49:28.420867Z","end":"2026-04-17T10:49:28.719746Z","steps":["trace[1077289078] 'read index received' (duration: 53.111µs)","trace[1077289078] 'applied index is now lower than readState.Index' (duration: 298.823745ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:28.719915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.038561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:28.71995Z","caller":"traceutil/trace.go:171","msg":"trace[1193856789] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:503; }","duration":"299.120604ms","start":"2026-04-17T10:49:28.420821Z","end":"2026-04-17T10:49:28.719942Z","steps":["trace[1193856789] 'agreement among raft nodes before linearized reading' (duration: 299.039232ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.193868Z","caller":"traceutil/trace.go:171","msg":"trace[177665848] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"270.415722ms","start":"2026-04-17T10:49:28.923425Z","end":"2026-04-17T10:49:29.193841Z","steps":["trace[177665848] 'process raft request' (duration: 270.243967ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.22167Z","caller":"traceutil/trace.go:171","msg":"trace[821356303] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"296.756971ms","start":"2026-04-17T10:49:28.924892Z","end":"2026-04-17T10:49:29.221649Z","steps":["trace[821356303] 'process raft request' (duration: 296.589596ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.221889Z","caller":"traceutil/trace.go:171","msg":"trace[2088950781] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"293.332417ms","start":"2026-04-17T10:49:28.928542Z","end":"2026-04-17T10:49:29.221874Z","steps":["trace[2088950781] 'process raft request' (duration: 293.058639ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.222003Z","caller":"traceutil/trace.go:171","msg":"trace[938723548] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"285.041966ms","start":"2026-04-17T10:49:28.936951Z","end":"2026-04-17T10:49:29.221993Z","steps":["trace[938723548] 'process raft request' (duration: 284.692286ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.222037Z","caller":"traceutil/trace.go:171","msg":"trace[1737062786] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"285.002585ms","start":"2026-04-17T10:49:28.937019Z","end":"2026-04-17T10:49:29.222021Z","steps":["trace[1737062786] 'process raft request' (duration: 284.83933ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.230114Z","caller":"traceutil/trace.go:171","msg":"trace[1584111538] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"289.544721ms","start":"2026-04-17T10:49:28.940553Z","end":"2026-04-17T10:49:29.230098Z","steps":["trace[1584111538] 'process raft request' (duration: 281.452088ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.230307Z","caller":"traceutil/trace.go:171","msg":"trace[555729981] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"288.753356ms","start":"2026-04-17T10:49:28.941546Z","end":"2026-04-17T10:49:29.230299Z","steps":["trace[555729981] 'process raft request' (duration: 288.483929ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.230322Z","caller":"traceutil/trace.go:171","msg":"trace[828242655] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"283.545949ms","start":"2026-04-17T10:49:28.946759Z","end":"2026-04-17T10:49:29.230305Z","steps":["trace[828242655] 'process raft request' (duration: 283.328362ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.23042Z","caller":"traceutil/trace.go:171","msg":"trace[792223872] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"282.754094ms","start":"2026-04-17T10:49:28.947641Z","end":"2026-04-17T10:49:29.230395Z","steps":["trace[792223872] 'process raft request' (duration: 282.504887ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.230392Z","caller":"traceutil/trace.go:171","msg":"trace[150401047] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"289.270961ms","start":"2026-04-17T10:49:28.941059Z","end":"2026-04-17T10:49:29.23033Z","steps":["trace[150401047] 'process raft request' (duration: 288.932422ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.23056Z","caller":"traceutil/trace.go:171","msg":"trace[1302148698] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"237.916548ms","start":"2026-04-17T10:49:28.992607Z","end":"2026-04-17T10:49:29.230523Z","steps":["trace[1302148698] 'process raft request' (duration: 237.599969ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.230948Z","caller":"traceutil/trace.go:171","msg":"trace[1717533967] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"140.7997ms","start":"2026-04-17T10:49:29.090129Z","end":"2026-04-17T10:49:29.230929Z","steps":["trace[1717533967] 'process raft request' (duration: 140.512861ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:29.231157Z","caller":"traceutil/trace.go:171","msg":"trace[728465796] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:518; }","duration":"184.892242ms","start":"2026-04-17T10:49:29.046252Z","end":"2026-04-17T10:49:29.231144Z","steps":["trace[728465796] 'read index received' (duration: 147.454791ms)","trace[728465796] 'applied index is now lower than readState.Index' (duration: 37.435411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:29.23126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.000946ms","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-17T10:49:29.231297Z","caller":"traceutil/trace.go:171","msg":"trace[1814220593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"185.084519ms","start":"2026-04-17T10:49:29.046203Z","end":"2026-04-17T10:49:29.231288Z","steps":["trace[1814220593] 'agreement among raft nodes before linearized reading' (duration: 184.989586ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:31.572049Z","caller":"traceutil/trace.go:171","msg":"trace[826953129] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"317.595604ms","start":"2026-04-17T10:49:31.254436Z","end":"2026-04-17T10:49:31.572032Z","steps":["trace[826953129] 'process raft request' (duration: 317.506642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:31.572158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:31.254421Z","time spent":"317.681866ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T10:49:36.115049Z","caller":"traceutil/trace.go:171","msg":"trace[367005978] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"443.641888ms","start":"2026-04-17T10:49:35.671387Z","end":"2026-04-17T10:49:36.115029Z","steps":["trace[367005978] 'read index received' (duration: 443.610207ms)","trace[367005978] 'applied index is now lower than readState.Index' (duration: 30.591µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:36.115152Z","caller":"traceutil/trace.go:171","msg":"trace[2141592901] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"525.612701ms","start":"2026-04-17T10:49:35.589532Z","end":"2026-04-17T10:49:36.115144Z","steps":["trace[2141592901] 'process raft request' (duration: 525.377944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:36.115237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.589507Z","time spent":"525.666492ms","remote":"127.0.0.1:57066","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-17T10:49:36.116107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.668026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T10:49:36.116219Z","caller":"traceutil/trace.go:171","msg":"trace[858537846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"444.815991ms","start":"2026-04-17T10:49:35.671357Z","end":"2026-04-17T10:49:36.116173Z","steps":["trace[858537846] 'agreement among raft nodes before linearized reading' (duration: 443.883804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:36.116278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.671331Z","time spent":"444.936814ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T10:49:36.854851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.814061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:36.854978Z","caller":"traceutil/trace.go:171","msg":"trace[842238437] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"739.838641ms","start":"2026-04-17T10:49:36.115118Z","end":"2026-04-17T10:49:36.854957Z","steps":["trace[842238437] 'read index received' (duration: 198.492096ms)","trace[842238437] 'applied index is now lower than readState.Index' (duration: 541.344975ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:36.855153Z","caller":"traceutil/trace.go:171","msg":"trace[2045699482] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.094615207s","start":"2026-04-17T10:49:35.760512Z","end":"2026-04-17T10:49:36.855128Z","steps":["trace[2045699482] 'process raft request' (duration: 553.426666ms)","trace[2045699482] 'compare' (duration: 540.388799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:36.855182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134431746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:36.855253Z","caller":"traceutil/trace.go:171","msg":"trace[959762641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"1.13455672s","start":"2026-04-17T10:49:35.720672Z","end":"2026-04-17T10:49:36.855229Z","steps":["trace[959762641] 'agreement among raft nodes before linearized reading' (duration: 1.134446117s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:36.855266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.760483Z","time spent":"1.094726441s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:49:36.855308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.720651Z","time spent":"1.134644693s","remote":"127.0.0.1:56774","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-17T10:49:36.855279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183568269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T10:49:36.855372Z","caller":"traceutil/trace.go:171","msg":"trace[1709743074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.183695893s","start":"2026-04-17T10:49:35.671656Z","end":"2026-04-17T10:49:36.855352Z","steps":["trace[1709743074] 'agreement among raft nodes before linearized reading' (duration: 1.183465637s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:36.855459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.671645Z","time spent":"1.183799407s","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T10:49:36.8555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183998493s","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-17T10:49:36.855608Z","caller":"traceutil/trace.go:171","msg":"trace[717964631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"1.184097965s","start":"2026-04-17T10:49:35.671449Z","end":"2026-04-17T10:49:36.855547Z","steps":["trace[717964631] 'agreement among raft nodes before linearized reading' (duration: 1.18356436s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:36.855536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.313523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n9fpz\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-17T10:49:36.855662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.671415Z","time spent":"1.184233539s","remote":"127.0.0.1:57066","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-17T10:49:36.857282Z","caller":"traceutil/trace.go:171","msg":"trace[1032333972] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n9fpz; range_end:; response_count:1; response_revision:615; }","duration":"862.55791ms","start":"2026-04-17T10:49:35.993163Z","end":"2026-04-17T10:49:36.855721Z","steps":["trace[1032333972] 'agreement among raft nodes before linearized reading' (duration: 861.919312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:36.857789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:35.993144Z","time spent":"864.564596ms","remote":"127.0.0.1:56964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-n9fpz\" "} {"level":"warn","ts":"2026-04-17T10:49:38.863921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000412804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T10:49:38.864003Z","caller":"traceutil/trace.go:171","msg":"trace[1453345340] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000535478s","start":"2026-04-17T10:49:36.863451Z","end":"2026-04-17T10:49:38.863987Z","steps":["trace[1453345340] 'agreement among raft nodes before linearized reading' (duration: 2.000431415s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:38.864037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:36.863437Z","time spent":"2.00059282s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T10:49:39.906888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:38.483562Z","time spent":"1.423319383s","remote":"127.0.0.1:38298","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T10:49:39.907357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.904473973s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:39.90777Z","caller":"traceutil/trace.go:171","msg":"trace[1586769420] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"3.040885453s","start":"2026-04-17T10:49:36.866867Z","end":"2026-04-17T10:49:39.907752Z","steps":["trace[1586769420] 'process raft request' (duration: 3.040707619s)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:39.907813Z","caller":"traceutil/trace.go:171","msg":"trace[978116201] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"3.786260409s","start":"2026-04-17T10:49:36.121543Z","end":"2026-04-17T10:49:39.907803Z","steps":["trace[978116201] 'process raft request' (duration: 881.258264ms)","trace[978116201] 'compare' (duration: 2.903849705s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:39.907869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:36.866847Z","time spent":"3.040957704s","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:49:39.907914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:36.121522Z","time spent":"3.786314341s","remote":"127.0.0.1:57066","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-17T10:49:39.907762Z","caller":"traceutil/trace.go:171","msg":"trace[792253081] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"3.043824386s","start":"2026-04-17T10:49:36.863918Z","end":"2026-04-17T10:49:39.907743Z","steps":["trace[792253081] 'read index received' (duration: 138.946203ms)","trace[792253081] 'applied index is now lower than readState.Index' (duration: 2.904876373s)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:39.908066Z","caller":"traceutil/trace.go:171","msg":"trace[1800236258] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"3.041053868s","start":"2026-04-17T10:49:36.866981Z","end":"2026-04-17T10:49:39.908035Z","steps":["trace[1800236258] 'process raft request' (duration: 3.040673978s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:39.90824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:36.866974Z","time spent":"3.041186002s","remote":"127.0.0.1:57066","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-17T10:49:39.908276Z","caller":"traceutil/trace.go:171","msg":"trace[425371188] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"3.028098653s","start":"2026-04-17T10:49:36.880166Z","end":"2026-04-17T10:49:39.908264Z","steps":["trace[425371188] 'process raft request' (duration: 3.027538148s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:39.90833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:36.880147Z","time spent":"3.028159785s","remote":"127.0.0.1:56964","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-17T10:49:39.908286Z","caller":"traceutil/trace.go:171","msg":"trace[234963782] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"2.98561569s","start":"2026-04-17T10:49:36.922658Z","end":"2026-04-17T10:49:39.908274Z","steps":["trace[234963782] 'process raft request' (duration: 2.985468156s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:39.908525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:36.922633Z","time spent":"2.985862247s","remote":"127.0.0.1:57030","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-17T10:49:40.018641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.614855485s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T10:49:40.063226Z","caller":"traceutil/trace.go:171","msg":"trace[1929774320] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"155.159157ms","start":"2026-04-17T10:49:39.908025Z","end":"2026-04-17T10:49:40.063185Z","steps":["trace[1929774320] 'read index received' (duration: 110.742114ms)","trace[1929774320] 'applied index is now lower than readState.Index' (duration: 44.415843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:40.063439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.750313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:40.06347Z","caller":"traceutil/trace.go:171","msg":"trace[689256372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"257.874666ms","start":"2026-04-17T10:49:39.805587Z","end":"2026-04-17T10:49:40.063462Z","steps":["trace[689256372] 'agreement among raft nodes before linearized reading' (duration: 257.750373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:40.063631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.653454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-17T10:49:40.063642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199157615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T10:49:40.063659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943064536s","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-17T10:49:40.06369Z","caller":"traceutil/trace.go:171","msg":"trace[1604640561] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:620; }","duration":"150.746926ms","start":"2026-04-17T10:49:39.912927Z","end":"2026-04-17T10:49:40.063674Z","steps":["trace[1604640561] 'agreement among raft nodes before linearized reading' (duration: 150.620273ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:40.06372Z","caller":"traceutil/trace.go:171","msg":"trace[690673910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.199289728s","start":"2026-04-17T10:49:38.864412Z","end":"2026-04-17T10:49:40.063702Z","steps":["trace[690673910] 'agreement among raft nodes before linearized reading' (duration: 1.199149654s)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:40.063729Z","caller":"traceutil/trace.go:171","msg":"trace[1766669016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"1.943164018s","start":"2026-04-17T10:49:38.120549Z","end":"2026-04-17T10:49:40.063713Z","steps":["trace[1766669016] 'agreement among raft nodes before linearized reading' (duration: 1.942931162s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:40.06376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:38.864395Z","time spent":"1.199358029s","remote":"127.0.0.1:56774","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-17T10:49:40.064005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:38.120532Z","time spent":"1.943231421s","remote":"127.0.0.1:57066","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-17T10:49:41.719492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.18037ms","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:5"} {"level":"warn","ts":"2026-04-17T10:49:41.719573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.712123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T10:49:41.719525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.833624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.719635Z","caller":"traceutil/trace.go:171","msg":"trace[1926416693] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:631; }","duration":"369.969928ms","start":"2026-04-17T10:49:41.349654Z","end":"2026-04-17T10:49:41.719624Z","steps":["trace[1926416693] 'range keys from in-memory index tree' (duration: 369.736931ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:41.719589Z","caller":"traceutil/trace.go:171","msg":"trace[936956545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"406.313953ms","start":"2026-04-17T10:49:41.313252Z","end":"2026-04-17T10:49:41.719567Z","steps":["trace[936956545] 'count revisions from in-memory index tree' (duration: 406.088018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.719682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.313239Z","time spent":"406.431957ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-17T10:49:41.719679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.704556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T10:49:41.719705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.349639Z","time spent":"370.027109ms","remote":"127.0.0.1:38530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-17T10:49:41.71974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.959403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.719751Z","caller":"traceutil/trace.go:171","msg":"trace[1027206527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"386.79721ms","start":"2026-04-17T10:49:41.332928Z","end":"2026-04-17T10:49:41.719726Z","steps":["trace[1027206527] 'range keys from in-memory index tree' (duration: 386.544742ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:41.719782Z","caller":"traceutil/trace.go:171","msg":"trace[1630674504] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:631; }","duration":"432.018304ms","start":"2026-04-17T10:49:41.287749Z","end":"2026-04-17T10:49:41.719768Z","steps":["trace[1630674504] 'count revisions from in-memory index tree' (duration: 431.888541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.719803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.332915Z","time spent":"386.871252ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-17T10:49:41.719818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.287735Z","time spent":"432.075677ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T10:49:41.719813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.757102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.71988Z","caller":"traceutil/trace.go:171","msg":"trace[1034212270] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:631; }","duration":"369.858986ms","start":"2026-04-17T10:49:41.350003Z","end":"2026-04-17T10:49:41.719862Z","steps":["trace[1034212270] 'range keys from in-memory index tree' (duration: 369.717401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.719983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.600049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.720003Z","caller":"traceutil/trace.go:171","msg":"trace[1768818297] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:631; }","duration":"455.646529ms","start":"2026-04-17T10:49:41.264351Z","end":"2026-04-17T10:49:41.719997Z","steps":["trace[1768818297] 'range keys from in-memory index tree' (duration: 455.459654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.349996Z","time spent":"369.990989ms","remote":"127.0.0.1:38530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-17T10:49:41.72002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.264329Z","time spent":"455.685171ms","remote":"127.0.0.1:38478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-17T10:49:41.720038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.321314ms","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:5"} {"level":"info","ts":"2026-04-17T10:49:41.720085Z","caller":"traceutil/trace.go:171","msg":"trace[1156003009] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"387.401146ms","start":"2026-04-17T10:49:41.33267Z","end":"2026-04-17T10:49:41.720071Z","steps":["trace[1156003009] 'count revisions from in-memory index tree' (duration: 387.206431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.757666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T10:49:41.719651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.746206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.720159Z","caller":"traceutil/trace.go:171","msg":"trace[1321817429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"406.829158ms","start":"2026-04-17T10:49:41.313312Z","end":"2026-04-17T10:49:41.720141Z","steps":["trace[1321817429] 'range keys from in-memory index tree' (duration: 406.733435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.313309Z","time spent":"406.882659ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-17T10:49:41.720285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.597254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.720324Z","caller":"traceutil/trace.go:171","msg":"trace[116992309] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:631; }","duration":"370.663686ms","start":"2026-04-17T10:49:41.349653Z","end":"2026-04-17T10:49:41.720317Z","steps":["trace[116992309] 'count revisions from in-memory index tree' (duration: 370.543763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.721581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T10:49:41.72035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.349642Z","time spent":"370.699147ms","remote":"127.0.0.1:38530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-17T10:49:41.719641Z","caller":"traceutil/trace.go:171","msg":"trace[1670668943] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:631; }","duration":"454.806606ms","start":"2026-04-17T10:49:41.264815Z","end":"2026-04-17T10:49:41.719621Z","steps":["trace[1670668943] 'range keys from in-memory index tree' (duration: 454.663872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.662756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.720381Z","caller":"traceutil/trace.go:171","msg":"trace[371215342] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:631; }","duration":"456.003809ms","start":"2026-04-17T10:49:41.264361Z","end":"2026-04-17T10:49:41.720365Z","steps":["trace[371215342] 'count revisions from in-memory index tree' (duration: 455.595058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:41.720413Z","caller":"traceutil/trace.go:171","msg":"trace[1649959003] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:631; }","duration":"432.074287ms","start":"2026-04-17T10:49:41.288331Z","end":"2026-04-17T10:49:41.720405Z","steps":["trace[1649959003] 'range keys from in-memory index tree' (duration: 431.597934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.264807Z","time spent":"455.575347ms","remote":"127.0.0.1:38478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-17T10:49:41.720442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.288312Z","time spent":"432.119318ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-04-17T10:49:41.720165Z","caller":"traceutil/trace.go:171","msg":"trace[1158825065] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:631; }","duration":"432.327682ms","start":"2026-04-17T10:49:41.287828Z","end":"2026-04-17T10:49:41.720156Z","steps":["trace[1158825065] 'range keys from in-memory index tree' (duration: 431.653224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.72013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.332651Z","time spent":"387.464137ms","remote":"127.0.0.1:38516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-17T10:49:41.720517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.287805Z","time spent":"432.699473ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-17T10:49:41.720435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.264345Z","time spent":"456.071811ms","remote":"127.0.0.1:38478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T10:49:41.720838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.69057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:41.720898Z","caller":"traceutil/trace.go:171","msg":"trace[16705054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"388.799864ms","start":"2026-04-17T10:49:41.332083Z","end":"2026-04-17T10:49:41.720883Z","steps":["trace[16705054] 'range keys from in-memory index tree' (duration: 387.367295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:41.720949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.332073Z","time spent":"388.860455ms","remote":"127.0.0.1:38516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-17T10:49:42.016624Z","caller":"traceutil/trace.go:171","msg":"trace[470137299] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"100.489657ms","start":"2026-04-17T10:49:41.916116Z","end":"2026-04-17T10:49:42.016605Z","steps":["trace[470137299] 'process raft request' (duration: 100.364003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:42.217479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.475761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:42.217603Z","caller":"traceutil/trace.go:171","msg":"trace[1671707947] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"300.389608ms","start":"2026-04-17T10:49:41.9172Z","end":"2026-04-17T10:49:42.21759Z","steps":["trace[1671707947] 'process raft request' (duration: 183.740042ms)","trace[1671707947] 'compare' (duration: 116.317527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:42.217644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.917187Z","time spent":"300.43922ms","remote":"127.0.0.1:57066","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-17T10:49:42.217765Z","caller":"traceutil/trace.go:171","msg":"trace[430165323] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"141.2213ms","start":"2026-04-17T10:49:42.076525Z","end":"2026-04-17T10:49:42.217746Z","steps":["trace[430165323] 'read index received' (duration: 24.364079ms)","trace[430165323] 'applied index is now lower than readState.Index' (duration: 116.855351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:42.217962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.434946ms","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-17T10:49:42.218009Z","caller":"traceutil/trace.go:171","msg":"trace[288518776] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"300.224715ms","start":"2026-04-17T10:49:41.917776Z","end":"2026-04-17T10:49:42.218001Z","steps":["trace[288518776] 'process raft request' (duration: 299.772403ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:42.21801Z","caller":"traceutil/trace.go:171","msg":"trace[1582150147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"141.512809ms","start":"2026-04-17T10:49:42.07648Z","end":"2026-04-17T10:49:42.217992Z","steps":["trace[1582150147] 'agreement among raft nodes before linearized reading' (duration: 141.339424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:42.218054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:41.917766Z","time spent":"300.267316ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T10:49:42.420617Z","caller":"traceutil/trace.go:171","msg":"trace[1772101229] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"199.299047ms","start":"2026-04-17T10:49:42.221256Z","end":"2026-04-17T10:49:42.420555Z","steps":["trace[1772101229] 'process raft request' (duration: 199.114441ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:42.49479Z","caller":"traceutil/trace.go:171","msg":"trace[726437105] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"163.802902ms","start":"2026-04-17T10:49:42.330963Z","end":"2026-04-17T10:49:42.494766Z","steps":["trace[726437105] 'process raft request' (duration: 163.590695ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:42.619328Z","caller":"traceutil/trace.go:171","msg":"trace[188762069] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"143.379886ms","start":"2026-04-17T10:49:42.475921Z","end":"2026-04-17T10:49:42.6193Z","steps":["trace[188762069] 'process raft request' (duration: 139.607396ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:43.671165Z","caller":"traceutil/trace.go:171","msg":"trace[280482404] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"336.744678ms","start":"2026-04-17T10:49:43.334394Z","end":"2026-04-17T10:49:43.671139Z","steps":["trace[280482404] 'read index received' (duration: 336.417139ms)","trace[280482404] 'applied index is now lower than readState.Index' (duration: 326.219µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:43.671192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:43.227437Z","time spent":"443.747514ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T10:49:43.671436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.994326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n9fpz\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-04-17T10:49:43.671476Z","caller":"traceutil/trace.go:171","msg":"trace[2074367000] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n9fpz; range_end:; response_count:1; response_revision:642; }","duration":"337.109818ms","start":"2026-04-17T10:49:43.334356Z","end":"2026-04-17T10:49:43.671465Z","steps":["trace[2074367000] 'agreement among raft nodes before linearized reading' (duration: 336.919823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:43.671517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:43.334337Z","time spent":"337.16429ms","remote":"127.0.0.1:56964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-n9fpz\" "} {"level":"warn","ts":"2026-04-17T10:49:43.672547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.209201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-17T10:49:43.67273Z","caller":"traceutil/trace.go:171","msg":"trace[1242145605] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:642; }","duration":"126.476158ms","start":"2026-04-17T10:49:43.546226Z","end":"2026-04-17T10:49:43.672702Z","steps":["trace[1242145605] 'agreement among raft nodes before linearized reading' (duration: 125.082952ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:44.510045Z","caller":"traceutil/trace.go:171","msg":"trace[738046299] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"117.864864ms","start":"2026-04-17T10:49:44.392158Z","end":"2026-04-17T10:49:44.510023Z","steps":["trace[738046299] 'process raft request' (duration: 101.181787ms)","trace[738046299] 'compare' (duration: 16.527433ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:44.685116Z","caller":"traceutil/trace.go:171","msg":"trace[1079681699] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"133.731459ms","start":"2026-04-17T10:49:44.551364Z","end":"2026-04-17T10:49:44.685095Z","steps":["trace[1079681699] 'process raft request' (duration: 88.192368ms)","trace[1079681699] 'compare' (duration: 45.433039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:45.117381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.130879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:45.117498Z","caller":"traceutil/trace.go:171","msg":"trace[246394209] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"398.373319ms","start":"2026-04-17T10:49:44.719105Z","end":"2026-04-17T10:49:45.117478Z","steps":["trace[246394209] 'read index received' (duration: 197.145878ms)","trace[246394209] 'applied index is now lower than readState.Index' (duration: 201.226241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:45.117565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.456171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:45.117606Z","caller":"traceutil/trace.go:171","msg":"trace[573237704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"398.522583ms","start":"2026-04-17T10:49:44.719073Z","end":"2026-04-17T10:49:45.117596Z","steps":["trace[573237704] 'agreement among raft nodes before linearized reading' (duration: 398.459662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:45.11764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:44.719059Z","time spent":"398.573134ms","remote":"127.0.0.1:56772","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-17T10:49:45.117675Z","caller":"traceutil/trace.go:171","msg":"trace[2114103030] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"428.362574ms","start":"2026-04-17T10:49:44.689267Z","end":"2026-04-17T10:49:45.11763Z","steps":["trace[2114103030] 'process raft request' (duration: 226.895967ms)","trace[2114103030] 'compare' (duration: 200.980914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:45.117925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:44.689249Z","time spent":"428.533739ms","remote":"127.0.0.1:56830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T10:49:45.125616Z","caller":"traceutil/trace.go:171","msg":"trace[587644102] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"373.64445ms","start":"2026-04-17T10:49:44.751952Z","end":"2026-04-17T10:49:45.125597Z","steps":["trace[587644102] 'process raft request' (duration: 373.561788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:45.125737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:44.751928Z","time spent":"373.760833ms","remote":"127.0.0.1:56830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T10:49:45.177012Z","caller":"traceutil/trace.go:171","msg":"trace[1290774426] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"223.782187ms","start":"2026-04-17T10:49:44.953214Z","end":"2026-04-17T10:49:45.176997Z","steps":["trace[1290774426] 'process raft request' (duration: 223.361126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:45.657928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.090322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T10:49:45.658005Z","caller":"traceutil/trace.go:171","msg":"trace[896952034] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"107.49752ms","start":"2026-04-17T10:49:45.550495Z","end":"2026-04-17T10:49:45.657993Z","steps":["trace[896952034] 'read index received' (duration: 39.251µs)","trace[896952034] 'applied index is now lower than readState.Index' (duration: 107.457119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:45.658102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.614582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:49:45.65814Z","caller":"traceutil/trace.go:171","msg":"trace[1147097869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:687; }","duration":"107.683824ms","start":"2026-04-17T10:49:45.550447Z","end":"2026-04-17T10:49:45.658131Z","steps":["trace[1147097869] 'agreement among raft nodes before linearized reading' (duration: 107.580201ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:45.658194Z","caller":"traceutil/trace.go:171","msg":"trace[1322023488] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"214.352571ms","start":"2026-04-17T10:49:45.443818Z","end":"2026-04-17T10:49:45.658171Z","steps":["trace[1322023488] 'process raft request' (duration: 60.952371ms)","trace[1322023488] 'compare' (duration: 153.015091ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:45.901477Z","caller":"traceutil/trace.go:171","msg":"trace[682035429] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"313.894024ms","start":"2026-04-17T10:49:45.587553Z","end":"2026-04-17T10:49:45.901447Z","steps":["trace[682035429] 'process raft request' (duration: 220.197323ms)","trace[682035429] 'compare' (duration: 93.539206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:45.901626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:45.587534Z","time spent":"314.020228ms","remote":"127.0.0.1:56852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T10:49:46.057439Z","caller":"traceutil/trace.go:171","msg":"trace[1481285761] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"294.157219ms","start":"2026-04-17T10:49:45.76326Z","end":"2026-04-17T10:49:46.057417Z","steps":["trace[1481285761] 'read index received' (duration: 44.578986ms)","trace[1481285761] 'applied index is now lower than readState.Index' (duration: 249.576883ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:46.057622Z","caller":"traceutil/trace.go:171","msg":"trace[1407381975] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"302.541898ms","start":"2026-04-17T10:49:45.75507Z","end":"2026-04-17T10:49:46.057612Z","steps":["trace[1407381975] 'process raft request' (duration: 301.655944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:46.0577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:45.755007Z","time spent":"302.643531ms","remote":"127.0.0.1:56830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T10:49:46.057765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.496019ms","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-17T10:49:46.057823Z","caller":"traceutil/trace.go:171","msg":"trace[724285255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"294.608172ms","start":"2026-04-17T10:49:45.763203Z","end":"2026-04-17T10:49:46.057811Z","steps":["trace[724285255] 'agreement among raft nodes before linearized reading' (duration: 294.406097ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.139346Z","caller":"traceutil/trace.go:171","msg":"trace[927152188] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"192.629408ms","start":"2026-04-17T10:49:45.94669Z","end":"2026-04-17T10:49:46.139319Z","steps":["trace[927152188] 'process raft request' (duration: 192.460934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:46.139537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.777204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-17T10:49:46.139605Z","caller":"traceutil/trace.go:171","msg":"trace[1619897801] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:690; }","duration":"189.884406ms","start":"2026-04-17T10:49:45.949703Z","end":"2026-04-17T10:49:46.139587Z","steps":["trace[1619897801] 'agreement among raft nodes before linearized reading' (duration: 189.589598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:46.139745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.036433ms","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-17T10:49:46.139914Z","caller":"traceutil/trace.go:171","msg":"trace[29248939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"100.183497ms","start":"2026-04-17T10:49:46.039623Z","end":"2026-04-17T10:49:46.139806Z","steps":["trace[29248939] 'agreement among raft nodes before linearized reading' (duration: 99.751225ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.233611Z","caller":"traceutil/trace.go:171","msg":"trace[883617708] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"160.701265ms","start":"2026-04-17T10:49:46.072887Z","end":"2026-04-17T10:49:46.233588Z","steps":["trace[883617708] 'process raft request' (duration: 160.639223ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.233676Z","caller":"traceutil/trace.go:171","msg":"trace[2047536943] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"172.61719ms","start":"2026-04-17T10:49:46.061026Z","end":"2026-04-17T10:49:46.233643Z","steps":["trace[2047536943] 'process raft request' (duration: 172.364284ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.251671Z","caller":"traceutil/trace.go:171","msg":"trace[146287341] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:713; }","duration":"100.953057ms","start":"2026-04-17T10:49:46.150683Z","end":"2026-04-17T10:49:46.251636Z","steps":["trace[146287341] 'read index received' (duration: 82.719468ms)","trace[146287341] 'applied index is now lower than readState.Index' (duration: 18.232369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:46.251882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.177592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-17T10:49:46.251912Z","caller":"traceutil/trace.go:171","msg":"trace[1285449111] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:693; }","duration":"101.264564ms","start":"2026-04-17T10:49:46.150638Z","end":"2026-04-17T10:49:46.251903Z","steps":["trace[1285449111] 'agreement among raft nodes before linearized reading' (duration: 101.115631ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.251732Z","caller":"traceutil/trace.go:171","msg":"trace[1258163778] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"107.119816ms","start":"2026-04-17T10:49:46.144589Z","end":"2026-04-17T10:49:46.251708Z","steps":["trace[1258163778] 'process raft request' (duration: 106.928801ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.382569Z","caller":"traceutil/trace.go:171","msg":"trace[2018494873] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"131.939874ms","start":"2026-04-17T10:49:46.250596Z","end":"2026-04-17T10:49:46.382536Z","steps":["trace[2018494873] 'process raft request' (duration: 130.157938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:46.382694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.975005ms","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-17T10:49:46.382795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.583411ms","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-17T10:49:46.382808Z","caller":"traceutil/trace.go:171","msg":"trace[1129548967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"153.104649ms","start":"2026-04-17T10:49:46.229654Z","end":"2026-04-17T10:49:46.382759Z","steps":["trace[1129548967] 'agreement among raft nodes before linearized reading' (duration: 152.880933ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.382892Z","caller":"traceutil/trace.go:171","msg":"trace[1415794007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"153.716384ms","start":"2026-04-17T10:49:46.229158Z","end":"2026-04-17T10:49:46.382874Z","steps":["trace[1415794007] 'agreement among raft nodes before linearized reading' (duration: 153.509079ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.383021Z","caller":"traceutil/trace.go:171","msg":"trace[409526566] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"130.747374ms","start":"2026-04-17T10:49:46.251725Z","end":"2026-04-17T10:49:46.382472Z","steps":["trace[409526566] 'read index received' (duration: 129.0494ms)","trace[409526566] 'applied index is now lower than readState.Index' (duration: 1.696404ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:46.468907Z","caller":"traceutil/trace.go:171","msg":"trace[622164053] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"102.883747ms","start":"2026-04-17T10:49:46.366006Z","end":"2026-04-17T10:49:46.46889Z","steps":["trace[622164053] 'process raft request' (duration: 102.804535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:46.46929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.715018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n9fpz\" ","response":"range_response_count:1 size:18067"} {"level":"warn","ts":"2026-04-17T10:49:46.469363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.278429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-17T10:49:46.469372Z","caller":"traceutil/trace.go:171","msg":"trace[651909470] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n9fpz; range_end:; response_count:1; response_revision:695; }","duration":"103.865103ms","start":"2026-04-17T10:49:46.365486Z","end":"2026-04-17T10:49:46.469351Z","steps":["trace[651909470] 'agreement among raft nodes before linearized reading' (duration: 103.532554ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.469643Z","caller":"traceutil/trace.go:171","msg":"trace[248399741] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:695; }","duration":"119.477974ms","start":"2026-04-17T10:49:46.350006Z","end":"2026-04-17T10:49:46.469483Z","steps":["trace[248399741] 'agreement among raft nodes before linearized reading' (duration: 118.967331ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.66951Z","caller":"traceutil/trace.go:171","msg":"trace[1813562765] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"146.258862ms","start":"2026-04-17T10:49:46.523224Z","end":"2026-04-17T10:49:46.669483Z","steps":["trace[1813562765] 'read index received' (duration: 145.965784ms)","trace[1813562765] 'applied index is now lower than readState.Index' (duration: 291.848µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:46.669957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.716434ms","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-17T10:49:46.669994Z","caller":"traceutil/trace.go:171","msg":"trace[937070678] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"186.1788ms","start":"2026-04-17T10:49:46.483778Z","end":"2026-04-17T10:49:46.669956Z","steps":["trace[937070678] 'process raft request' (duration: 185.537503ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:46.670268Z","caller":"traceutil/trace.go:171","msg":"trace[1992178003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:698; }","duration":"146.867107ms","start":"2026-04-17T10:49:46.523152Z","end":"2026-04-17T10:49:46.670019Z","steps":["trace[1992178003] 'agreement among raft nodes before linearized reading' (duration: 146.611861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:46.670282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.139201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-17T10:49:46.670463Z","caller":"traceutil/trace.go:171","msg":"trace[462635442] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:698; }","duration":"120.343106ms","start":"2026-04-17T10:49:46.550087Z","end":"2026-04-17T10:49:46.67043Z","steps":["trace[462635442] 'agreement among raft nodes before linearized reading' (duration: 119.831813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:47.253616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.050127ms","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-17T10:49:47.253749Z","caller":"traceutil/trace.go:171","msg":"trace[1183783320] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:699; }","duration":"358.19911ms","start":"2026-04-17T10:49:46.895526Z","end":"2026-04-17T10:49:47.253726Z","steps":["trace[1183783320] 'range keys from in-memory index tree' (duration: 358.029306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:47.267147Z","caller":"traceutil/trace.go:171","msg":"trace[1755392000] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"117.091606ms","start":"2026-04-17T10:49:47.150038Z","end":"2026-04-17T10:49:47.267129Z","steps":["trace[1755392000] 'read index received' (duration: 116.874461ms)","trace[1755392000] 'applied index is now lower than readState.Index' (duration: 216.475µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:47.267309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.265292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-17T10:49:47.267348Z","caller":"traceutil/trace.go:171","msg":"trace[1702844704] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:700; }","duration":"117.346674ms","start":"2026-04-17T10:49:47.149981Z","end":"2026-04-17T10:49:47.267328Z","steps":["trace[1702844704] 'agreement among raft nodes before linearized reading' (duration: 117.21418ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:47.267512Z","caller":"traceutil/trace.go:171","msg":"trace[2070125066] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"289.625084ms","start":"2026-04-17T10:49:46.977872Z","end":"2026-04-17T10:49:47.267497Z","steps":["trace[2070125066] 'process raft request' (duration: 289.09598ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:47.738032Z","caller":"traceutil/trace.go:171","msg":"trace[1379537682] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"359.171732ms","start":"2026-04-17T10:49:47.378837Z","end":"2026-04-17T10:49:47.738009Z","steps":["trace[1379537682] 'read index received' (duration: 359.000828ms)","trace[1379537682] 'applied index is now lower than readState.Index' (duration: 169.854µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:49:47.738327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.437298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n9fpz\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-04-17T10:49:47.738388Z","caller":"traceutil/trace.go:171","msg":"trace[396241314] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n9fpz; range_end:; response_count:1; response_revision:703; }","duration":"359.568672ms","start":"2026-04-17T10:49:47.378802Z","end":"2026-04-17T10:49:47.738371Z","steps":["trace[396241314] 'agreement among raft nodes before linearized reading' (duration: 359.316055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:47.738441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:47.378787Z","time spent":"359.642954ms","remote":"127.0.0.1:56964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-n9fpz\" "} {"level":"info","ts":"2026-04-17T10:49:47.738339Z","caller":"traceutil/trace.go:171","msg":"trace[1437394044] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"389.989257ms","start":"2026-04-17T10:49:47.348083Z","end":"2026-04-17T10:49:47.738072Z","steps":["trace[1437394044] 'process raft request' (duration: 389.67532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:47.738728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:47.348056Z","time spent":"390.594063ms","remote":"127.0.0.1:56830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:49:47.747751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.630401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-17T10:49:47.74782Z","caller":"traceutil/trace.go:171","msg":"trace[413092292] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"197.746685ms","start":"2026-04-17T10:49:47.550058Z","end":"2026-04-17T10:49:47.747805Z","steps":["trace[413092292] 'agreement among raft nodes before linearized reading' (duration: 197.54185ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:47.747982Z","caller":"traceutil/trace.go:171","msg":"trace[1641112396] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"360.402294ms","start":"2026-04-17T10:49:47.387561Z","end":"2026-04-17T10:49:47.747963Z","steps":["trace[1641112396] 'process raft request' (duration: 359.928581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:47.748074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:49:47.387524Z","time spent":"360.501776ms","remote":"127.0.0.1:56830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T10:49:52.651926Z","caller":"traceutil/trace.go:171","msg":"trace[1575692981] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"119.589987ms","start":"2026-04-17T10:49:52.532305Z","end":"2026-04-17T10:49:52.651895Z","steps":["trace[1575692981] 'process raft request' (duration: 119.33974ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:59.567755Z","caller":"traceutil/trace.go:171","msg":"trace[942328875] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"128.644769ms","start":"2026-04-17T10:49:59.439087Z","end":"2026-04-17T10:49:59.567732Z","steps":["trace[942328875] 'read index received' (duration: 128.425854ms)","trace[942328875] 'applied index is now lower than readState.Index' (duration: 217.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:59.567783Z","caller":"traceutil/trace.go:171","msg":"trace[1452650647] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"130.263776ms","start":"2026-04-17T10:49:59.437498Z","end":"2026-04-17T10:49:59.567762Z","steps":["trace[1452650647] 'process raft request' (duration: 130.088532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:49:59.56804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.924075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9wrhz\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-17T10:49:59.568122Z","caller":"traceutil/trace.go:171","msg":"trace[607851148] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9wrhz; range_end:; response_count:1; response_revision:786; }","duration":"129.035548ms","start":"2026-04-17T10:49:59.439063Z","end":"2026-04-17T10:49:59.568099Z","steps":["trace[607851148] 'agreement among raft nodes before linearized reading' (duration: 128.762532ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:49:59.736133Z","caller":"traceutil/trace.go:171","msg":"trace[1985411727] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"162.063023ms","start":"2026-04-17T10:49:59.57405Z","end":"2026-04-17T10:49:59.736113Z","steps":["trace[1985411727] 'process raft request' (duration: 133.43044ms)","trace[1985411727] 'compare' (duration: 28.529561ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:49:59.775195Z","caller":"traceutil/trace.go:171","msg":"trace[461176793] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"199.777297ms","start":"2026-04-17T10:49:59.575379Z","end":"2026-04-17T10:49:59.775156Z","steps":["trace[461176793] 'process raft request' (duration: 199.565342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:01.189766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696669336848779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T10:50:01.701699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696669336848779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T10:50:02.202501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696669336848779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T10:50:02.631652Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.287322863s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T10:50:02.63208Z","caller":"traceutil/trace.go:171","msg":"trace[1101491456] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"2.287911886s","start":"2026-04-17T10:50:00.344146Z","end":"2026-04-17T10:50:02.632058Z","steps":["trace[1101491456] 'process raft request' (duration: 2.287756783s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.632227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.34413Z","time spent":"2.288020688s","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:50:02.725237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000476879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T10:50:02.725664Z","caller":"traceutil/trace.go:171","msg":"trace[972218994] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00094151s","start":"2026-04-17T10:50:00.724679Z","end":"2026-04-17T10:50:02.72562Z","steps":["trace[972218994] 'agreement among raft nodes before linearized reading' (duration: 2.00051448s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.725914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.724658Z","time spent":"2.001239836s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 10:50:02 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-17T10:50:02.809925Z","caller":"traceutil/trace.go:171","msg":"trace[1637559639] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"2.465561867s","start":"2026-04-17T10:50:00.344333Z","end":"2026-04-17T10:50:02.809895Z","steps":["trace[1637559639] 'process raft request' (duration: 2.459263094s)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:02.810037Z","caller":"traceutil/trace.go:171","msg":"trace[1270284635] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"2.066095349s","start":"2026-04-17T10:50:00.743922Z","end":"2026-04-17T10:50:02.810018Z","steps":["trace[1270284635] 'process raft request' (duration: 2.06574853s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.810087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.344322Z","time spent":"2.465690979s","remote":"127.0.0.1:57066","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-17T10:50:02.809931Z","caller":"traceutil/trace.go:171","msg":"trace[467222831] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:818; }","duration":"2.12104968s","start":"2026-04-17T10:50:00.688852Z","end":"2026-04-17T10:50:02.809902Z","steps":["trace[467222831] 'read index received' (duration: 1.943085872s)","trace[467222831] 'applied index is now lower than readState.Index' (duration: 177.962658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:50:02.810169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.743903Z","time spent":"2.06618428s","remote":"127.0.0.1:57030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T10:50:02.810161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.121306616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T10:50:02.810218Z","caller":"traceutil/trace.go:171","msg":"trace[1532011107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"2.121374617s","start":"2026-04-17T10:50:00.688829Z","end":"2026-04-17T10:50:02.810203Z","steps":["trace[1532011107] 'agreement among raft nodes before linearized reading' (duration: 2.121159942s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.810259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.688609Z","time spent":"2.121638364s","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T10:50:02.810372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.118625535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T10:50:02.810433Z","caller":"traceutil/trace.go:171","msg":"trace[730705249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"2.118724757s","start":"2026-04-17T10:50:00.69169Z","end":"2026-04-17T10:50:02.810415Z","steps":["trace[730705249] 'agreement among raft nodes before linearized reading' (duration: 2.118572264s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.810484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.691672Z","time spent":"2.118801218s","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T10:50:02.810575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923293346s","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-17T10:50:02.810601Z","caller":"traceutil/trace.go:171","msg":"trace[67784274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"1.923356757s","start":"2026-04-17T10:50:00.887236Z","end":"2026-04-17T10:50:02.810593Z","steps":["trace[67784274] 'agreement among raft nodes before linearized reading' (duration: 1.923280865s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.810621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.887216Z","time spent":"1.923400048s","remote":"127.0.0.1:57066","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-17T10:50:02.810609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005206648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:50:02.810655Z","caller":"traceutil/trace.go:171","msg":"trace[1006544577] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:794; }","duration":"2.00529635s","start":"2026-04-17T10:50:00.805347Z","end":"2026-04-17T10:50:02.810643Z","steps":["trace[1006544577] 'agreement among raft nodes before linearized reading' (duration: 2.005219408s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:02.810719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:00.805322Z","time spent":"2.005361282s","remote":"127.0.0.1:57120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-17T10:50:03.578826Z","caller":"traceutil/trace.go:171","msg":"trace[44623164] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"118.950352ms","start":"2026-04-17T10:50:03.45985Z","end":"2026-04-17T10:50:03.5788Z","steps":["trace[44623164] 'process raft request' (duration: 118.805558ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:05.165838Z","caller":"traceutil/trace.go:171","msg":"trace[265246131] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"291.400691ms","start":"2026-04-17T10:50:04.874406Z","end":"2026-04-17T10:50:05.165807Z","steps":["trace[265246131] 'process raft request' (duration: 284.751424ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:24.994129Z","caller":"traceutil/trace.go:171","msg":"trace[1277736624] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"240.994844ms","start":"2026-04-17T10:50:24.753113Z","end":"2026-04-17T10:50:24.994108Z","steps":["trace[1277736624] 'process raft request' (duration: 240.651907ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:25.128879Z","caller":"traceutil/trace.go:171","msg":"trace[924954766] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"128.291843ms","start":"2026-04-17T10:50:25.000554Z","end":"2026-04-17T10:50:25.128846Z","steps":["trace[924954766] 'process raft request' (duration: 128.245462ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:25.128948Z","caller":"traceutil/trace.go:171","msg":"trace[924749539] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"130.146359ms","start":"2026-04-17T10:50:24.998762Z","end":"2026-04-17T10:50:25.128908Z","steps":["trace[924749539] 'process raft request' (duration: 85.483976ms)","trace[924749539] 'compare' (duration: 44.373878ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:50:25.12923Z","caller":"traceutil/trace.go:171","msg":"trace[1332243544] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"129.424776ms","start":"2026-04-17T10:50:24.999793Z","end":"2026-04-17T10:50:25.129218Z","steps":["trace[1332243544] 'process raft request' (duration: 128.966667ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:45.386842Z","caller":"traceutil/trace.go:171","msg":"trace[551344351] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"265.499982ms","start":"2026-04-17T10:50:45.12132Z","end":"2026-04-17T10:50:45.386819Z","steps":["trace[551344351] 'read index received' (duration: 265.288718ms)","trace[551344351] 'applied index is now lower than readState.Index' (duration: 210.554µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T10:50:45.386893Z","caller":"traceutil/trace.go:171","msg":"trace[293220387] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"275.286241ms","start":"2026-04-17T10:50:45.111588Z","end":"2026-04-17T10:50:45.386874Z","steps":["trace[293220387] 'process raft request' (duration: 275.094617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:45.387108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.897631ms","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-17T10:50:45.387125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.79705ms","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-17T10:50:45.387153Z","caller":"traceutil/trace.go:171","msg":"trace[623935226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"159.993723ms","start":"2026-04-17T10:50:45.227146Z","end":"2026-04-17T10:50:45.38714Z","steps":["trace[623935226] 'agreement among raft nodes before linearized reading' (duration: 159.86032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:45.387134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.789377ms","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-17T10:50:45.387191Z","caller":"traceutil/trace.go:171","msg":"trace[375196855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"155.911652ms","start":"2026-04-17T10:50:45.231263Z","end":"2026-04-17T10:50:45.387174Z","steps":["trace[375196855] 'agreement among raft nodes before linearized reading' (duration: 155.703979ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:45.387219Z","caller":"traceutil/trace.go:171","msg":"trace[1895472645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"265.92065ms","start":"2026-04-17T10:50:45.121282Z","end":"2026-04-17T10:50:45.387203Z","steps":["trace[1895472645] 'agreement among raft nodes before linearized reading' (duration: 265.641575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:45.387249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.225848ms","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-17T10:50:45.387308Z","caller":"traceutil/trace.go:171","msg":"trace[703379516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"167.31369ms","start":"2026-04-17T10:50:45.219977Z","end":"2026-04-17T10:50:45.38729Z","steps":["trace[703379516] 'agreement among raft nodes before linearized reading' (duration: 166.970604ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:53.820912Z","caller":"traceutil/trace.go:171","msg":"trace[1645515230] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"362.63118ms","start":"2026-04-17T10:50:53.458253Z","end":"2026-04-17T10:50:53.820884Z","steps":["trace[1645515230] 'process raft request' (duration: 337.932498ms)","trace[1645515230] 'compare' (duration: 24.379026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:50:53.821064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.458228Z","time spent":"362.757381ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T10:50:53.821183Z","caller":"traceutil/trace.go:171","msg":"trace[1935750961] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"360.804379ms","start":"2026-04-17T10:50:53.460214Z","end":"2026-04-17T10:50:53.821019Z","steps":["trace[1935750961] 'read index received' (duration: 335.908573ms)","trace[1935750961] 'applied index is now lower than readState.Index' (duration: 24.892106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T10:50:53.821205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.22681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T10:50:53.821275Z","caller":"traceutil/trace.go:171","msg":"trace[1446112713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1134; }","duration":"390.320601ms","start":"2026-04-17T10:50:53.430937Z","end":"2026-04-17T10:50:53.821257Z","steps":["trace[1446112713] 'agreement among raft nodes before linearized reading' (duration: 390.112328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:53.821327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.430932Z","time spent":"390.385772ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T10:50:53.821347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.538979ms","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-17T10:50:53.821475Z","caller":"traceutil/trace.go:171","msg":"trace[1084609102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"389.726162ms","start":"2026-04-17T10:50:53.431731Z","end":"2026-04-17T10:50:53.821457Z","steps":["trace[1084609102] 'agreement among raft nodes before linearized reading' (duration: 389.338315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:53.821533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.603286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T10:50:53.821583Z","caller":"traceutil/trace.go:171","msg":"trace[970232225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"390.670277ms","start":"2026-04-17T10:50:53.430901Z","end":"2026-04-17T10:50:53.821571Z","steps":["trace[970232225] 'agreement among raft nodes before linearized reading' (duration: 390.509555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:53.821621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.430893Z","time spent":"390.720269ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T10:50:53.821663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.431724Z","time spent":"389.822104ms","remote":"127.0.0.1:57066","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-17T10:50:53.821703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.843078ms","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-17T10:50:53.821764Z","caller":"traceutil/trace.go:171","msg":"trace[420978593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1134; }","duration":"400.933929ms","start":"2026-04-17T10:50:53.420818Z","end":"2026-04-17T10:50:53.821752Z","steps":["trace[420978593] 'agreement among raft nodes before linearized reading' (duration: 400.679185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:53.821797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.4208Z","time spent":"400.99053ms","remote":"127.0.0.1:57066","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-17T10:50:53.822325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.358854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T10:50:53.822381Z","caller":"traceutil/trace.go:171","msg":"trace[51395039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"103.444406ms","start":"2026-04-17T10:50:53.718924Z","end":"2026-04-17T10:50:53.822368Z","steps":["trace[51395039] 'agreement among raft nodes before linearized reading' (duration: 103.347105ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T10:50:53.82254Z","caller":"traceutil/trace.go:171","msg":"trace[1873966386] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"358.310218ms","start":"2026-04-17T10:50:53.464219Z","end":"2026-04-17T10:50:53.822529Z","steps":["trace[1873966386] 'process raft request' (duration: 357.998423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:53.822653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.464201Z","time spent":"358.387809ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T10:50:53.822686Z","caller":"traceutil/trace.go:171","msg":"trace[520491669] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"360.939531ms","start":"2026-04-17T10:50:53.461715Z","end":"2026-04-17T10:50:53.822654Z","steps":["trace[520491669] 'process raft request' (duration: 360.159838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T10:50:53.822867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T10:50:53.461692Z","time spent":"361.065234ms","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}